[mythtv-users] Metadata lookup failing status 140 on first run only

Mark Perkins perkins1724 at hotmail.com
Mon Mar 20 10:12:11 UTC 2017



> -----Original Message-----
> From: mythtv-users [mailto:mythtv-users-bounces at mythtv.org] On Behalf
> Of Bill Meek
> Sent: Monday, 20 March 2017 11:40 AM
> To: mythtv-users at mythtv.org
> Subject: Re: [mythtv-users] Metadata lookup failing status 140 on first run
> only
> 
> On 03/19/2017 06:43 PM, Mark Perkins wrote:
> 
> ...
> 
> > 2017-03-20 09:34:50.975480 N [17533/17577] Expire autoexpire.cpp:251
> > (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 7.0
> > GB w/freq: 14 min
> >
> > There appear to be no completion messages logged by default but
> mythweb now shows Status: Finished. Comments: Metadata Lookup
> Complete.
> >
> > It is obviously a very low impact issue because, other than some red error
> lines in mythweb, the failure is invisible because everything updates correctly
> despite the reported failure. But it is curious.
> >
> > Any suggestions or guidance greatly appreciated.
> 
> I'd look in mythmetadatalookup.log for the actual command that was run,
> e.g.:
> 
>      ... Running Grabber:
> /usr/local/share/mythtv/metadata/Television/ttvdb.py -l xx -a xx -C xxxxx
> 
> Then run that command from the command line maybe even include the --
> debug option.
> You can even append:   ;echo $?   to the command line to see the code that
> would
> be returned to mythmetadatalookup.
> 
> It appears that 140 (GENERIC_EXIT_KILLED) replaces all status codes except
> GENERIC_EXIT_TIMEOUT in mythsystemunix.
> 
> I'm wondering if that's masking the true error. Running the grabber from the
> command line should tell.
> 
> --
> Bill


Thanks Bill. I checked the log files and the 'running grabber' command under both the first and second runs were identical. Rerunning the command manually with --debug;echo $? Generated a whole bunch of detail / data and a clean exit code of zero.

I tried with a  tmdb id that did not exist on my system but was valid and still got an exit code zero.

However I then tried a mythmetadatalookup execution with a valid tmdb id that did not exist on my system and I triggered a segfault.

But as before, rerunning the command a second time under gdb resulted in a clean exit.

Finally, I ran mythmetadatalookup with a new valid tmdb id that did not exist on my system but was a valid id and trapped the following from gdb. I hope it makes some sense to someone. Also I have scrubbed out my system hostname entries, if that is a problem let me know and I can provide an uncensored version:

gdb /usr/local/mythtv_0_28/bin/mythmetadatalookup -x ${HOME}/gdbcommands
GNU gdb (GDB) Red Hat Enterprise Linux 7.6.1-94.el7
Copyright (C) 2013 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-redhat-linux-gnu".
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>...
Reading symbols from /usr/local/mythtv_0_28/bin/mythmetadatalookup...done.
Function "qFatal" not defined.
Breakpoint 1 (qFatal) pending.
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib64/libthread_db.so.1".
[New Thread 0x7fffd4c40700 (LWP 15632)]
[New Thread 0x7fffcffff700 (LWP 15633)]
[New Thread 0x7fffcf7fe700 (LWP 15634)]
[New Thread 0x7fffceafd700 (LWP 15635)]
[Thread 0x7fffceafd700 (LWP 15635) exited]
[New Thread 0x7fffceafd700 (LWP 15639)]
[New Thread 0x7fffce2fc700 (LWP 15640)]
[New Thread 0x7fffcdafb700 (LWP 15641)]
[New Thread 0x7fffcd2fa700 (LWP 15642)]
Detaching after fork from child process 15643.
2017-03-20 20:19:39.124974 C  mythmetadatalookup version: fixes/0.28 [v0.28.1-6-g7de03a9-dirty] www.mythtv.org
2017-03-20 20:19:39.124994 C  Qt version: compile: 5.6.1, runtime: 5.6.1
2017-03-20 20:19:39.124997 N  Enabled verbose msgs:  general database file jobqueue
2017-03-20 20:19:39.125052 N  Setting Log Level to LOG_DEBUG
2017-03-20 20:19:39.166593 I  Setup Interrupt handler
2017-03-20 20:19:39.166600 I  Setup Terminated handler
2017-03-20 20:19:39.166604 I  Setup Segmentation fault handler
2017-03-20 20:19:39.166608 I  Setup Aborted handler
2017-03-20 20:19:39.166610 I  Setup Bus error handler
2017-03-20 20:19:39.166614 I  Setup Floating point exception handler
2017-03-20 20:19:39.166617 I  Setup Illegal instruction handler
2017-03-20 20:19:39.166623 I  Setup Real-time signal 0 handler
2017-03-20 20:19:39.166627 I  Setup Hangup handler
2017-03-20 20:19:39.166653 N  Read conf dir = /home/mythtv/.mythtv_0_28/
2017-03-20 20:19:39.166696 N  Using runtime prefix = /usr/local/mythtv_0_28
2017-03-20 20:19:39.166699 N  Using configuration directory = /home/mythtv/.mythtv_0_28/
2017-03-20 20:19:39.166700 D  appbindir      = /usr/local/mythtv_0_28/bin/
2017-03-20 20:19:39.166705 D  sharedir       = /usr/local/mythtv_0_28/share/mythtv/
2017-03-20 20:19:39.166706 D  libdir         = /usr/local/mythtv_0_28/lib/mythtv/
2017-03-20 20:19:39.166708 D  themedir       = /usr/local/mythtv_0_28/share/mythtv/themes/
2017-03-20 20:19:39.166709 D  pluginsdir     = /usr/local/mythtv_0_28/lib/mythtv/plugins/
2017-03-20 20:19:39.166711 D  translationsdir= /usr/local/mythtv_0_28/share/mythtv/i18n/
2017-03-20 20:19:39.166712 D  filtersdir     = /usr/local/mythtv_0_28/lib/mythtv/filters/
2017-03-20 20:19:39.166779 I  Assumed character encoding: en_AU.UTF-8
2017-03-20 20:19:39.167056 N  Empty LocalHostName.
2017-03-20 20:19:39.167061 I  Using localhost value of XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
2017-03-20 20:19:39.167071 I  Clearing Settings Cache.
2017-03-20 20:19:39.217822 I  Added logging to the console
2017-03-20 20:19:39.274699 I  Database object created: DBManager0
2017-03-20 20:19:39.274721 I  New DB connection, total: 1
2017-03-20 20:19:39.275903 I  [DBManager0] Connected to database 'mythconverg_0_28' at host: XXX.XXX.XXX.XXX
2017-03-20 20:19:39.277734 I  MSqlQuery::exec(DBManager0) SELECT data FROM settings WHERE value = 'backendserverip' AND hostname = 'XXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.277805 D  MSqlQuery::next(DBManager0) Result: "data = XXX.XXX.XXX.XXX"
2017-03-20 20:19:39.278129 I  MSqlQuery::exec(DBManager0) SELECT data FROM settings WHERE value = 'backendserverip6' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.278140 D  MSqlQuery::next(DBManager0) Result: "data = ::1"
2017-03-20 20:19:39.278167 I  Closing DB connection named 'DBManager0'
2017-03-20 20:19:39.278207 I  Clearing Settings Cache.
2017-03-20 20:19:39.278233 I  Database object created: DBManager1
2017-03-20 20:19:39.278236 I  New DB connection, total: 1
2017-03-20 20:19:39.278565 I  [DBManager1] Connected to database 'mythconverg_0_28' at host: XXX.XXX.XXX.XXX
2017-03-20 20:19:39.278633 D  FindDatabase() - Success!
2017-03-20 20:19:39.278646 I  Closing DB connection named 'DBManager1'
2017-03-20 20:19:39.278667 I  Clearing Settings Cache.
2017-03-20 20:19:39.278700 I  Database object created: DBManager2
2017-03-20 20:19:39.278703 I  New DB connection, total: 1
2017-03-20 20:19:39.278952 I  [DBManager2] Connected to database 'mythconverg_0_28' at host: XXX.XXX.XXX.XXX
2017-03-20 20:19:39.279223 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'language' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:39.279460 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'language' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.279470 D  MSqlQuery::next(DBManager2) Result: "data = en_US"
2017-03-20 20:19:39.279794 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'country' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:39.280082 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'country' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.280092 D  MSqlQuery::next(DBManager2) Result: "data = AU"
2017-03-20 20:19:39.280144 N  Setting QT default locale to en_AU
2017-03-20 20:19:39.280153 I  Current locale en_AU
2017-03-20 20:19:39.280190 E  No locale defaults file for en_AU, skipping
2017-03-20 20:19:39.280201 I  Enabling Settings Cache.
2017-03-20 20:19:39.280203 I  Clearing Settings Cache.
2017-03-20 20:19:39.280487 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'language' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:39.280789 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'language' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.280800 D  MSqlQuery::next(DBManager2) Result: "data = en_US"
2017-03-20 20:19:39.280881 I  Loading en_us translation for module mythfrontend
2017-03-20 20:19:39.281223 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'videolistunknownfiletypes' AND hostname = 'XXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.281232 D  MSqlQuery::next(DBManager2) Result: "data = 0"
2017-03-20 20:19:39.281263 I  Testing grabbers and metadata sites for functionality...
2017-03-20 20:19:39.281679 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'moviegrabber' AND hostname = 'XXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:39.281969 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'moviegrabber' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.281977 D  MSqlQuery::next(DBManager2) Result: "data = metadata/Movie/tmdb3.py"
2017-03-20 20:19:39.286740 I  Starting process manager
2017-03-20 20:19:39.290598 I  Starting process signal handler
2017-03-20 20:19:39.294390 I  Starting IO manager (read)
2017-03-20 20:19:39.298323 I  Starting IO manager (write)
2017-03-20 20:19:39.318169 D  New Logging Client: ID:  (#1)
2017-03-20 20:19:39.318288 I  Added logging to /tmp/mythmetadatalookup.20170320094939.15628.log
Detaching after fork from child process 15644.
Detaching after fork from child process 15649.
Detaching after fork from child process 15654.
2017-03-20 20:19:39.595817 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'televisiongrabber' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:39.596155 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'televisiongrabber' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:39.596165 D  MSqlQuery::next(DBManager2) Result: "data = metadata/Television/ttvdb.py"
Detaching after fork from child process 15659.
Detaching after fork from child process 15664.
Detaching after fork from child process 15669.
Detaching after fork from child process 15670.
2017-03-20 20:19:40.045891 I  All grabbers tested and working.  Continuing...
2017-03-20 20:19:40.047528 I  MSqlQuery::exec(DBManager2) SELECT r.title,            r.subtitle,     r.description,            r.season,           r.episode,      r.category,               r.chanid,           c.channum,      c.callsign,               c.name,             c.outputfilters,r.recgroup,               r.playgroup,        r.storagegroup, r.basename,               r.hostname,         r.recpriority,  r.seriesid,               r.programid,        r.inetref,      r.filesize,               r.progstart,        r.progend,      r.stars,                  r.starttime,        r.endtime,      p.airdate+0,              r.originalairdate,  r.lastmodified, r.recordid,               c.commmethod,       r.commflagged,  r.previouslyshown,        r.transcoder,       r.transcoded,   r.deletepending,          r.preserve,         r.cutlist,      r.autoexpire,             r.editing,          r.bookmark,     r.watched,                p.audioprop+0,      p.videoprop+0,  p.subtitletypes+0,        r.findid,           rec.dupin,      rec.dupmethod,            p.syndicatedepisodenumber, p.partnumber, p.parttotal,         p.season,           p.episode,      p.totalepisodes,          p.category_type,    r.recordedid,   r.inputname,              r.bookmarkupdate                                       FROM recorded AS r LEFT JOIN channel AS c ON (r.chanid    = c.chanid) LEFT JOIN recordedprogram AS p ON (r.chanid    = p.chanid AND     r.progstart = p.starttime) LEFT JOIN record AS rec ON (r.recordid = rec.recordid) WHERE r.chanid    = '1044' AND       r.starttime = '2017-03-14T06:27:00Z' <<<< Took 1ms, Returned 1 row(s)
2017-03-20 20:19:40.047732 D  MSqlQuery::next(DBManager2) Result: "title = Boat Trip, subtitle = , description = A demonstrational program that focuses on the tools and techniques used in traditional woodwork., season = 0, episode = 0, category = Series, chanid = 1044, channum = 44, callsign = 44 Adelaide, name = 44 Adelaide, outputfilters = , recgroup = Default, playgroup = Default, storagegroup = Default, basename = 1044_20170314062700.ts, hostname = XXXXXXXXXXXXXXXXXXXXXXXXXXXXX, recpriority = 0, seriesid = 185522675, programid = , inetref = tmdb3.py_9557, filesize = 2094033300, progstart = 2017-03-14T06:30:00, progend = 2017-03-14T07:00:00, stars = 0, starttime = 2017-03-14T06:27:00, endtime = 2017-03-14T07:15:00, p.airdate+0 = 2014, originalairdate = , lastmodified = 2017-03-20T09:48:15, recordid = 2168, commmethod = -1, commflagged = 1, previouslyshown =
2017-03-20 20:19:40.048015 D  Metadata Grabber: Clearing grabber cache
2017-03-20 20:19:40.096063 D  Metadata Grabber: Adding tmdb3.py
Detaching after fork from child process 15675.
[New Thread 0x7fffccaf9700 (LWP 15676)]
[New Thread 0x7fffaffff700 (LWP 15678)]
[New Thread 0x7fffaf7fe700 (LWP 15680)]
Detaching after fork from child process 15681.
2017-03-20 20:19:40.246436 D  Metadata Grabber: Adding ttvdb.py
2017-03-20 20:19:40.297210 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'idletimeoutsecs' AND hostname = 'XXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:40.297483 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'idletimeoutsecs' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:40.297496 D  MSqlQuery::next(DBManager2) Result: "data = 0"
2017-03-20 20:19:40.297883 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'masterserverip' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:40.298173 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'masterserverip' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:40.298184 D  MSqlQuery::next(DBManager2) Result: "data = XXX.XXX.XXX.XXX"
2017-03-20 20:19:40.298442 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'masterserverport' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:40.298685 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'masterserverport' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:40.298696 D  MSqlQuery::next(DBManager2) Result: "data = 6545"
2017-03-20 20:19:40.298949 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'wolbackendcommand' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:40.299175 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'wolbackendcommand' AND hostname IS NULL <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:40.299185 D  MSqlQuery::next(DBManager2) Result: "data = "
2017-03-20 20:19:40.299415 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'backendconnectretry' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:40.299614 I  MSqlQuery::exec(DBManager2) SELECT data FROM settings WHERE value = 'backendconnectretry' AND hostname IS NULL <<<< Took 0ms, Returned 0 row(s)
2017-03-20 20:19:40.299654 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: XXX.XXX.XXX.XXX:6545 (try 1 of 1)
2017-03-20 20:19:40.307057 I  MythCoreContext::CheckProtoVersion(): Using protocol version 88 XmasGift
2017-03-20 20:19:40.318431 I  Running Grabber: /usr/local/mythtv_0_28/share/mythtv/metadata/Movie/tmdb3.py -l en -a AU -D 9557
2017-03-20 20:19:40.535651 D  MythCoreContext::RegisterFileForWrite(): /mnt/servertwo/myth_0_28_prod_recordings/1022_20170320094900.ts
2017-03-20 20:19:46.329169 D  MythCoreContext::RegisterFileForWrite(): /mnt/servertwo/myth_0_28_prod_recordings/1003_20170320085700.ts
2017-03-20 20:19:50.591738 D  MythCoreContext::RegisterFileForWrite(): /mnt/servertwo/myth_0_28_prod_recordings/1022_20170320094900.ts
2017-03-20 20:19:56.385592 D  MythCoreContext::RegisterFileForWrite(): /mnt/servertwo/myth_0_28_prod_recordings/1003_20170320085700.ts
[Thread 0x7fffaf7fe700 (LWP 15680) exited]
2017-03-20 20:19:57.941722 I  Result Found, Season 0 Episode 0
2017-03-20 20:19:57.941865 I  Returning Metadata Results: Boat Trip 0 0
[New Thread 0x7fffaf7fe700 (LWP 15686)]
2017-03-20 20:19:58.325070 I  Database object created: DBManager3
2017-03-20 20:19:58.325075 I  New DB connection, total: 2
2017-03-20 20:19:58.325769 I  [DBManager3] Connected to database 'mythconverg_0_28' at host: XXX.XXX.XXX.XXX
2017-03-20 20:19:58.326481 I  MSqlQuery::exec(DBManager3) SELECT data FROM settings WHERE value = 'backendserverport' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:58.326493 D  MSqlQuery::next(DBManager3) Result: "data = 6545"
2017-03-20 20:19:58.326569 I  RemoteFile::Exists(): looking for remote file: myth://Coverart@XXXXXXXXXXXXXXXXXXXXXXXX:6545/tmdb3.py_9557_coverart.jpg
[New Thread 0x7fffaeffd700 (LWP 15688)]
[New Thread 0x7fffae7fc700 (LWP 15689)]
2017-03-20 20:19:58.583572 I  Metadata Image Download: http://image.tmdb.org/t/p/original/vVrCtMrRNjGBYXjGmdMuhlqZlpx.jpg -> myth://Coverart@XXXXXXXXXXXXXXXXXXXXXXXX:6545/tmdb3.py_9557_coverart.jpg
[New Thread 0x7fffadffb700 (LWP 15690)]
2017-03-20 20:19:58.824741 D  DownloadManager: items downloading 0
2017-03-20 20:19:58.824747 D  DownloadManager: items queued 0
2017-03-20 20:19:58.824750 D  DownloadManager: waiting for more items to download
2017-03-20 20:19:58.860575 D  DownloadManager: items downloading 1
2017-03-20 20:19:58.860580 D  DownloadManager: items queued 0
2017-03-20 20:19:58.860600 D  DownloadManager: waiting 200ms
[New Thread 0x7fffad7fa700 (LWP 15691)]
[New Thread 0x7fffacff9700 (LWP 15692)]
2017-03-20 20:19:59.060671 D  DownloadManager: items downloading 1
2017-03-20 20:19:59.060678 D  DownloadManager: items queued 0
2017-03-20 20:19:59.066684 D  DownloadManager: waiting 200ms
2017-03-20 20:19:59.266762 D  DownloadManager: items downloading 1
2017-03-20 20:19:59.266769 D  DownloadManager: items queued 0
2017-03-20 20:19:59.267510 D  DownloadManager: downloadProgress(348019, 348019) (for reply 140735944889568)
2017-03-20 20:19:59.267518 D  DownloadManager: downloadProgress: http://image.tmdb.org/t/p/original/vVrCtMrRNjGBYXjGmdMuhlqZlpx.jpg to  is at 348019 of 348019 bytes downloaded
2017-03-20 20:19:59.269424 D  DownloadManager: downloadProgress(348019, 348019) (for reply 140735944889568)
2017-03-20 20:19:59.269431 D  DownloadManager: downloadProgress: http://image.tmdb.org/t/p/original/vVrCtMrRNjGBYXjGmdMuhlqZlpx.jpg to  is at 348019 of 348019 bytes downloaded
2017-03-20 20:19:59.269442 D  DownloadManager: downloadFinished(140735944889568)
2017-03-20 20:19:59.269454 D  DownloadManager: redirectUrl()
2017-03-20 20:19:59.269459 D  downloadFinished(140735877827520): COMPLETE: http://image.tmdb.org/t/p/original/vVrCtMrRNjGBYXjGmdMuhlqZlpx.jpg
2017-03-20 20:19:59.269839 D  DownloadManager: waiting 200ms
[New Thread 0x7fff8f7c1700 (LWP 15693)]
[New Thread 0x7fff8efc0700 (LWP 15695)]
2017-03-20 20:19:59.508860 D  DownloadManager: items downloading 0
2017-03-20 20:19:59.508866 D  DownloadManager: items queued 0
2017-03-20 20:19:59.508868 D  DownloadManager: waiting for more items to download
2017-03-20 20:19:59.593204 I  MSqlQuery::exec(DBManager3) SELECT data FROM settings WHERE value = 'backendserverip' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:59.593217 D  MSqlQuery::next(DBManager3) Result: "data = XXX.XXX.XXX.XXX"
2017-03-20 20:19:59.593359 I  MSqlQuery::exec(DBManager3) SELECT data FROM settings WHERE value = 'backendserverip6' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:59.593367 D  MSqlQuery::next(DBManager3) Result: "data = ::1"
2017-03-20 20:19:59.593683 I  MSqlQuery::exec(DBManager3) SELECT data FROM settings WHERE value = 'allowlinklocal' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:59.593693 D  MSqlQuery::next(DBManager3) Result: "data = 1"
2017-03-20 20:19:59.593957 D  Adding IPv4 loopback to address list.
2017-03-20 20:19:59.593965 D  Adding BackendServerIP6 to address list.
2017-03-20 20:19:59.593972 D  Adding BackendServerIP to address list.
2017-03-20 20:19:59.593981 D  Adding link-local 'fe80::428d:5cff:fe51:90e1%enp4s0' to address list.
2017-03-20 20:19:59.594195 I  MSqlQuery::exec(DBManager3) SELECT data FROM settings WHERE value = 'backendserverip6' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:59.594205 D  MSqlQuery::next(DBManager3) Result: "data = ::1"
2017-03-20 20:19:59.594338 I  MSqlQuery::exec(DBManager3) SELECT data FROM settings WHERE value = 'backendserverip' AND hostname = 'XXXXXXXXXXXXXXXXXXXXXXXXXXXX' <<<< Took 0ms, Returned 1 row(s)
2017-03-20 20:19:59.594347 D  MSqlQuery::next(DBManager3) Result: "data = XXX.XXX.XXX.XXX"
[Thread 0x7fff8efc0700 (LWP 15695) exited]
[Thread 0x7fff8f7c1700 (LWP 15693) exited]
2017-03-20 20:19:59.633446 D  RemoteFile(myth://Coverart@XXXXXXXXXXXXXXXXXXXXXXXXXX:6545/tmdb3.py_9557_coverart.jpg)
2017-03-20 20:19:59.633552 D  MythCoreContext::RegisterFileForWrite(): /mnt/servertwo/Myth_Cover_Art//tmdb3.py_9557_coverart.jpg
2017-03-20 20:19:59.642400 D  MythCoreContext::UnregisterFileForWrite(): /mnt/servertwo/Myth_Cover_Art//tmdb3.py_9557_coverart.jpg
2017-03-20 20:19:59.647535 I  RemoteFile::Exists(): looking for remote file: myth://Fanart@XXXXXXXXXXXXXXXXXXXXXXXXXXXX:6545/tmdb3.py_9557_fanart.jpg
2017-03-20 20:19:59.893722 I  Metadata Image Download: http://image.tmdb.org/t/p/original/kaE3kAyR554epCzP0JmJBIRSvWx.jpg -> myth://Fanart@XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX:6545/tmdb3.py_9557_fanart.jpg
2017-03-20 20:19:59.894049 D  DownloadManager: items downloading 1
2017-03-20 20:19:59.894053 D  DownloadManager: items queued 0
2017-03-20 20:19:59.894060 D  DownloadManager: waiting 200ms
2017-03-20 20:20:00.094164 D  DownloadManager: items downloading 1
2017-03-20 20:20:00.094170 D  DownloadManager: items queued 0
2017-03-20 20:20:00.094609 D  DownloadManager: downloadProgress(320091, 320091) (for reply 140735946843968)
2017-03-20 20:20:00.094616 D  DownloadManager: downloadProgress: http://image.tmdb.org/t/p/original/kaE3kAyR554epCzP0JmJBIRSvWx.jpg to  is at 320091 of 320091 bytes downloaded
2017-03-20 20:20:00.094678 D  DownloadManager: downloadProgress(320091, 320091) (for reply 140735946843968)
2017-03-20 20:20:00.094683 D  DownloadManager: downloadProgress: http://image.tmdb.org/t/p/original/kaE3kAyR554epCzP0JmJBIRSvWx.jpg to  is at 320091 of 320091 bytes downloaded
2017-03-20 20:20:00.094687 D  DownloadManager: downloadFinished(140735946843968)
2017-03-20 20:20:00.094690 D  DownloadManager: redirectUrl()
2017-03-20 20:20:00.094694 D  downloadFinished(140735877816416): COMPLETE: http://image.tmdb.org/t/p/original/kaE3kAyR554epCzP0JmJBIRSvWx.jpg
2017-03-20 20:20:00.094830 D  DownloadManager: waiting 200ms
[New Thread 0x7fff8f7c1700 (LWP 15699)]
[New Thread 0x7fff8efc0700 (LWP 15701)]
[Thread 0x7fff8efc0700 (LWP 15701) exited]
[Thread 0x7fff8f7c1700 (LWP 15699) exited]
[Thread 0x7fffaf7fe700 (LWP 15686) exited]
2017-03-20 20:20:00.150054 D  RemoteFile(myth://Fanart@XXXXXXXXXXXXXXXXXXXXXXXXXX:6545/tmdb3.py_9557_fanart.jpg)
2017-03-20 20:20:00.150805 D  MythCoreContext::RegisterFileForWrite(): /mnt/servertwo/Myth_Fan_Art//tmdb3.py_9557_fanart.jpg
2017-03-20 20:20:00.157383 D  MythCoreContext::UnregisterFileForWrite(): /mnt/servertwo/Myth_Fan_Art//tmdb3.py_9557_fanart.jpg
2017-03-20 20:20:00.157588 I  Closing DB connection named 'DBManager3'
2017-03-20 20:20:00.294955 D  DownloadManager: items downloading 0
2017-03-20 20:20:00.294961 D  DownloadManager: items queued 0
2017-03-20 20:20:00.294963 D  DownloadManager: waiting for more items to download
2017-03-20 20:20:00.648115 D  MythCoreContext::RegisterFileForWrite(): /mnt/servertwo/myth_0_28_prod_recordings/1022_20170320094900.ts
[New Thread 0x7fffaf7fe700 (LWP 15706)]
2017-03-20 20:20:01.365317 D  I found the following data:
2017-03-20 20:20:01.365328 D          Input Title: Boat Trip
2017-03-20 20:20:01.365334 D          Input Sub:
2017-03-20 20:20:01.365336 D          Title:       Boat Trip
2017-03-20 20:20:01.365337 D          Subtitle:
2017-03-20 20:20:01.365340 D          Season:      0
2017-03-20 20:20:01.365341 D          Episode:     0
2017-03-20 20:20:01.365343 D          Inetref:     tmdb3.py_9557
2017-03-20 20:20:01.365364 D          User Rating: 4.5
2017-03-20 20:20:01.365949 I  MSqlQuery::exec(DBManager2) UPDATE recorded SET season = '0', episode = '0' WHERE chanid = '1044' AND starttime = '2017-03-14T06:27:00Z' AND recordid = '2168' <<<< Took 0ms
2017-03-20 20:20:01.372284 I  MSqlQuery::exec(DBManager2) UPDATE recorded SET inetref = 'tmdb3.py_9557' WHERE chanid = '1044' AND starttime = '2017-03-14T06:27:00Z' AND recordid = '2168' <<<< Took 0ms
2017-03-20 20:20:01.373555 I  MSqlQuery::exec(DBManager2) DELETE FROM recordedartwork WHERE inetref = 'tmdb3.py_9557' AND season = '0'; <<<< Took 0ms
2017-03-20 20:20:01.373734 I  MSqlQuery::exec(DBManager2) INSERT INTO recordedartwork(inetref,season,host,coverart,fanart,banner) VALUES( 'tmdb3.py_9557', '0', 'XXXXXXXXXXXXXXXXXXXXXXXXXX, 'tmdb3.py_9557_coverart.jpg', 'tmdb3.py_9557_fanart.jpg', ''); <<<< Took 0ms
2017-03-20 20:20:01.373838 N  MythMetadataLookup run complete.
2017-03-20 20:20:01.373846 I  Waiting for threads to exit.
[New Thread 0x7fff8f7c1700 (LWP 15707)]
[Thread 0x7fffcf7fe700 (LWP 15634) exited]
2017-03-20 20:20:02.854819 I  Removed logging to /tmp/mythmetadatalookup.20170320094939.15628.log
[Thread 0x7fffd4c40700 (LWP 15632) exited]
[Thread 0x7fffcffff700 (LWP 15633) exited]
[Thread 0x7fffccaf9700 (LWP 15676) exited]
[Thread 0x7fffaf7fe700 (LWP 15706) exited]
[Thread 0x7fff8f7c1700 (LWP 15707) exited]
[Thread 0x7fffaffff700 (LWP 15678) exited]
[Thread 0x7fffce2fc700 (LWP 15640) exited]
[Thread 0x7fffcd2fa700 (LWP 15642) exited]
[Thread 0x7fffcdafb700 (LWP 15641) exited]
[Thread 0x7fffaeffd700 (LWP 15688) exited]
[Thread 0x7fffad7fa700 (LWP 15691) exited]
[Thread 0x7fffceafd700 (LWP 15639) exited]
[Thread 0x7fffacff9700 (LWP 15692) exited]

Program received signal SIGSEGV, Segmentation fault.
[Switching to Thread 0x7fffae7fc700 (LWP 15689)]
0x00007fffe8c5a9f2 in QDBusMetaType::typeToSignature(int) () from /lib64/libQt5DBus.so.5

Thread 16 (Thread 0x7fffadffb700 (LWP 15690)):
#0  0x00007fffed559c90 in __nptl_death_event () at /lib64/libpthread.so.0
#1  0x00007fffed55af1c in start_thread () at /lib64/libpthread.so.0
#2  0x00007fffebda873d in clone () at /lib64/libc.so.6

Thread 15 (Thread 0x7fffae7fc700 (LWP 15689)):
#0  0x00007fffe8c5a9f2 in QDBusMetaType::typeToSignature(int) () at /lib64/libQt5DBus.so.5
#1  0x00007fffe8c515bc in qDBusParametersForMethod(QList<QByteArray> const&, QVector<int>&, QString&) () at /lib64/libQt5DBus.so.5
#2  0x00007fffe8c51b6d in qDBusParametersForMethod(QMetaMethod const&, QVector<int>&, QString&) () at /lib64/libQt5DBus.so.5
#3  0x00007fffe8c1cace in QDBusConnectionPrivate::findSlot(QObject*, QByteArray const&, QVector<int>&) () at /lib64/libQt5DBus.so.5
#4  0x00007fffe8c1d428 in QDBusConnectionPrivate::prepareHook(QDBusConnectionPrivate::SignalHook&, QString&, QString const&, QString const&, QString const&, QString const&, QStringList const&, QObject*, char const*, int, bool) () at /lib64/libQt5DBus.so.5
#5  0x00007fffe8c1e3a1 in QDBusConnectionPrivate::disconnectRelay(QString const&, QString const&, QString const&, QDBusAbstractInterface*, QMetaMethod const&) () at /lib64/libQt5DBus.so.5
#6  0x00007fffe8c308a3 in QDBusAbstractInterfacePrivate::finishDisconnectNotify(QDBusAbstractInterface*, int) () at /lib64/libQt5DBus.so.5
#7  0x00007fffecb04e9e in QObject::event(QEvent*) () at /lib64/libQt5Core.so.5
#8  0x00007fffecadb725 in doNotify(QObject*, QEvent*) () at /lib64/libQt5Core.so.5
#9  0x00007fffecadb83b in QCoreApplication::notifyInternal2(QObject*, QEvent*) () at /lib64/libQt5Core.so.5
#10 0x00007fffecadda4a in QCoreApplicationPrivate::sendPostedEvents(QObject*, int, QThreadData*) () at /lib64/libQt5Core.so.5
#11 0x00007fffecb29a53 in postEventSourceDispatch(_GSource*, int (*)(void*), void*) () at /lib64/libQt5Core.so.5
#12 0x00007fffe1ad7d7a in g_main_context_dispatch () at /lib64/libglib-2.0.so.0
#13 0x00007fffe1ad80b8 in g_main_context_iterate.isra.24 () at /lib64/libglib-2.0.so.0
#14 0x00007fffe1ad816c in g_main_context_iteration () at /lib64/libglib-2.0.so.0
#15 0x00007fffecb292cc in QEventDispatcherGlib::processEvents(QFlags<QEventLoop::ProcessEventsFlag>) () at /lib64/libQt5Core.so.5
#16 0x00007fffecada36b in QEventLoop::exec(QFlags<QEventLoop::ProcessEventsFlag>) () at /lib64/libQt5Core.so.5
#17 0x00007fffec935158 in QThread::exec() () at /lib64/libQt5Core.so.5
#18 0x00007fffec93988d in QThreadPrivate::start(void*) () at /lib64/libQt5Core.so.5
#19 0x00007fffed55adc5 in start_thread () at /lib64/libpthread.so.0
#20 0x00007fffebda873d in clone () at /lib64/libc.so.6

Thread 1 (Thread 0x7fffdb16c940 (LWP 15628)):
#0  0x00007ffff7df17d2 in _dl_close_worker () at /lib64/ld-linux-x86-64.so.2
#1  0x00007ffff7df203c in _dl_close () at /lib64/ld-linux-x86-64.so.2
#2  0x00007ffff7debff4 in _dl_catch_error () at /lib64/ld-linux-x86-64.so.2
#3  0x00007fffed7705bd in _dlerror_run () at /lib64/libdl.so.2
#4  0x00007fffed77009f in dlclose () at /lib64/libdl.so.2
#5  0x00007fffecad77c7 in QLibraryPrivate::unload_sys() () at /lib64/libQt5Core.so.5
#6  0x00007fffecad20cc in QLibraryPrivate::unload(QLibraryPrivate::UnloadFlag) () at /lib64/libQt5Core.so.5
#7  0x00007fffecacaee3 in QFactoryLoaderPrivate::~QFactoryLoaderPrivate() () at /lib64/libQt5Core.so.5
#8  0x00007fffecacb049 in QFactoryLoaderPrivate::~QFactoryLoaderPrivate() () at /lib64/libQt5Core.so.5
#9  0x00007fffecb0bc43 in QObject::~QObject() () at /lib64/libQt5Core.so.5
#10 0x00007fffecaca0c2 in QFactoryLoader::~QFactoryLoader() () at /lib64/libQt5Core.so.5
#11 0x00007ffff7ed4ca9 in (anonymous namespace)::Q_QGS_loader::innerFunction()::Holder::~Holder() () at /lib64/libQt5Network.so.5
#12 0x00007fffebce9a49 in __run_exit_handlers () at /lib64/libc.so.6
#13 0x00007fffebce9a95 in  () at /lib64/libc.so.6
#14 0x00007fffebcd2b3c in __libc_start_main () at /lib64/libc.so.6
#15 0x0000000000406629 in _start ()
Missing separate debuginfos, use: debuginfo-install SDL-1.2.15-14.el7.x86_64 alsa-lib-1.1.1-1.el7.x86_64 avahi-compat-libdns_sd-0.6.31-17.el7.x86_64 avahi-libs-0.6.31-17.el7.x86_64 bzip2-libs-1.0.6-13.el7.x86_64 dbus-libs-1.6.12-17.el7.x86_64 elfutils-libelf-0.166-2.el7.x86_64 elfutils-libs-0.166-2.el7.x86_64 exiv2-libs-0.23-6.el7.x86_64 expat-2.1.0-10.el7_3.x86_64 faac-1.28-6.0.el7.nux.x86_64 fftw-libs-double-3.3.3-8.el7.x86_64 fftw-libs-single-3.3.3-8.el7.x86_64 flac-libs-1.3.0-5.el7_1.x86_64 fontconfig-2.10.95-10.el7.x86_64 freetype-2.4.11-12.el7.x86_64 fribidi-0.19.4-6.el7.x86_64 glib2-2.46.2-4.el7.x86_64 glibc-2.17-157.el7_3.1.x86_64 graphite2-1.3.6-1.el7_2.x86_64 gsm-1.0.13-11.el7.x86_64 gstreamer-0.10.36-7.el7.x86_64 gstreamer-plugins-base-0.10.36-10.el7.x86_64 harfbuzz-0.9.36-1.el7.x86_64 jasper-libs-1.900.1-29.el7.x86_64 json-c-0.11-4.el7_0.x86_64 keyutils-libs-1.5.8-3.el7.x86_64 krb5-libs-1.14.1-27.el7_3.x86_64 lame-libs-3.99.5-2.el7.x86_64 libICE-1.0.9-2.el7.x86_64 libSM-1.2.2-2.el7.x86_64 libX11-1.6.3-3.el7.x86_64 libXau-1.0.8-2.1.el7.x86_64 libXcomposite-0.4.4-4.1.el7.x86_64 libXdamage-1.1.4-4.1.el7.x86_64 libXext-1.3.3-3.el7.x86_64 libXfixes-5.0.1-2.1.el7.x86_64 libXi-1.7.4-2.el7.x86_64 libXinerama-1.1.3-2.1.el7.x86_64 libXrandr-1.4.2-2.el7.x86_64 libXrender-0.9.8-2.1.el7.x86_64 libXtst-1.2.2-2.1.el7.x86_64 libXv-1.0.10-2.el7.x86_64 libXxf86vm-1.1.3-2.1.el7.x86_64 libass-0.13.4-1.el7.x86_64 libasyncns-0.8-7.el7.x86_64 libattr-2.4.46-12.el7.x86_64 libcap-2.22-8.el7.x86_64 libcom_err-1.42.9-9.el7.x86_64 libdrm-2.4.67-3.el7.x86_64 libffi-3.0.13-18.el7.x86_64 libgcc-4.8.5-11.el7.x86_64 libgcrypt-1.5.3-13.el7_3.1.x86_64 libgpg-error-1.12-3.el7.x86_64 libicu-50.1.2-15.el7.x86_64 libjpeg-turbo-1.2.90-5.el7.x86_64 libmodman-2.0.1-8.el7.x86_64 libogg-1.3.0-7.el7.x86_64 libpng-1.5.13-7.el7_2.x86_64 libproxy-0.4.11-10.el7.x86_64 libselinux-2.5-6.el7.x86_64 libsndfile-1.0.25-10.el7.x86_64 libstdc++-4.8.5-11.el7.x86_64 libuuid-2.23.2-33.el7.x86_64 libvorbis-1.3.3-8.el7.x86_64 libvpx-1.3.0-5.el7_0.x86_64 libwebp-0.3.0-3.el7.x86_64 libxcb-1.11-4.el7.x86_64 libxml2-2.9.1-6.el7_2.3.x86_64 libxshmfence-1.2-1.el7.x86_64 libxslt-1.1.28-5.el7.x86_64 mariadb-libs-5.5.52-1.el7.x86_64 mesa-libGL-11.2.2-2.20160614.el7.x86_64 mesa-libglapi-11.2.2-2.20160614.el7.x86_64 numactl-libs-2.0.9-6.el7_2.x86_64 openssl-libs-1.0.1e-60.el7.x86_64 orc-0.4.22-5.el7.x86_64 pcre-8.32-15.el7_2.1.x86_64 pulseaudio-libs-6.0-8.el7.x86_64 qt5-qtbase-5.6.1-10.el7.x86_64 qt5-qtbase-gui-5.6.1-10.el7.x86_64 qt5-qtbase-mysql-5.6.1-10.el7.x86_64 qt5-qtdeclarative-5.6.1-10.el7.x86_64 qt5-qtimageformats-5.6.1-10.el7.x86_64 qt5-qtlocation-5.6.1-10.el7.x86_64 qt5-qtscript-5.6.1-10.el7.x86_64 qt5-qtsensors-5.6.1-10.el7.x86_64 qt5-qtwebchannel-5.6.1-10.el7.x86_64 qt5-qtwebkit-5.6.1-3.b889f46git.el7.x86_64 sqlite-3.7.17-8.el7.x86_64 systemd-libs-219-30.el7_3.6.x86_64 taglib-1.8-7.20130218git.el7.x86_64 tcp_wrappers-libs-7.6-77.el7.x86_64 x264-libs-0.142-11.20141221git6a301b6.el7.nux.x86_64 x265-libs-1.9-1.el7.nux.x86_64 xvidcore-1.3.2-5.el7.nux.x86_64 xz-libs-5.2.2-1.el7.x86_64 zlib-1.2.7-17.el7.x86_64
(gdb)





More information about the mythtv-users mailing list