2014-07-11 02:09:04.345613 C mythmetadatalookup version: makepkg [v0.27.3-59-g6435215-dirty] www.mythtv.org 2014-07-11 02:09:04.345631 C Qt version: compile: 4.8.6, runtime: 4.8.6 2014-07-11 02:09:04.345635 N Enabled verbose msgs: all norefcount 2014-07-11 02:09:04.345645 N Setting Log Level to LOG_DEBUG 2014-07-11 02:09:04.356954 I Added logging to the console 2014-07-11 02:09:04.358129 I Setup Interrupt handler 2014-07-11 02:09:04.358150 I Setup Terminated handler 2014-07-11 02:09:04.358167 I Setup Segmentation fault handler 2014-07-11 02:09:04.358183 I Setup Aborted handler 2014-07-11 02:09:04.358199 I Setup Bus error handler 2014-07-11 02:09:04.358216 I Setup Floating point exception handler 2014-07-11 02:09:04.358235 I Setup Illegal instruction handler 2014-07-11 02:09:04.358256 I Setup Real-time signal 0 handler 2014-07-11 02:09:04.358324 N Using runtime prefix = /usr 2014-07-11 02:09:04.358348 N Using configuration directory = /home/mythtv/.mythtv 2014-07-11 02:09:04.358455 I Assumed character encoding: en_US.UTF-8 2014-07-11 02:09:04.358988 I Using localhost value of central 2014-07-11 02:09:04.359006 I Clearing Settings Cache. 2014-07-11 02:09:04.359042 I DefaultUPnP() - No default UPnP backend 2014-07-11 02:09:04.359058 I Testing network connectivity to '192.168.2.12' 2014-07-11 02:09:04.359423 I Starting process manager 2014-07-11 02:09:04.359522 D Launching: ping -t 3 -c 1 192.168.2.12 >/dev/null 2>&1 2014-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 2014-07-11 02:09:04.362461 I Starting IO manager (write) 2014-07-11 02:09:04.362968 I Starting process signal handler 2014-07-11 02:09:04.363225 I Starting IO manager (read) 2014-07-11 02:09:04.459411 I New Client: (#1) 2014-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 2014-07-11 02:09:04.463235 I Clearing Settings Cache. 2014-07-11 02:09:04.485238 I Database connection created: DBManager0 2014-07-11 02:09:04.485271 I New DB connection, total: 1 2014-07-11 02:09:04.487317 I Connected to database 'mythconverg' at host: 192.168.2.12 2014-07-11 02:09:04.490349 D FindDatabase() - Success! 2014-07-11 02:09:04.490378 I Closing DB connection named 'DBManager0' 2014-07-11 02:09:04.490460 I Clearing Settings Cache. 2014-07-11 02:09:04.490510 I Database connection created: DBManager1 2014-07-11 02:09:04.490520 I New DB connection, total: 1 2014-07-11 02:09:04.490851 I Connected to database 'mythconverg' at host: 192.168.2.12 2014-07-11 02:09:04.491399 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'language' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.491472 D MSqlQuery::next(DBManager1) Result: "data = EN_US" 2014-07-11 02:09:04.491703 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'country' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.491718 D MSqlQuery::next(DBManager1) Result: "data = US" 2014-07-11 02:09:04.491761 N Setting QT default locale to EN_US 2014-07-11 02:09:04.491827 I Current locale EN_US 2014-07-11 02:09:04.491876 N Reading locale defaults from /usr/share/mythtv//locales/en_us.xml 2014-07-11 02:09:04.492470 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'country' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.492491 D MSqlQuery::next(DBManager1) Result: "data = US" 2014-07-11 02:09:04.492793 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'freqtable' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:04.492963 D MSqlQuery::next(DBManager1) Result: "data = us-bcast" 2014-07-11 02:09:04.493139 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'iso639language0' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:04.493309 D MSqlQuery::next(DBManager1) Result: "data = eng" 2014-07-11 02:09:04.493481 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'iso639language1' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:04.493638 D MSqlQuery::next(DBManager1) Result: "data = eng" 2014-07-11 02:09:04.493803 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'language' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.493816 D MSqlQuery::next(DBManager1) Result: "data = EN_US" 2014-07-11 02:09:04.493980 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'tvformat' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:04.494132 D MSqlQuery::next(DBManager1) Result: "data = NTSC" 2014-07-11 02:09:04.494296 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'vbiformat' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:04.494449 D MSqlQuery::next(DBManager1) Result: "data = None" 2014-07-11 02:09:04.494613 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'dateformat' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.494627 D MSqlQuery::next(DBManager1) Result: "data = ddd MMM d" 2014-07-11 02:09:04.494790 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'mytharchivedateformat' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.494803 D MSqlQuery::next(DBManager1) Result: "data = %a %b %d" 2014-07-11 02:09:04.494993 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'mytharchivetimeformat' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.495006 D MSqlQuery::next(DBManager1) Result: "data = %I:%M %p" 2014-07-11 02:09:04.495181 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'mytharchivevideoformat' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.495195 D MSqlQuery::next(DBManager1) Result: "data = PAL" 2014-07-11 02:09:04.495360 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'shortdateformat' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.495374 D MSqlQuery::next(DBManager1) Result: "data = M/d" 2014-07-11 02:09:04.495535 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'timeformat' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.495548 D MSqlQuery::next(DBManager1) Result: "data = h:mm AP" 2014-07-11 02:09:04.495579 I Enabling Settings Cache. 2014-07-11 02:09:04.495583 I Clearing Settings Cache. 2014-07-11 02:09:04.495769 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'language' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.495783 D MSqlQuery::next(DBManager1) Result: "data = EN_US" 2014-07-11 02:09:04.495882 I Loading en_us translation for module mythfrontend 2014-07-11 02:09:04.496096 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'videolistunknownfiletypes' AND hostname = 'central' <<<< Returns 1 row(s) 2014-07-11 02:09:04.496111 D MSqlQuery::next(DBManager1) Result: "data = 1" 2014-07-11 02:09:04.496147 I Testing grabbers and metadata sites for functionality... 2014-07-11 02:09:04.496304 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'moviegrabber' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:04.496464 D MSqlQuery::next(DBManager1) Result: "data = metadata/Movie/tmdb3.py" 2014-07-11 02:09:04.496543 D Launching: /usr/share/mythtv/metadata/Movie/tmdb3.py -t 2014-07-11 02:09:04.498720 I Managed child (PID: 22891) has started! command=/usr/share/mythtv/metadata/Movie/tmdb3.py -t, timeout=0 2014-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 2014-07-11 02:09:04.864463 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'televisiongrabber' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:04.864829 D MSqlQuery::next(DBManager1) Result: "data = metadata/Television/ttvdb.py" 2014-07-11 02:09:04.864960 D Launching: /usr/share/mythtv/metadata/Television/ttvdb.py -t 2014-07-11 02:09:04.867741 I Managed child (PID: 22894) has started! command=/usr/share/mythtv/metadata/Television/ttvdb.py -t, timeout=0 2014-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 2014-07-11 02:09:05.064193 I All grabbers tested and working. Continuing... 2014-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) 2014-07-11 02:09:05.065715 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaultstartoffset' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.066409 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaultendoffset' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.067092 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'prefdupmethod' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-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) 2014-07-11 02:09:05.067890 D MSqlQuery::next(DBManager1) Result: "SUM(1 << filterid) = 0" 2014-07-11 02:09:05.068276 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autoexpiredefault' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.068651 D MSqlQuery::next(DBManager1) Result: "data = 0" 2014-07-11 02:09:05.069048 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autocommercialflag' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.069388 D MSqlQuery::next(DBManager1) Result: "data = 1" 2014-07-11 02:09:05.069757 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autotranscode' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.070107 D MSqlQuery::next(DBManager1) Result: "data = 0" 2014-07-11 02:09:05.070392 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'defaulttranscoder' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.070644 D MSqlQuery::next(DBManager1) Result: "data = 0" 2014-07-11 02:09:05.070921 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob1' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.071172 D MSqlQuery::next(DBManager1) Result: "data = 0" 2014-07-11 02:09:05.071441 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob2' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.071688 D MSqlQuery::next(DBManager1) Result: "data = 0" 2014-07-11 02:09:05.071960 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob3' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.072206 D MSqlQuery::next(DBManager1) Result: "data = 0" 2014-07-11 02:09:05.072477 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autorunuserjob4' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.072719 D MSqlQuery::next(DBManager1) Result: "data = 0" 2014-07-11 02:09:05.072988 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'autometadatalookup' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.073232 D MSqlQuery::next(DBManager1) Result: "data = 1" 2014-07-11 02:09:05.073625 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'idletimeoutsecs' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.073878 D MSqlQuery::next(DBManager1) Result: "data = 300" 2014-07-11 02:09:05.074159 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'masterserverip' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.074410 D MSqlQuery::next(DBManager1) Result: "data = 192.168.2.12" 2014-07-11 02:09:05.074745 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'masterserverport' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.074996 D MSqlQuery::next(DBManager1) Result: "data = 6543" 2014-07-11 02:09:05.075276 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'wolbackendcommand' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.075528 D MSqlQuery::next(DBManager1) Result: "data = " 2014-07-11 02:09:05.075800 D MSqlQuery::exec(DBManager1) SELECT data FROM settings WHERE value = 'backendconnectretry' AND hostname = 'central' <<<< Returns 0 row(s) 2014-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) 2014-07-11 02:09:05.076079 I MythCoreContext: Connecting to backend server: 192.168.2.12:6543 (try 1 of 1) 2014-07-11 02:09:05.076172 I MythSocket(1f6b210:-1): MythSocket(-1, 0x0) ctor 2014-07-11 02:09:05.076912 I MythSocket(1f6b210:-1): IP is local, using loopback address instead 2014-07-11 02:09:05.076944 I MythSocket(1f6b210:-1): attempting connect() to (127.0.0.1:6543) 2014-07-11 02:09:05.077251 I MythSocket(1f6b210:16): Connected to (127.0.0.1:6543) 2014-07-11 02:09:05.077511 I MythSocket(1f6b210:16): write -> 16 30 MYTH_PROTO_VERSION 77 WindMark 2014-07-11 02:09:05.078919 I MythSocket(1f6b210:16): read <- 16 13 ACCEPT[]:[]77 2014-07-11 02:09:05.079902 I Using protocol version 77 2014-07-11 02:09:05.079963 I MythSocket(1f6b210:16): write -> 16 22 ANN Playback central 0 2014-07-11 02:09:05.080236 I MythSocket(1f6b210:16): read <- 16 2 OK 2014-07-11 02:09:05.081276 I MythSocket(1fc5140:-1): MythSocket(-1, 0x1f6be38) ctor 2014-07-11 02:09:05.081533 I MythSocket(1fc5140:-1): IP is local, using loopback address instead 2014-07-11 02:09:05.081558 I MythSocket(1fc5140:-1): attempting connect() to (127.0.0.1:6543) 2014-07-11 02:09:05.081733 D MythSocket(1fc5140:18): calling m_callback->connected() 2014-07-11 02:09:05.081755 I MythSocket(1fc5140:18): Connected to (127.0.0.1:6543) 2014-07-11 02:09:05.081956 I MythSocket(1fc5140:18): write -> 18 21 ANN Monitor central 1 2014-07-11 02:09:05.083128 I MythSocket(1fc5140:18): read <- 18 2 OK 2014-07-11 02:09:05.083217 I MythSocket(1f6b210:16): write -> 16 14 QUERY_HOSTNAME 2014-07-11 02:09:05.083498 I MythSocket(1f6b210:16): read <- 16 7 central 2014-07-11 02:09:05.084494 I Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M 2014-07-11 02:09:05.084512 D Launching: /usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M 2014-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 2014-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 2014-07-11 02:09:05.465004 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M 2014-07-11 02:09:05.465026 D Launching: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M 2014-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 2014-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 2014-07-11 02:09:05.765231 I Metadata Lookup Failed: No Results 0 0 2014-07-11 02:09:07.084596 N MythMetadataLookup run complete. 2014-07-11 02:09:07.162662 D MythSocket(1fc5140:18): calling m_callback->readyRead() 2014-07-11 02:09:07.163391 I MythSocket(1fc5140:18): read <- 18 51 BACKEND_MESSAGE[]:[]SHUTDOWN_COUNTDOWN -1[]:[]empty