[mythtv-users] mythfilldatabase not running automatically?

Wed Jan 12 17:08:47 UTC 2022

On Wed, Jan 12, 2022 at 10:38 AM Bill Meek <keemllib at gmail.com> wrote:

> On 1/12/22 7:56 AM, James Abernathy wrote:
> > | MythFillDatabaseLog             | /var/log/mythtv           | NULL
>  |
> > | MythFillDatabasePath            | /usr/bin/mythfilldatabase | NULL
>  |
> > | MythFillEnabled                 | 1                         | NULL
>  |
> > | MythFillFixProgramIDsHasRunOnce | 1                         | crw-pvr
> |
> > | MythFillFixProgramIDsHasRunOnce | 1                         | crw-pvr
> |
> > | MythFillGrabberSuggestsTime     | 0                         | NULL
>  |
> > | MythFillMaxHour                 | 16                        | NULL
>  |
> > | MythFillMinHour                 | 16                        | NULL
>  |
>
> MythFillDatabaseLog doesn't exist, at least in the v32Pre code base,
> harmless
>
> MythFillDatabasePath must be mythfilldatabase, any other value, like a full
> path name will *prevent* mythfilldatabase logging.
>
> MythFillFixProgramIDsHasRunOnce, I've only got one. Probably harmless.
>
> MythFillMaxHour and Min are the same, that looks like a change just for
> testing. Not the original 0-5AM.
>
> I'd also look at the timestamps in housekeeping:
>
> mysql> SELECT * FROM housekeeping WHERE tag='MythFillDB';
> +------------+----------+---------------------+---------------------+
> | tag        | hostname | lastrun             | lastsuccess         |
> +------------+----------+---------------------+---------------------+
> | MythFillDB | NULL     | 2022-01-12 09:00:02 | 2022-01-12 09:00:02 |
> +------------+----------+---------------------+---------------------+
>
> Once MythFillDatabasePath is corrected, I'd run the backend with -v
> system,xmltv
> and there may be clues in the mythfilldatabase.log (or wherever you log
> to).
>
> --
> Bill
>

I took a look at the mythtv-backend.service file and fixed the logging from
syslog to systemd-journal and added the -v system,xmltv.

I found some odd things about the grabbers. I get an error on
tv_grab_zz_sdjson_sqlite when it runs from the backend service, but I do
not get the error if I run it manually as user 'mythtv'.

I wonder if the backend has a hard coded path for the grabbers and not
using the default path. My path puts the grabbers at:
/usr/bin/site_perl/tv_grab_zz_sdjson_sqlite
which is where the xmltv make install puts it under arch linux.

 Jan 12 11:58:38 den-pc mythbackend[4235]: Managed child (PID: 4289) has
