myhthfilldatabase stopped grabbing listings [solved]

Have a MythTV related problem? Ask for help from other MythTV users here.

Moderator: Forum Moderators

Post Reply
rahvin
Junior
Posts: 15
Joined: Sat Jul 02, 2016 12:00 am
United States of America

myhthfilldatabase stopped grabbing listings [solved]

Post by rahvin » Thu Nov 01, 2018 2:34 am

Strange problem here, everything worked great up until a week or two ago then it all of a sudden stopped downloading data. I've verified the database is up, I've verified the mythtv password to the database works, I've verified the databases are ok. Below is a debug log level run of mythfilldatabase console output with the log pasted in right after it.

Code: Select all

root@mythbuntu:/home/mythtv/.mythtv# sudo mythfilldatabase --dd-grab-all --loglevel debug --logpath /tmp
2018-10-31 20:19:08.763248 C  mythfilldatabase version: fixes/0.28 [v0.28.2-6-g9031599] www.mythtv.org
2018-10-31 20:19:08.763265 C  Qt version: compile: 5.5.1, runtime: 5.5.1
2018-10-31 20:19:08.763268 N  Enabled verbose msgs:  general
2018-10-31 20:19:08.763320 N  Setting Log Level to LOG_DEBUG
2018-10-31 20:19:08.774324 I  Added logging to the console
2018-10-31 20:19:08.775017 I  Setup Interrupt handler
2018-10-31 20:19:08.775036 I  Setup Terminated handler
2018-10-31 20:19:08.775047 I  Setup Segmentation fault handler
2018-10-31 20:19:08.775058 I  Setup Aborted handler
2018-10-31 20:19:08.775068 I  Setup Bus error handler
2018-10-31 20:19:08.775079 I  Setup Floating point exception handler
2018-10-31 20:19:08.775088 I  Setup Illegal instruction handler
2018-10-31 20:19:08.775106 I  Setup Real-time signal 0 handler
2018-10-31 20:19:08.775117 I  Setup Hangup handler
2018-10-31 20:19:08.775276 N  Using runtime prefix = /usr
2018-10-31 20:19:08.775281 N  Using configuration directory = /root/.mythtv
2018-10-31 20:19:08.775285 D  appbindir      = /usr/bin/
2018-10-31 20:19:08.775291 D  sharedir       = /usr/share/mythtv/
2018-10-31 20:19:08.775295 D  libdir         = /usr/lib/mythtv/
2018-10-31 20:19:08.775300 D  themedir       = /usr/share/mythtv/themes/
2018-10-31 20:19:08.775306 D  pluginsdir     = /usr/lib/mythtv/plugins/
2018-10-31 20:19:08.775309 D  translationsdir= /usr/share/mythtv/i18n/
2018-10-31 20:19:08.775314 D  filtersdir     = /usr/lib/mythtv/filters/
2018-10-31 20:19:08.775422 I  Assumed character encoding: en_US.UTF-8
2018-10-31 20:19:08.775829 N  Empty LocalHostName.
2018-10-31 20:19:08.775841 I  Using localhost value of mythbuntu
2018-10-31 20:19:08.787805 D  FindDatabase() - Success!
2018-10-31 20:19:08.789769 N  Setting QT default locale to en_US
2018-10-31 20:19:08.789779 I  Current locale en_US
2018-10-31 20:19:08.789830 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2018-10-31 20:19:08.797460 I  Loading en_us translation for module mythfrontend
2018-10-31 20:19:08.799150 I  Current MythTV Schema Version (DBSchemaVer): 1344
2018-10-31 20:19:08.802745 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.0.35:6543 (try 1 of 1)
2018-10-31 20:19:08.804740 I  MythCoreContext::CheckProtoVersion(): Using protocol version 88 XmasGift
2018-10-31 20:19:08.805105 I  Opening blocking connection to master backend
2018-10-31 20:19:08.806905 I  Updating source #1 (Antenna) with grabber schedulesdirect1
2018-10-31 20:19:08.807414 I  Found 28 channels for source 1 which use grabber
2018-10-31 20:19:08.807436 I  This DataDirect listings source is shared by 2 MythTV lineups
2018-10-31 20:19:08.807440 N  We should keep data around after this one
2018-10-31 20:19:08.808971 I  Retrieving datadirect data.
2018-10-31 20:19:08.808976 I  Grabbing ALL available data.
2018-10-31 20:19:08.809000 I  DataDirect: Grabbing listing data
2018-10-31 20:19:08.809116 I  Downloading DataDirect feed
2018-10-31 20:19:08.874970 I  Added logging to /tmp/mythfilldatabase.20181101021908.4632.log
2018-10-31 20:19:10.881491 E  DataDirect: Failed to get data: Download error
2018-10-31 20:19:10.881504 E  Encountered error in grabbing data.
2018-10-31 20:19:10.882933 I  Updating source #2 (Satellite) with grabber schedulesdirect1
2018-10-31 20:19:10.883567 I  Found 120 channels for source 2 which use grabber
2018-10-31 20:19:10.883578 I  This DataDirect listings source is shared by 2 MythTV lineups
2018-10-31 20:19:10.883579 N  We should use cached data for this one
2018-10-31 20:19:10.885271 I  Retrieving datadirect data.
2018-10-31 20:19:10.885274 I  Grabbing ALL available data.
2018-10-31 20:19:10.885282 I  DataDirect: Grabbing listing data
2018-10-31 20:19:10.885357 I  Downloading DataDirect feed
2018-10-31 20:19:11.486628 E  DataDirect: Failed to get data: Download error
2018-10-31 20:19:11.486648 E  Encountered error in grabbing data.
2018-10-31 20:19:11.487775 E  Failed to fetch some program info
2018-10-31 20:19:11.487799 I  Adjusting program database end times.
2018-10-31 20:19:11.488097 I      0 replacements made
2018-10-31 20:19:11.488102 I  Marking generic episodes.
2018-10-31 20:19:11.488573 I      Found 0
2018-10-31 20:19:11.488579 I  Extending non-unique programids with multiple parts.
2018-10-31 20:19:11.489134 I      Found 0
2018-10-31 20:19:11.489139 I  Fixing missing original airdates.
2018-10-31 20:19:11.489881 I      Found 0 with programids
2018-10-31 20:19:11.490603 I      Found 0 without programids
2018-10-31 20:19:11.490609 I  Marking repeats.
2018-10-31 20:19:11.491897 I      Found 0
2018-10-31 20:19:11.491904 I  Unmarking new episode rebroadcast repeats.
2018-10-31 20:19:11.492265 I      Found 0
2018-10-31 20:19:11.492536 I  Marking episode first showings.
2018-10-31 20:19:11.493692 I      Found 0
2018-10-31 20:19:11.493697 I  Marking episode last showings.
2018-10-31 20:19:11.494805 I      Found 0
2018-10-31 20:19:11.497017 I  DataDirect: Grabbing next suggested grabbing time
2018-10-31 20:19:11.898180 E  DataDirect: GrabNextSuggestedTime: Could not download
2018-10-31 20:19:11.898200 I  
===============================================================
| Attempting to contact the master backend for rescheduling.  |
| If the master is not running, rescheduling will happen when |
| the master backend is restarted.                            |
===============================================================
2018-10-31 20:19:11.899500 N  mythfilldatabase run complete.
2018-10-31 20:19:11.899632 I  Waiting for threads to exit.
2018-10-31 20:19:11.991728 I  Removed logging to /tmp/mythfilldatabase.20181101021908.4632.log
root@mythbuntu:/home/mythtv/.mythtv# ls /tmp
mythfilldatabase.20181101020748.4545.log  mythfilldatabase.20181101021908.4632.log  mythtv_ddp_crCviO  mythtv_ddp_M4sQgz  mythtv_ddp_zcqEyn
root@mythbuntu:/home/mythtv/.mythtv# cat /tmp/mythfilldatabase.20181101021908.4632.log 
2018-10-31 20:19:08.763248 C [4632/4632] thread_unknown mythcommandlineparser.cpp:2599 (ConfigureLogging) - mythfilldatabase version: fixes/0.28 [v0.28.2-6-g9031599] www.mythtv.org
2018-10-31 20:19:08.763265 C [4632/4632] thread_unknown mythcommandlineparser.cpp:2601 (ConfigureLogging) - Qt version: compile: 5.5.1, runtime: 5.5.1
2018-10-31 20:19:08.763268 N [4632/4632] thread_unknown mythcommandlineparser.cpp:2603 (ConfigureLogging) - Enabled verbose msgs:  general
2018-10-31 20:19:08.763320 N [4632/4632] thread_unknown logging.cpp:920 (logStart) - Setting Log Level to LOG_DEBUG
2018-10-31 20:19:08.774324 I [4632/4635] Logger logging.cpp:313 (run) - Added logging to the console
2018-10-31 20:19:08.775017 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Interrupt handler
2018-10-31 20:19:08.775036 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Terminated handler
2018-10-31 20:19:08.775047 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Segmentation fault handler
2018-10-31 20:19:08.775058 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Aborted handler
2018-10-31 20:19:08.775068 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Bus error handler
2018-10-31 20:19:08.775079 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Floating point exception handler
2018-10-31 20:19:08.775088 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Illegal instruction handler
2018-10-31 20:19:08.775106 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Real-time signal 0 handler
2018-10-31 20:19:08.775117 I [4632/4632] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Hangup handler
2018-10-31 20:19:08.775276 N [4632/4632] thread_unknown mythdirs.cpp:192 (InitializeMythDirs) - Using runtime prefix = /usr
2018-10-31 20:19:08.775281 N [4632/4632] thread_unknown mythdirs.cpp:194 (InitializeMythDirs) - Using configuration directory = /root/.mythtv
2018-10-31 20:19:08.775285 D [4632/4632] thread_unknown mythdirs.cpp:196 (InitializeMythDirs) - appbindir      = /usr/bin/
2018-10-31 20:19:08.775291 D [4632/4632] thread_unknown mythdirs.cpp:197 (InitializeMythDirs) - sharedir       = /usr/share/mythtv/
2018-10-31 20:19:08.775295 D [4632/4632] thread_unknown mythdirs.cpp:198 (InitializeMythDirs) - libdir         = /usr/lib/mythtv/
2018-10-31 20:19:08.775300 D [4632/4632] thread_unknown mythdirs.cpp:199 (InitializeMythDirs) - themedir       = /usr/share/mythtv/themes/
2018-10-31 20:19:08.775306 D [4632/4632] thread_unknown mythdirs.cpp:200 (InitializeMythDirs) - pluginsdir     = /usr/lib/mythtv/plugins/
2018-10-31 20:19:08.775309 D [4632/4632] thread_unknown mythdirs.cpp:201 (InitializeMythDirs) - translationsdir= /usr/share/mythtv/i18n/
2018-10-31 20:19:08.775314 D [4632/4632] thread_unknown mythdirs.cpp:202 (InitializeMythDirs) - filtersdir     = /usr/lib/mythtv/filters/
2018-10-31 20:19:08.775422 I [4632/4632] CoreContext mythcorecontext.cpp:266 (Init) - Assumed character encoding: en_US.UTF-8
2018-10-31 20:19:08.775829 N [4632/4632] CoreContext mythcontext.cpp:505 (LoadDatabaseSettings) - Empty LocalHostName.
2018-10-31 20:19:08.775841 I [4632/4632] CoreContext mythcontext.cpp:513 (LoadDatabaseSettings) - Using localhost value of mythbuntu
2018-10-31 20:19:08.787805 D [4632/4632] CoreContext mythcontext.cpp:433 (FindDatabase) - FindDatabase() - Success!
2018-10-31 20:19:08.789769 N [4632/4632] CoreContext mythcorecontext.cpp:1670 (InitLocale) - Setting QT default locale to en_US
2018-10-31 20:19:08.789779 I [4632/4632] CoreContext mythcorecontext.cpp:1703 (SaveLocaleDefaults) - Current locale en_US
2018-10-31 20:19:08.789830 N [4632/4632] CoreContext mythlocale.cpp:123 (LoadDefaultsFromXML) - Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2018-10-31 20:19:08.797460 I [4632/4632] CoreContext mythtranslation.cpp:73 (load) - Loading en_us translation for module mythfrontend
2018-10-31 20:19:08.799150 I [4632/4632] CoreContext schemawizard.cpp:120 (Compare) - Current MythTV Schema Version (DBSchemaVer): 1344
2018-10-31 20:19:08.802745 I [4632/4632] CoreContext mythcorecontext.cpp:436 (ConnectCommandSocket) - MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.0.35:6543 (try 1 of 1)
2018-10-31 20:19:08.804740 I [4632/4632] CoreContext mythcorecontext.cpp:1578 (CheckProtoVersion) - MythCoreContext::CheckProtoVersion(): Using protocol version 88 XmasGift
2018-10-31 20:19:08.805105 I [4632/4632] CoreContext main.cpp:311 (main) - Opening blocking connection to master backend
2018-10-31 20:19:08.806905 I [4632/4632] CoreContext filldata.cpp:569 (Run) - Updating source #1 (Antenna) with grabber schedulesdirect1
2018-10-31 20:19:08.807414 I [4632/4632] CoreContext filldata.cpp:583 (Run) - Found 28 channels for source 1 which use grabber
2018-10-31 20:19:08.807436 I [4632/4632] CoreContext filldata.cpp:180 (GrabDDData) - This DataDirect listings source is shared by 2 MythTV lineups
2018-10-31 20:19:08.807440 N [4632/4632] CoreContext filldata.cpp:189 (GrabDDData) - We should keep data around after this one
2018-10-31 20:19:08.808971 I [4632/4632] CoreContext filldata.cpp:212 (GrabDDData) - Retrieving datadirect data.
2018-10-31 20:19:08.808976 I [4632/4632] CoreContext filldata.cpp:215 (GrabDDData) - Grabbing ALL available data.
2018-10-31 20:19:08.809000 I [4632/4632] CoreContext datadirect.cpp:1172 (GrabData) - DataDirect: Grabbing listing data
2018-10-31 20:19:08.809116 I [4632/4632] CoreContext datadirect.cpp:1023 (DDPost) - Downloading DataDirect feed
2018-10-31 20:19:08.874970 I [4632/4634] LogForward loggingserver.cpp:142 (FileLogger) - Added logging to /tmp/mythfilldatabase.20181101021908.4632.log
2018-10-31 20:19:10.881491 E [4632/4632] CoreContext datadirect.cpp:1199 (GrabData) - DataDirect: Failed to get data: Download error
2018-10-31 20:19:10.881504 E [4632/4632] CoreContext filldata.cpp:218 (GrabDDData) - Encountered error in grabbing data.
2018-10-31 20:19:10.882933 I [4632/4632] CoreContext filldata.cpp:569 (Run) - Updating source #2 (Satellite) with grabber schedulesdirect1
2018-10-31 20:19:10.883567 I [4632/4632] CoreContext filldata.cpp:583 (Run) - Found 120 channels for source 2 which use grabber
2018-10-31 20:19:10.883578 I [4632/4632] CoreContext filldata.cpp:180 (GrabDDData) - This DataDirect listings source is shared by 2 MythTV lineups
2018-10-31 20:19:10.883579 N [4632/4632] CoreContext filldata.cpp:184 (GrabDDData) - We should use cached data for this one
2018-10-31 20:19:10.885271 I [4632/4632] CoreContext filldata.cpp:212 (GrabDDData) - Retrieving datadirect data.
2018-10-31 20:19:10.885274 I [4632/4632] CoreContext filldata.cpp:215 (GrabDDData) - Grabbing ALL available data.
2018-10-31 20:19:10.885282 I [4632/4632] CoreContext datadirect.cpp:1172 (GrabData) - DataDirect: Grabbing listing data
2018-10-31 20:19:10.885357 I [4632/4632] CoreContext datadirect.cpp:1023 (DDPost) - Downloading DataDirect feed
2018-10-31 20:19:11.486628 E [4632/4632] CoreContext datadirect.cpp:1199 (GrabData) - DataDirect: Failed to get data: Download error
2018-10-31 20:19:11.486648 E [4632/4632] CoreContext filldata.cpp:218 (GrabDDData) - Encountered error in grabbing data.
2018-10-31 20:19:11.487775 E [4632/4632] CoreContext main.cpp:432 (main) - Failed to fetch some program info
2018-10-31 20:19:11.487799 I [4632/4632] CoreContext main.cpp:442 (main) - Adjusting program database end times.
2018-10-31 20:19:11.488097 I [4632/4632] CoreContext main.cpp:448 (main) -     0 replacements made
2018-10-31 20:19:11.488102 I [4632/4632] CoreContext main.cpp:450 (main) - Marking generic episodes.
2018-10-31 20:19:11.488573 I [4632/4632] CoreContext main.cpp:462 (main) -     Found 0
2018-10-31 20:19:11.488579 I [4632/4632] CoreContext main.cpp:465 (main) - Extending non-unique programids with multiple parts.
2018-10-31 20:19:11.489134 I [4632/4632] CoreContext main.cpp:516 (main) -     Found 0
2018-10-31 20:19:11.489139 I [4632/4632] CoreContext main.cpp:518 (main) - Fixing missing original airdates.
2018-10-31 20:19:11.489881 I [4632/4632] CoreContext main.cpp:533 (main) -     Found 0 with programids
2018-10-31 20:19:11.490603 I [4632/4632] CoreContext main.cpp:553 (main) -     Found 0 without programids
2018-10-31 20:19:11.490609 I [4632/4632] CoreContext main.cpp:557 (main) - Marking repeats.
2018-10-31 20:19:11.491897 I [4632/4632] CoreContext main.cpp:571 (main) -     Found 0
2018-10-31 20:19:11.491904 I [4632/4632] CoreContext main.cpp:573 (main) - Unmarking new episode rebroadcast repeats.
2018-10-31 20:19:11.492265 I [4632/4632] CoreContext main.cpp:583 (main) -     Found 0
2018-10-31 20:19:11.492536 I [4632/4632] CoreContext main.cpp:592 (main) - Marking episode first showings.
2018-10-31 20:19:11.493692 I [4632/4632] CoreContext main.cpp:626 (main) -     Found 0
2018-10-31 20:19:11.493697 I [4632/4632] CoreContext main.cpp:628 (main) - Marking episode last showings.
2018-10-31 20:19:11.494805 I [4632/4632] CoreContext main.cpp:662 (main) -     Found 0
2018-10-31 20:19:11.497017 I [4632/4632] CoreContext datadirect.cpp:1070 (GrabNextSuggestedTime) - DataDirect: Grabbing next suggested grabbing time
2018-10-31 20:19:11.898180 E [4632/4632] CoreContext datadirect.cpp:1104 (GrabNextSuggestedTime) - DataDirect: GrabNextSuggestedTime: Could not download
2018-10-31 20:19:11.898200 I [4632/4632] CoreContext main.cpp:689 (main) - 
===============================================================
| Attempting to contact the master backend for rescheduling.  |
| If the master is not running, rescheduling will happen when |
| the master backend is restarted.                            |
===============================================================
2018-10-31 20:19:11.899500 N [4632/4632] CoreContext main.cpp:699 (main) - mythfilldatabase run complete.
2018-10-31 20:19:11.899632 I [4632/4632] CoreContext mythcontext.cpp:1195 (~MythContext) - Waiting for threads to exit.
The can't connect is nearly instantaneous, just 2 seconds between initiating the connection attempt and failing. I've verified the route and traced to dd.schedulesdirect.com. I've verified I can connect to schedules direct and I've verified my lineups are there and working via the website. The short time almost rules out network that I can think of, if it was blocked or timed out it would take several seconds but this is so fast I can't figure out what's wrong because the only thing that would reject that quick would be no network at all and that's not the case.

