Ticket #12206: v0.27.3.59.g6435215.txt

File v0.27.3.59.g6435215.txt, 19.3 KB (added by Frank Phillips <fphillips81@…>, 10 years ago)
Line 
12014-07-11 02:09:04.345613 C  mythmetadatalookup version: makepkg [v0.27.3-59-g6435215-dirty] www.mythtv.org
22014-07-11 02:09:04.345631 C  Qt version: compile: 4.8.6, runtime: 4.8.6
32014-07-11 02:09:04.345635 N  Enabled verbose msgs: all norefcount
42014-07-11 02:09:04.345645 N  Setting Log Level to LOG_DEBUG
52014-07-11 02:09:04.356954 I  Added logging to the console
62014-07-11 02:09:04.358129 I  Setup Interrupt handler
72014-07-11 02:09:04.358150 I  Setup Terminated handler
82014-07-11 02:09:04.358167 I  Setup Segmentation fault handler
92014-07-11 02:09:04.358183 I  Setup Aborted handler
102014-07-11 02:09:04.358199 I  Setup Bus error handler
112014-07-11 02:09:04.358216 I  Setup Floating point exception handler
122014-07-11 02:09:04.358235 I  Setup Illegal instruction handler
132014-07-11 02:09:04.358256 I  Setup Real-time signal 0 handler
142014-07-11 02:09:04.358324 N  Using runtime prefix = /usr
152014-07-11 02:09:04.358348 N  Using configuration directory = /home/mythtv/.mythtv
162014-07-11 02:09:04.358455 I  Assumed character encoding: en_US.UTF-8
172014-07-11 02:09:04.358988 I  Using localhost value of central
182014-07-11 02:09:04.359006 I  Clearing Settings Cache.
192014-07-11 02:09:04.359042 I  DefaultUPnP() - No default UPnP backend
202014-07-11 02:09:04.359058 I  Testing network connectivity to '192.168.2.12'
212014-07-11 02:09:04.359423 I  Starting process manager
222014-07-11 02:09:04.359522 D  Launching: ping -t 3 -c 1  192.168.2.12  >/dev/null 2>&1
232014-07-11 02:09:04.362201 I  Managed child (PID: 22888) has started! * command=ping -t 3 -c 1  192.168.2.12  >/dev/null 2>&1, timeout=0
242014-07-11 02:09:04.362461 I  Starting IO manager (write)
252014-07-11 02:09:04.362968 I  Starting process signal handler
262014-07-11 02:09:04.363225 I  Starting IO manager (read)
272014-07-11 02:09:04.459411 I  New Client:  (#1)
282014-07-11 02:09:04.459578 I  Managed child (PID: 22888) has exited! command=ping -t 3 -c 1  192.168.2.12  >/dev/null 2>&1, status=0, result=0
292014-07-11 02:09:04.463235 I  Clearing Settings Cache.
302014-07-11 02:09:04.485238 I  Database connection created: DBManager0
312014-07-11 02:09:04.485271 I  New DB connection, total: 1
322014-07-11 02:09:04.487317 I  Connected to database 'mythconverg' at host: 192.168.2.12
332014-07-11 02:09:04.490349 D  FindDatabase() - Success!
342014-07-11 02:09:04.490378 I  Closing DB connection named 'DBManager0'
352014-07-11 02:09:04.490460 I  Clearing Settings Cache.
362014-07-11 02:09:04.490510 I  Database connection created: DBManager1
372014-07-11 02:09:04.490520 I  New DB connection, total: 1
382014-07-11 02:09:04.490851 I  Connected to database 'mythconverg' at host: 192.168.2.12
392014-07-11 02:09:04.491399 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'language' AND hostname = 'central' <<<< Returns 1 row(s)
402014-07-11 02:09:04.491472 D  MSqlQuery::next(DBManager1) Result: "data = EN_US"
412014-07-11 02:09:04.491703 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'country' AND hostname = 'central' <<<< Returns 1 row(s)
422014-07-11 02:09:04.491718 D  MSqlQuery::next(DBManager1) Result: "data = US"
432014-07-11 02:09:04.491761 N  Setting QT default locale to EN_US
442014-07-11 02:09:04.491827 I  Current locale EN_US
452014-07-11 02:09:04.491876 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
462014-07-11 02:09:04.492470 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'country' AND hostname = 'central' <<<< Returns 1 row(s)
472014-07-11 02:09:04.492491 D  MSqlQuery::next(DBManager1) Result: "data = US"
482014-07-11 02:09:04.492793 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'freqtable' AND hostname = 'central' <<<< Returns 0 row(s)
492014-07-11 02:09:04.492948 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'freqtable' AND hostname IS NULL <<<< Returns 1 row(s)
502014-07-11 02:09:04.492963 D  MSqlQuery::next(DBManager1) Result: "data = us-bcast"
512014-07-11 02:09:04.493139 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'iso639language0' AND hostname = 'central' <<<< Returns 0 row(s)
522014-07-11 02:09:04.493295 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'iso639language0' AND hostname IS NULL <<<< Returns 1 row(s)
532014-07-11 02:09:04.493309 D  MSqlQuery::next(DBManager1) Result: "data = eng"
542014-07-11 02:09:04.493481 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'iso639language1' AND hostname = 'central' <<<< Returns 0 row(s)
552014-07-11 02:09:04.493625 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'iso639language1' AND hostname IS NULL <<<< Returns 1 row(s)
562014-07-11 02:09:04.493638 D  MSqlQuery::next(DBManager1) Result: "data = eng"
572014-07-11 02:09:04.493803 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'language' AND hostname = 'central' <<<< Returns 1 row(s)
582014-07-11 02:09:04.493816 D  MSqlQuery::next(DBManager1) Result: "data = EN_US"
592014-07-11 02:09:04.493980 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'tvformat' AND hostname = 'central' <<<< Returns 0 row(s)
602014-07-11 02:09:04.494119 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'tvformat' AND hostname IS NULL <<<< Returns 1 row(s)
612014-07-11 02:09:04.494132 D  MSqlQuery::next(DBManager1) Result: "data = NTSC"
622014-07-11 02:09:04.494296 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'vbiformat' AND hostname = 'central' <<<< Returns 0 row(s)
632014-07-11 02:09:04.494436 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'vbiformat' AND hostname IS NULL <<<< Returns 1 row(s)
642014-07-11 02:09:04.494449 D  MSqlQuery::next(DBManager1) Result: "data = None"
652014-07-11 02:09:04.494613 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'dateformat' AND hostname = 'central' <<<< Returns 1 row(s)
662014-07-11 02:09:04.494627 D  MSqlQuery::next(DBManager1) Result: "data = ddd MMM d"
672014-07-11 02:09:04.494790 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'mytharchivedateformat' AND hostname = 'central' <<<< Returns 1 row(s)
682014-07-11 02:09:04.494803 D  MSqlQuery::next(DBManager1) Result: "data = %a  %b  %d"
692014-07-11 02:09:04.494993 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'mytharchivetimeformat' AND hostname = 'central' <<<< Returns 1 row(s)
702014-07-11 02:09:04.495006 D  MSqlQuery::next(DBManager1) Result: "data = %I:%M %p"
712014-07-11 02:09:04.495181 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'mytharchivevideoformat' AND hostname = 'central' <<<< Returns 1 row(s)
722014-07-11 02:09:04.495195 D  MSqlQuery::next(DBManager1) Result: "data = PAL"
732014-07-11 02:09:04.495360 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'shortdateformat' AND hostname = 'central' <<<< Returns 1 row(s)
742014-07-11 02:09:04.495374 D  MSqlQuery::next(DBManager1) Result: "data = M/d"
752014-07-11 02:09:04.495535 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'timeformat' AND hostname = 'central' <<<< Returns 1 row(s)
762014-07-11 02:09:04.495548 D  MSqlQuery::next(DBManager1) Result: "data = h:mm AP"
772014-07-11 02:09:04.495579 I  Enabling Settings Cache.
782014-07-11 02:09:04.495583 I  Clearing Settings Cache.
792014-07-11 02:09:04.495769 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'language' AND hostname = 'central' <<<< Returns 1 row(s)
802014-07-11 02:09:04.495783 D  MSqlQuery::next(DBManager1) Result: "data = EN_US"
812014-07-11 02:09:04.495882 I  Loading en_us translation for module mythfrontend
822014-07-11 02:09:04.496096 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'videolistunknownfiletypes' AND hostname = 'central' <<<< Returns 1 row(s)
832014-07-11 02:09:04.496111 D  MSqlQuery::next(DBManager1) Result: "data = 1"
842014-07-11 02:09:04.496147 I  Testing grabbers and metadata sites for functionality...
852014-07-11 02:09:04.496304 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'moviegrabber' AND hostname = 'central' <<<< Returns 0 row(s)
862014-07-11 02:09:04.496450 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'moviegrabber' AND hostname IS NULL <<<< Returns 1 row(s)
872014-07-11 02:09:04.496464 D  MSqlQuery::next(DBManager1) Result: "data = metadata/Movie/tmdb3.py"
882014-07-11 02:09:04.496543 D  Launching: /usr/share/mythtv/metadata/Movie/tmdb3.py -t
892014-07-11 02:09:04.498720 I  Managed child (PID: 22891) has started!  command=/usr/share/mythtv/metadata/Movie/tmdb3.py -t, timeout=0
902014-07-11 02:09:04.860008 I  Managed child (PID: 22891) has exited! command=/usr/share/mythtv/metadata/Movie/tmdb3.py -t, status=0, result=0
912014-07-11 02:09:04.864463 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'televisiongrabber' AND hostname = 'central' <<<< Returns 0 row(s)
922014-07-11 02:09:04.864796 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'televisiongrabber' AND hostname IS NULL <<<< Returns 1 row(s)
932014-07-11 02:09:04.864829 D  MSqlQuery::next(DBManager1) Result: "data = metadata/Television/ttvdb.py"
942014-07-11 02:09:04.864960 D  Launching: /usr/share/mythtv/metadata/Television/ttvdb.py -t
952014-07-11 02:09:04.867741 I  Managed child (PID: 22894) has started!  command=/usr/share/mythtv/metadata/Television/ttvdb.py -t, timeout=0
962014-07-11 02:09:05.060260 I  Managed child (PID: 22894) has exited! command=/usr/share/mythtv/metadata/Television/ttvdb.py -t, status=0, result=0
972014-07-11 02:09:05.064193 I  All grabbers tested and working.  Continuing...
982014-07-11 02:09:05.065194 D  MSqlQuery::exec(DBManager1)  SELECT category_type  FROM recordedprogram  WHERE chanid = '0'  AND starttime = '2014-07-11T07:09:05Z'; <<<< Returns 0 row(s)
992014-07-11 02:09:05.065715 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaultstartoffset' AND hostname = 'central' <<<< Returns 0 row(s)
1002014-07-11 02:09:05.066036 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaultstartoffset' AND hostname IS NULL <<<< Returns 0 row(s)
1012014-07-11 02:09:05.066409 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaultendoffset' AND hostname = 'central' <<<< Returns 0 row(s)
1022014-07-11 02:09:05.066717 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaultendoffset' AND hostname IS NULL <<<< Returns 0 row(s)
1032014-07-11 02:09:05.067092 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'prefdupmethod' AND hostname = 'central' <<<< Returns 0 row(s)
1042014-07-11 02:09:05.067398 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'prefdupmethod' AND hostname IS NULL <<<< Returns 0 row(s)
1052014-07-11 02:09:05.067823 D  MSqlQuery::exec(DBManager1) SELECT SUM(1 << filterid) FROM recordfilter WHERE filterid >= 0 AND filterid < '12' AND       TRIM(clause) <> '' AND newruledefault <> 0 <<<< Returns 1 row(s)
1062014-07-11 02:09:05.067890 D  MSqlQuery::next(DBManager1) Result: "SUM(1 << filterid) = 0"
1072014-07-11 02:09:05.068276 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autoexpiredefault' AND hostname = 'central' <<<< Returns 0 row(s)
1082014-07-11 02:09:05.068620 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autoexpiredefault' AND hostname IS NULL <<<< Returns 1 row(s)
1092014-07-11 02:09:05.068651 D  MSqlQuery::next(DBManager1) Result: "data = 0"
1102014-07-11 02:09:05.069048 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autocommercialflag' AND hostname = 'central' <<<< Returns 0 row(s)
1112014-07-11 02:09:05.069359 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autocommercialflag' AND hostname IS NULL <<<< Returns 1 row(s)
1122014-07-11 02:09:05.069388 D  MSqlQuery::next(DBManager1) Result: "data = 1"
1132014-07-11 02:09:05.069757 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autotranscode' AND hostname = 'central' <<<< Returns 0 row(s)
1142014-07-11 02:09:05.070078 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autotranscode' AND hostname IS NULL <<<< Returns 1 row(s)
1152014-07-11 02:09:05.070107 D  MSqlQuery::next(DBManager1) Result: "data = 0"
1162014-07-11 02:09:05.070392 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaulttranscoder' AND hostname = 'central' <<<< Returns 0 row(s)
1172014-07-11 02:09:05.070623 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaulttranscoder' AND hostname IS NULL <<<< Returns 1 row(s)
1182014-07-11 02:09:05.070644 D  MSqlQuery::next(DBManager1) Result: "data = 0"
1192014-07-11 02:09:05.070921 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob1' AND hostname = 'central' <<<< Returns 0 row(s)
1202014-07-11 02:09:05.071150 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob1' AND hostname IS NULL <<<< Returns 1 row(s)
1212014-07-11 02:09:05.071172 D  MSqlQuery::next(DBManager1) Result: "data = 0"
1222014-07-11 02:09:05.071441 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob2' AND hostname = 'central' <<<< Returns 0 row(s)
1232014-07-11 02:09:05.071667 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob2' AND hostname IS NULL <<<< Returns 1 row(s)
1242014-07-11 02:09:05.071688 D  MSqlQuery::next(DBManager1) Result: "data = 0"
1252014-07-11 02:09:05.071960 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob3' AND hostname = 'central' <<<< Returns 0 row(s)
1262014-07-11 02:09:05.072186 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob3' AND hostname IS NULL <<<< Returns 1 row(s)
1272014-07-11 02:09:05.072206 D  MSqlQuery::next(DBManager1) Result: "data = 0"
1282014-07-11 02:09:05.072477 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob4' AND hostname = 'central' <<<< Returns 0 row(s)
1292014-07-11 02:09:05.072698 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob4' AND hostname IS NULL <<<< Returns 1 row(s)
1302014-07-11 02:09:05.072719 D  MSqlQuery::next(DBManager1) Result: "data = 0"
1312014-07-11 02:09:05.072988 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autometadatalookup' AND hostname = 'central' <<<< Returns 0 row(s)
1322014-07-11 02:09:05.073212 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autometadatalookup' AND hostname IS NULL <<<< Returns 1 row(s)
1332014-07-11 02:09:05.073232 D  MSqlQuery::next(DBManager1) Result: "data = 1"
1342014-07-11 02:09:05.073625 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'idletimeoutsecs' AND hostname = 'central' <<<< Returns 0 row(s)
1352014-07-11 02:09:05.073857 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'idletimeoutsecs' AND hostname IS NULL <<<< Returns 1 row(s)
1362014-07-11 02:09:05.073878 D  MSqlQuery::next(DBManager1) Result: "data = 300"
1372014-07-11 02:09:05.074159 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'masterserverip' AND hostname = 'central' <<<< Returns 0 row(s)
1382014-07-11 02:09:05.074388 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'masterserverip' AND hostname IS NULL <<<< Returns 1 row(s)
1392014-07-11 02:09:05.074410 D  MSqlQuery::next(DBManager1) Result: "data = 192.168.2.12"
1402014-07-11 02:09:05.074745 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'masterserverport' AND hostname = 'central' <<<< Returns 0 row(s)
1412014-07-11 02:09:05.074975 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'masterserverport' AND hostname IS NULL <<<< Returns 1 row(s)
1422014-07-11 02:09:05.074996 D  MSqlQuery::next(DBManager1) Result: "data = 6543"
1432014-07-11 02:09:05.075276 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'wolbackendcommand' AND hostname = 'central' <<<< Returns 0 row(s)
1442014-07-11 02:09:05.075506 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'wolbackendcommand' AND hostname IS NULL <<<< Returns 1 row(s)
1452014-07-11 02:09:05.075528 D  MSqlQuery::next(DBManager1) Result: "data = "
1462014-07-11 02:09:05.075800 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'backendconnectretry' AND hostname = 'central' <<<< Returns 0 row(s)
1472014-07-11 02:09:05.076030 D  MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'backendconnectretry' AND hostname IS NULL <<<< Returns 0 row(s)
1482014-07-11 02:09:05.076079 I  MythCoreContext: Connecting to backend server: 192.168.2.12:6543 (try 1 of 1)
1492014-07-11 02:09:05.076172 I  MythSocket(1f6b210:-1): MythSocket(-1, 0x0) ctor
1502014-07-11 02:09:05.076912 I  MythSocket(1f6b210:-1): IP is local, using loopback address instead
1512014-07-11 02:09:05.076944 I  MythSocket(1f6b210:-1): attempting connect() to (127.0.0.1:6543)
1522014-07-11 02:09:05.077251 I  MythSocket(1f6b210:16): Connected to (127.0.0.1:6543)
1532014-07-11 02:09:05.077511 I  MythSocket(1f6b210:16): write -> 16 30      MYTH_PROTO_VERSION 77 WindMark
1542014-07-11 02:09:05.078919 I  MythSocket(1f6b210:16): read  <- 16 13      ACCEPT[]:[]77
1552014-07-11 02:09:05.079902 I  Using protocol version 77
1562014-07-11 02:09:05.079963 I  MythSocket(1f6b210:16): write -> 16 22      ANN Playback central 0
1572014-07-11 02:09:05.080236 I  MythSocket(1f6b210:16): read  <- 16 2       OK
1582014-07-11 02:09:05.081276 I  MythSocket(1fc5140:-1): MythSocket(-1, 0x1f6be38) ctor
1592014-07-11 02:09:05.081533 I  MythSocket(1fc5140:-1): IP is local, using loopback address instead
1602014-07-11 02:09:05.081558 I  MythSocket(1fc5140:-1): attempting connect() to (127.0.0.1:6543)
1612014-07-11 02:09:05.081733 D  MythSocket(1fc5140:18): calling m_callback->connected()
1622014-07-11 02:09:05.081755 I  MythSocket(1fc5140:18): Connected to (127.0.0.1:6543)
1632014-07-11 02:09:05.081956 I  MythSocket(1fc5140:18): write -> 18 21      ANN Monitor central 1
1642014-07-11 02:09:05.083128 I  MythSocket(1fc5140:18): read  <- 18 2       OK
1652014-07-11 02:09:05.083217 I  MythSocket(1f6b210:16): write -> 16 14      QUERY_HOSTNAME
1662014-07-11 02:09:05.083498 I  MythSocket(1f6b210:16): read  <- 16 7       central
1672014-07-11 02:09:05.084494 I  Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M
1682014-07-11 02:09:05.084512 D  Launching: /usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M
1692014-07-11 02:09:05.089633 I  Managed child (PID: 22902) has started!  command=/usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M, timeout=0
1702014-07-11 02:09:05.460657 I  Managed child (PID: 22902) has exited! command=/usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M, status=256, result=1
1712014-07-11 02:09:05.465004 I  Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M
1722014-07-11 02:09:05.465026 D  Launching: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M
1732014-07-11 02:09:05.470086 I  Managed child (PID: 22905) has started!  command=/usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M, timeout=0
1742014-07-11 02:09:05.760979 I  Managed child (PID: 22905) has exited! command=/usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M, status=0, result=0
1752014-07-11 02:09:05.765231 I  Metadata Lookup Failed: No Results  0 0
1762014-07-11 02:09:07.084596 N  MythMetadataLookup run complete.
1772014-07-11 02:09:07.162662 D  MythSocket(1fc5140:18): calling m_callback->readyRead()
1782014-07-11 02:09:07.163391 I  MythSocket(1fc5140:18): read  <- 18 51      BACKEND_MESSAGE[]:[]SHUTDOWN_COUNTDOWN -1[]:[]empty