started! * command=/usr/bin/mythfilldatabase --verbose general,system,xml
tv --loglevel info --systemd-journal --quiet, timeout=0
Jan 12 11:58:38 den-pc mythbackend[4235]: Starting IO manager (read)
Jan 12 11:58:38 den-pc mythbackend[4235]: Starting process signal handler
Jan 12 11:58:38 den-pc mythbackend[4235]: Starting IO manager (write)
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: mythfilldatabase version:
master [v32-Pre-3361-gba401e1ea9] www.mythtv.org
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Qt version: compile: 5.15.2,
runtime: 5.15.2
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Arch Linux (x86_64)
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Enabled verbose msgs:
 general system xmltv
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setting Log Level to
LOG_INFO
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Interrupt handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Added logging to the console
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Terminated handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Segmentation fault
handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Aborted handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Bus error handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Floating point
exception handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Illegal instruction
handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Real-time signal 0
handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Setup Hangup handler
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Using runtime prefix = /usr
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Using configuration
directory = /home/mythtv/.mythtv
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Assumed character encoding:
en_US.UTF-8
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Empty LocalHostName. This is
typical.
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Using a profile name of:
'den-pc' (Usually the same as this host's name.)
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Start up testing
connections. DB localhost, BE , attempt 0, status dbAwake, Delay: 2000
Jan 12 11:58:38 den-pc mythfilldatabase[4289]: Added journal logging
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Setting QT default locale to
en_US
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Current locale en_US
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Reading locale defaults from
/usr/share/mythtv//locales/en_us.xml
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: PowerDBus: Failed to delay
sleep,shutdown: Permission denied
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: PowerDBus: Added
UPower.Device '/org/freedesktop/UPower/devices/mouse_hidpp_battery_0'
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Power: On AC power
Jan 12 11:58:39 den-pc mythbackend[4235]: 2022-01-12 11:58:39.701231 I
 MainServer: MainServer::ANN Playback
Jan 12 11:58:39 den-pc mythbackend[4235]: 2022-01-12 11:58:39.701238 I
 MainServer: adding: den-pc(556d85a4d430) as a client (events: 0)
Jan 12 11:58:39 den-pc mythbackend[4235]: MainServer: MainServer::ANN
Playback
Jan 12 11:58:39 den-pc mythbackend[4235]: MainServer: adding:
den-pc(556d85a4d430) as a client (events: 0)
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Power: Supported actions:
None
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Loading en_us translation
for module mythfrontend
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Current MythTV Schema
Version (DBSchemaVer): 1372
Jan 12 11:58:39 den-pc mythfilldatabase[4289]:
MythCoreContext::ConnectCommandSocket(): Connecting to backend server:
192.168.0.100:6543 (try 1 o
f 1)
Jan 12 11:58:39 den-pc mythfilldatabase[4289]:
MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Opening blocking connection
to master backend
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Updating source #1 (SD) with
grabber tv_grab_zz_sdjson_sqlite
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Found 30 channels for source
1 which use grabber
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Starting process manager
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Starting process signal
handler
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Starting IO manager (read)
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Starting IO manager (write)
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Managed child (PID: 4305)
has started! * command=tv_grab_zz_sdjson_sqlite --capabilities, timeout=
25
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Managed child (PID: 4305)
has exited! command=tv_grab_zz_sdjson_sqlite --capabilities, status=3251
2, result=127
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: tv_grab_zz_sdjson_sqlite
 --capabilities failed or we timed out waiting. You may need to upgrade y
our xmltv grabber
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Grabbing XMLTV data using
tv_grab_zz_sdjson_sqlite is not supported. You may need to upgrade to th
e latest version of XMLTV.
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Data fetching complete.
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Adjusting program database
end times.
Jan 12 11:58:39 den-pc mythfilldatabase[4289]:     0 replacements made
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Marking generic episodes.
Jan 12 11:58:39 den-pc mythfilldatabase[4289]:     Found 0
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Extending non-unique
programids with multiple parts.
Jan 12 11:58:39 den-pc mythfilldatabase[4289]:     Found 0
Jan 12 11:58:39 den-pc mythfilldatabase[4289]: Fixing missing original
airdates.
Jan 12 11:58:40 den-pc mythfilldatabase[4289]:     Found 0 with programids
Jan 12 11:58:40 den-pc mythfilldatabase[4289]:     Found 0 without
programids
Jan 12 11:58:40 den-pc mythfilldatabase[4289]: Marking repeats.
Jan 12 11:58:40 den-pc mythfilldatabase[4289]:     Found 0
Jan 12 11:58:40 den-pc mythfilldatabase[4289]: Unmarking new episode
rebroadcast repeats.
Jan 12 11:58:40 den-pc mythfilldatabase[4289]:     Found 0
Jan 12 11:58:40 den-pc mythfilldatabase[4289]: Marking episode first
showings.
Jan 12 11:58:40 den-pc mythfilldatabase[4289]:     Found 15971
Jan 12 11:58:40 den-pc mythfilldatabase[4289]: Marking episode last
showings.
Jan 12 11:58:40 den-pc mythfilldatabase[4289]:     Found 15965
Jan 12 11:58:40 den-pc mythfilldatabase[4289]:

===============================================================

                                              | Attempting to contact the
master backend for rescheduling.  |
                                              | If the master is not
running, rescheduling will happen when |
                                              | the master backend is
restarted.                            |

===============================================================

Jan 12 11:58:40 den-pc mythfilldatabase[4289]: mythfilldatabase run
complete.
Jan 12 11:58:40 den-pc mythfilldatabase[4289]: PowerDBus: Closing
interfaces
Jan 12 11:58:40 den-pc mythfilldatabase[4289]: Waiting for threads to exit.
Jan 12 11:58:40 den-pc mythfilldatabase[4289]: Exiting
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20220112/a0b1d93c/attachment.htm>