Anyone got any suggestions on what to check?
Last edited by rahvin on Fri Nov 02, 2018 3:01 am, edited 1 time in total.

User avatar
bill6502
Developer
Posts: 1098
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: myhthfilldatabase stopped grabbing listings

Post by bill6502 » Thu Nov 01, 2018 4:04 am

I recall a case where this exposed an error in the DB, so it's worth a try.
Don't post the output, as it displays your SD user/password:

Code: Select all

mysql --host=localhost --user=mythtv --password mythconverg --execute="SELECT sourceid,name,xmltvgrabber,userid,password FROM videosource"

Gribnif
Junior
Posts: 46
Joined: Wed May 14, 2014 12:46 pm
United States of America

Re: myhthfilldatabase stopped grabbing listings

Post by Gribnif » Thu Nov 01, 2018 3:09 pm

I had a similar problem which seems to have been caused by an oddity in the way mfdb does DNS resolution. Does it work if you run mfdb, let it fail, and then immediately run it again? If so, you're hitting the same problem I had. I ended up substituting this wrapper script for a direct call to mfdb:

Code: Select all

#!/bin/sh
ping -c 3 dd.schedulesdirect.org
/usr/local/bin/mythfilldatabase $*

rahvin
Junior
Posts: 15
Joined: Sat Jul 02, 2016 12:00 am
United States of America

Re: myhthfilldatabase stopped grabbing listings

Post by rahvin » Fri Nov 02, 2018 1:58 am

bill6502 wrote:
Thu Nov 01, 2018 4:04 am
I recall a case where this exposed an error in the DB, so it's worth a try.
Don't post the output, as it displays your SD user/password:

Code: Select all

mysql --host=localhost --user=mythtv --password mythconverg --execute="SELECT sourceid,name,xmltvgrabber,userid,password FROM videosource"
+----------+-----------+------------------+----------+-----------+
| sourceid | name | xmltvgrabber | userid | password |
+----------+-----------+------------------+----------+-----------+
| 1 | Antenna | schedulesdirect1 | xxxxx| xxxxxx |
| 2 | Satellite | schedulesdirect1 | xxxxx | xxxxxx |
+----------+-----------+------------------+----------+-----------+

Everything here looks right except for the 1 after schedules direct. The password and userid are correct.

rahvin
Junior
Posts: 15
Joined: Sat Jul 02, 2016 12:00 am
United States of America

Re: myhthfilldatabase stopped grabbing listings

Post by rahvin » Fri Nov 02, 2018 2:08 am

Gribnif wrote:
Thu Nov 01, 2018 3:09 pm
I had a similar problem which seems to have been caused by an oddity in the way mfdb does DNS resolution. Does it work if you run mfdb, let it fail, and then immediately run it again? If so, you're hitting the same problem I had. I ended up substituting this wrapper script for a direct call to mfdb:

Code: Select all

#!/bin/sh
ping -c 3 dd.schedulesdirect.org
/usr/local/bin/mythfilldatabase $*
At first I thought it was a DNS resolving issue but I can ping schedulesdirect fine and it resolves fine. I've run MFD several dozen times in a row and it fails the same way each time with each attempt failing within a second or two of issuing the command. I'm stumped here because even logging debug I'm not getting an error message.

I'm still getting the same error

Code: Select all

root@mythbuntu:/home/mythtv/.mythtv# sudo mythfilldatabase --dd-grab-all --loglevel debug --logpath /tmp
2018-11-01 20:06:03.446353 C  mythfilldatabase version: fixes/0.28 [v0.28.2-6-g9031599] www.mythtv.org
2018-11-01 20:06:03.446369 C  Qt version: compile: 5.5.1, runtime: 5.5.1
2018-11-01 20:06:03.446372 N  Enabled verbose msgs:  general
2018-11-01 20:06:03.446418 N  Setting Log Level to LOG_DEBUG
2018-11-01 20:06:03.457319 I  Added logging to the console
2018-11-01 20:06:03.457866 I  Setup Interrupt handler
2018-11-01 20:06:03.457879 I  Setup Terminated handler
2018-11-01 20:06:03.457888 I  Setup Segmentation fault handler
2018-11-01 20:06:03.457898 I  Setup Aborted handler
2018-11-01 20:06:03.457906 I  Setup Bus error handler
2018-11-01 20:06:03.457915 I  Setup Floating point exception handler
2018-11-01 20:06:03.457923 I  Setup Illegal instruction handler
2018-11-01 20:06:03.457938 I  Setup Real-time signal 0 handler
2018-11-01 20:06:03.457947 I  Setup Hangup handler
2018-11-01 20:06:03.458070 N  Using runtime prefix = /usr
2018-11-01 20:06:03.458074 N  Using configuration directory = /root/.mythtv
2018-11-01 20:06:03.458077 D  appbindir      = /usr/bin/
2018-11-01 20:06:03.458082 D  sharedir       = /usr/share/mythtv/
2018-11-01 20:06:03.458085 D  libdir         = /usr/lib/mythtv/
2018-11-01 20:06:03.458089 D  themedir       = /usr/share/mythtv/themes/
2018-11-01 20:06:03.458094 D  pluginsdir     = /usr/lib/mythtv/plugins/
2018-11-01 20:06:03.458097 D  translationsdir= /usr/share/mythtv/i18n/
2018-11-01 20:06:03.458101 D  filtersdir     = /usr/lib/mythtv/filters/
2018-11-01 20:06:03.458171 I  Assumed character encoding: en_US.UTF-8
2018-11-01 20:06:03.458437 N  Empty LocalHostName.
2018-11-01 20:06:03.458446 I  Using localhost value of mythbuntu
2018-11-01 20:06:03.469981 D  FindDatabase() - Success!
2018-11-01 20:06:03.471909 N  Setting QT default locale to en_US
2018-11-01 20:06:03.471921 I  Current locale en_US
2018-11-01 20:06:03.471969 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2018-11-01 20:06:03.478955 I  Loading en_us translation for module mythfrontend
2018-11-01 20:06:03.479982 I  Current MythTV Schema Version (DBSchemaVer): 1344
2018-11-01 20:06:03.481726 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.0.35:6543 (try 1 of 1)
2018-11-01 20:06:03.483284 I  MythCoreContext::CheckProtoVersion(): Using protocol version 88 XmasGift
2018-11-01 20:06:03.483616 I  Opening blocking connection to master backend
2018-11-01 20:06:03.484451 I  Updating source #1 (Antenna) with grabber schedulesdirect1
2018-11-01 20:06:03.484707 I  Found 28 channels for source 1 which use grabber
2018-11-01 20:06:03.484724 I  This DataDirect listings source is shared by 2 MythTV lineups
2018-11-01 20:06:03.484727 N  We should keep data around after this one
2018-11-01 20:06:03.485900 I  Retrieving datadirect data.
2018-11-01 20:06:03.485907 I  Grabbing ALL available data.
2018-11-01 20:06:03.485932 I  DataDirect: Grabbing listing data
2018-11-01 20:06:03.486088 I  Downloading DataDirect feed
2018-11-01 20:06:03.557874 I  Added logging to /tmp/mythfilldatabase.20181102020603.8648.log
2018-11-01 20:06:05.560280 E  DataDirect: Failed to get data: Download error
2018-11-01 20:06:05.560293 E  Encountered error in grabbing data.
2018-11-01 20:06:05.561087 I  Updating source #2 (Satellite) with grabber schedulesdirect1
2018-11-01 20:06:05.561445 I  Found 120 channels for source 2 which use grabber
2018-11-01 20:06:05.561462 I  This DataDirect listings source is shared by 2 MythTV lineups
2018-11-01 20:06:05.561466 N  We should use cached data for this one
2018-11-01 20:06:05.562544 I  Retrieving datadirect data.
2018-11-01 20:06:05.562550 I  Grabbing ALL available data.
2018-11-01 20:06:05.562562 I  DataDirect: Grabbing listing data
2018-11-01 20:06:05.562681 I  Downloading DataDirect feed
2018-11-01 20:06:05.963994 E  DataDirect: Failed to get data: Download error
2018-11-01 20:06:05.964008 E  Encountered error in grabbing data.
2018-11-01 20:06:05.965087 E  Failed to fetch some program info
2018-11-01 20:06:05.965108 I  Adjusting program database end times.
2018-11-01 20:06:05.965390 I      0 replacements made
2018-11-01 20:06:05.965395 I  Marking generic episodes.
2018-11-01 20:06:05.965802 I      Found 0
2018-11-01 20:06:05.965808 I  Extending non-unique programids with multiple parts.
2018-11-01 20:06:05.966319 I      Found 0
2018-11-01 20:06:05.966324 I  Fixing missing original airdates.
2018-11-01 20:06:05.966984 I      Found 0 with programids
2018-11-01 20:06:05.967534 I      Found 0 without programids
2018-11-01 20:06:05.967538 I  Marking repeats.
2018-11-01 20:06:05.968755 I      Found 0
2018-11-01 20:06:05.968762 I  Unmarking new episode rebroadcast repeats.
2018-11-01 20:06:05.969108 I      Found 0
2018-11-01 20:06:05.969347 I  Marking episode first showings.
2018-11-01 20:06:05.970500 I      Found 0
2018-11-01 20:06:05.970506 I  Marking episode last showings.
2018-11-01 20:06:05.971597 I      Found 0
2018-11-01 20:06:05.973858 I  DataDirect: Grabbing next suggested grabbing time
2018-11-01 20:06:06.375137 E  DataDirect: GrabNextSuggestedTime: Could not download
2018-11-01 20:06:06.375155 I  
===============================================================
| Attempting to contact the master backend for rescheduling.  |
| If the master is not running, rescheduling will happen when |
| the master backend is restarted.                            |
===============================================================
2018-11-01 20:06:06.376556 N  mythfilldatabase run complete.
2018-11-01 20:06:06.376678 I  Waiting for threads to exit.
2018-11-01 20:06:06.468634 I  Removed logging to /tmp/mythfilldatabase.20181102020603.8648.log
root@mythbuntu:/home/mythtv/.mythtv# ls /tmp
mythfilldatabase.20181102020603.8648.log  mythtv_ddp_crCviO  mythtv_ddp_M4sQgz  mythtv_ddp_zcqEyn
root@mythbuntu:/home/mythtv/.mythtv# cat /tmp/mythfilldatabase.20181102020603.8648.log 
2018-11-01 20:06:03.446353 C [8648/8648] thread_unknown mythcommandlineparser.cpp:2599 (ConfigureLogging) - mythfilldatabase version: fixes/0.28 [v0.28.2-6-g9031599] www.mythtv.org
2018-11-01 20:06:03.446369 C [8648/8648] thread_unknown mythcommandlineparser.cpp:2601 (ConfigureLogging) - Qt version: compile: 5.5.1, runtime: 5.5.1
2018-11-01 20:06:03.446372 N [8648/8648] thread_unknown mythcommandlineparser.cpp:2603 (ConfigureLogging) - Enabled verbose msgs:  general
2018-11-01 20:06:03.446418 N [8648/8648] thread_unknown logging.cpp:920 (logStart) - Setting Log Level to LOG_DEBUG
2018-11-01 20:06:03.457319 I [8648/8651] Logger logging.cpp:313 (run) - Added logging to the console
2018-11-01 20:06:03.457866 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Interrupt handler
2018-11-01 20:06:03.457879 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Terminated handler
2018-11-01 20:06:03.457888 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Segmentation fault handler
2018-11-01 20:06:03.457898 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Aborted handler
2018-11-01 20:06:03.457906 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Bus error handler
2018-11-01 20:06:03.457915 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Floating point exception handler
2018-11-01 20:06:03.457923 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Illegal instruction handler
2018-11-01 20:06:03.457938 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Real-time signal 0 handler
2018-11-01 20:06:03.457947 I [8648/8648] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Hangup handler
2018-11-01 20:06:03.458070 N [8648/8648] thread_unknown mythdirs.cpp:192 (InitializeMythDirs) - Using runtime prefix = /usr
2018-11-01 20:06:03.458074 N [8648/8648] thread_unknown mythdirs.cpp:194 (InitializeMythDirs) - Using configuration directory = /root/.mythtv
2018-11-01 20:06:03.458077 D [8648/8648] thread_unknown mythdirs.cpp:196 (InitializeMythDirs) - appbindir      = /usr/bin/
2018-11-01 20:06:03.458082 D [8648/8648] thread_unknown mythdirs.cpp:197 (InitializeMythDirs) - sharedir       = /usr/share/mythtv/
2018-11-01 20:06:03.458085 D [8648/8648] thread_unknown mythdirs.cpp:198 (InitializeMythDirs) - libdir         = /usr/lib/mythtv/
2018-11-01 20:06:03.458089 D [8648/8648] thread_unknown mythdirs.cpp:199 (InitializeMythDirs) - themedir       = /usr/share/mythtv/themes/
2018-11-01 20:06:03.458094 D [8648/8648] thread_unknown mythdirs.cpp:200 (InitializeMythDirs) - pluginsdir     = /usr/lib/mythtv/plugins/
2018-11-01 20:06:03.458097 D [8648/8648] thread_unknown mythdirs.cpp:201 (InitializeMythDirs) - translationsdir= /usr/share/mythtv/i18n/
2018-11-01 20:06:03.458101 D [8648/8648] thread_unknown mythdirs.cpp:202 (InitializeMythDirs) - filtersdir     = /usr/lib/mythtv/filters/
2018-11-01 20:06:03.458171 I [8648/8648] CoreContext mythcorecontext.cpp:266 (Init) - Assumed character encoding: en_US.UTF-8
2018-11-01 20:06:03.458437 N [8648/8648] CoreContext mythcontext.cpp:505 (LoadDatabaseSettings) - Empty LocalHostName.
2018-11-01 20:06:03.458446 I [8648/8648] CoreContext mythcontext.cpp:513 (LoadDatabaseSettings) - Using localhost value of mythbuntu
2018-11-01 20:06:03.469981 D [8648/8648] CoreContext mythcontext.cpp:433 (FindDatabase) - FindDatabase() - Success!
2018-11-01 20:06:03.471909 N [8648/8648] CoreContext mythcorecontext.cpp:1670 (InitLocale) - Setting QT default locale to en_US
2018-11-01 20:06:03.471921 I [8648/8648] CoreContext mythcorecontext.cpp:1703 (SaveLocaleDefaults) - Current locale en_US
2018-11-01 20:06:03.471969 N [8648/8648] CoreContext mythlocale.cpp:123 (LoadDefaultsFromXML) - Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2018-11-01 20:06:03.478955 I [8648/8648] CoreContext mythtranslation.cpp:73 (load) - Loading en_us translation for module mythfrontend
2018-11-01 20:06:03.479982 I [8648/8648] CoreContext schemawizard.cpp:120 (Compare) - Current MythTV Schema Version (DBSchemaVer): 1344
2018-11-01 20:06:03.481726 I [8648/8648] CoreContext mythcorecontext.cpp:436 (ConnectCommandSocket) - MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.0.35:6543 (try 1 of 1)
2018-11-01 20:06:03.483284 I [8648/8648] CoreContext mythcorecontext.cpp:1578 (CheckProtoVersion) - MythCoreContext::CheckProtoVersion(): Using protocol version 88 XmasGift
2018-11-01 20:06:03.483616 I [8648/8648] CoreContext main.cpp:311 (main) - Opening blocking connection to master backend
2018-11-01 20:06:03.484451 I [8648/8648] CoreContext filldata.cpp:569 (Run) - Updating source #1 (Antenna) with grabber schedulesdirect1
2018-11-01 20:06:03.484707 I [8648/8648] CoreContext filldata.cpp:583 (Run) - Found 28 channels for source 1 which use grabber
2018-11-01 20:06:03.484724 I [8648/8648] CoreContext filldata.cpp:180 (GrabDDData) - This DataDirect listings source is shared by 2 MythTV lineups
2018-11-01 20:06:03.484727 N [8648/8648] CoreContext filldata.cpp:189 (GrabDDData) - We should keep data around after this one
2018-11-01 20:06:03.485900 I [8648/8648] CoreContext filldata.cpp:212 (GrabDDData) - Retrieving datadirect data.
2018-11-01 20:06:03.485907 I [8648/8648] CoreContext filldata.cpp:215 (GrabDDData) - Grabbing ALL available data.
2018-11-01 20:06:03.485932 I [8648/8648] CoreContext datadirect.cpp:1172 (GrabData) - DataDirect: Grabbing listing data
2018-11-01 20:06:03.486088 I [8648/8648] CoreContext datadirect.cpp:1023 (DDPost) - Downloading DataDirect feed
2018-11-01 20:06:03.557874 I [8648/8650] LogForward loggingserver.cpp:142 (FileLogger) - Added logging to /tmp/mythfilldatabase.20181102020603.8648.log
2018-11-01 20:06:05.560280 E [8648/8648] CoreContext datadirect.cpp:1199 (GrabData) - DataDirect: Failed to get data: Download error
2018-11-01 20:06:05.560293 E [8648/8648] CoreContext filldata.cpp:218 (GrabDDData) - Encountered error in grabbing data.
2018-11-01 20:06:05.561087 I [8648/8648] CoreContext filldata.cpp:569 (Run) - Updating source #2 (Satellite) with grabber schedulesdirect1
2018-11-01 20:06:05.561445 I [8648/8648] CoreContext filldata.cpp:583 (Run) - Found 120 channels for source 2 which use grabber
2018-11-01 20:06:05.561462 I [8648/8648] CoreContext filldata.cpp:180 (GrabDDData) - This DataDirect listings source is shared by 2 MythTV lineups
2018-11-01 20:06:05.561466 N [8648/8648] CoreContext filldata.cpp:184 (GrabDDData) - We should use cached data for this one
2018-11-01 20:06:05.562544 I [8648/8648] CoreContext filldata.cpp:212 (GrabDDData) - Retrieving datadirect data.
2018-11-01 20:06:05.562550 I [8648/8648] CoreContext filldata.cpp:215 (GrabDDData) - Grabbing ALL available data.
2018-11-01 20:06:05.562562 I [8648/8648] CoreContext datadirect.cpp:1172 (GrabData) - DataDirect: Grabbing listing data
2018-11-01 20:06:05.562681 I [8648/8648] CoreContext datadirect.cpp:1023 (DDPost) - Downloading DataDirect feed
2018-11-01 20:06:05.963994 E [8648/8648] CoreContext datadirect.cpp:1199 (GrabData) - DataDirect: Failed to get data: Download error
2018-11-01 20:06:05.964008 E [8648/8648] CoreContext filldata.cpp:218 (GrabDDData) - Encountered error in grabbing data.
2018-11-01 20:06:05.965087 E [8648/8648] CoreContext main.cpp:432 (main) - Failed to fetch some program info
2018-11-01 20:06:05.965108 I [8648/8648] CoreContext main.cpp:442 (main) - Adjusting program database end times.
2018-11-01 20:06:05.965390 I [8648/8648] CoreContext main.cpp:448 (main) -     0 replacements made
2018-11-01 20:06:05.965395 I [8648/8648] CoreContext main.cpp:450 (main) - Marking generic episodes.
2018-11-01 20:06:05.965802 I [8648/8648] CoreContext main.cpp:462 (main) -     Found 0
2018-11-01 20:06:05.965808 I [8648/8648] CoreContext main.cpp:465 (main) - Extending non-unique programids with multiple parts.
2018-11-01 20:06:05.966319 I [8648/8648] CoreContext main.cpp:516 (main) -     Found 0
2018-11-01 20:06:05.966324 I [8648/8648] CoreContext main.cpp:518 (main) - Fixing missing original airdates.
2018-11-01 20:06:05.966984 I [8648/8648] CoreContext main.cpp:533 (main) -     Found 0 with programids
2018-11-01 20:06:05.967534 I [8648/8648] CoreContext main.cpp:553 (main) -     Found 0 without programids
2018-11-01 20:06:05.967538 I [8648/8648] CoreContext main.cpp:557 (main) - Marking repeats.
2018-11-01 20:06:05.968755 I [8648/8648] CoreContext main.cpp:571 (main) -     Found 0
2018-11-01 20:06:05.968762 I [8648/8648] CoreContext main.cpp:573 (main) - Unmarking new episode rebroadcast repeats.
2018-11-01 20:06:05.969108 I [8648/8648] CoreContext main.cpp:583 (main) -     Found 0
2018-11-01 20:06:05.969347 I [8648/8648] CoreContext main.cpp:592 (main) - Marking episode first showings.
2018-11-01 20:06:05.970500 I [8648/8648] CoreContext main.cpp:626 (main) -     Found 0
2018-11-01 20:06:05.970506 I [8648/8648] CoreContext main.cpp:628 (main) - Marking episode last showings.
2018-11-01 20:06:05.971597 I [8648/8648] CoreContext main.cpp:662 (main) -     Found 0
2018-11-01 20:06:05.973858 I [8648/8648] CoreContext datadirect.cpp:1070 (GrabNextSuggestedTime) - DataDirect: Grabbing next suggested grabbing time
2018-11-01 20:06:06.375137 E [8648/8648] CoreContext datadirect.cpp:1104 (GrabNextSuggestedTime) - DataDirect: GrabNextSuggestedTime: Could not download
2018-11-01 20:06:06.375155 I [8648/8648] CoreContext main.cpp:689 (main) - 
===============================================================
| Attempting to contact the master backend for rescheduling.  |
| If the master is not running, rescheduling will happen when |
| the master backend is restarted.                            |
===============================================================
2018-11-01 20:06:06.376556 N [8648/8648] CoreContext main.cpp:699 (main) - mythfilldatabase run complete.
2018-11-01 20:06:06.376678 I [8648/8648] CoreContext mythcontext.cpp:1195 (~MythContext) - Waiting for threads to exit.

rahvin
Junior
Posts: 15
Joined: Sat Jul 02, 2016 12:00 am
United States of America

Re: myhthfilldatabase stopped grabbing listings

Post by rahvin » Fri Nov 02, 2018 3:01 am

Ok, I found a old thread that gave me the way to see more information on the network connection, it turns out the password was wrong, did some looking and my schedulesdirect password had changed (the browser was filling it in automatically when I checked the lineup). Fixing the password in setup fixed the problem. I've saved the debug setup command that gave me more detail on the network issue. Thanks guys, problem solved.

Post Reply