Ticket #2285: 2285-dbg.patch

libs/libmythtv/remoteutil.cpp

@@ -181 +181 @@
     RemoteGetRecordingList(&expiringlist, strList);
 }
 
-int RemoteGetRecordingList(vector<ProgramInfo *> *reclist, QStringList &strList)
+int RemoteGetRecordingList(vector<ProgramInfo *> *reclist,
+                           QStringList &strList)
 {
+    VERBOSE(VB_IMPORTANT, "RemoteGetRecordingList(..."
+            <<strList[0]<<") -- begin");
     if (!gContext->SendReceiveStringList(strList))
+    {
+        VERBOSE(VB_IMPORTANT, "RemoteGetRecordingList(..."
+                <<strList[0]<<") -- end 0");
         return 0;
+    }
 
     int numrecordings = strList[0].toInt();
 
@@ -205 +212 @@
         }
     }
 
+    VERBOSE(VB_IMPORTANT, "RemoteGetRecordingList(..."
+            <<strList[0]<<") -- end "<<numrecordings);
+
     return numrecordings;
 }
 

programs/mythfrontend/playbackbox.cpp

@@ -1431 +1431 @@
 
 bool PlaybackBox::FillList(bool useCachedData)
 {
+    VERBOSE(VB_IMPORTANT, "FillList("
+            <<(useCachedData?"use":"no")<<" cache) -- begin");
+
     ProgramInfo *p;
 
     // Save some information so we can find our place again.
@@ -1469 +1472 @@
     // objects with the title lists.
     progLists[""].setAutoDelete(false);
 
+    VERBOSE(VB_IMPORTANT, "FillList() 1");
     fillRecGroupPasswordCache();
 
     ViewTitleSort titleSort = (ViewTitleSort)gContext->GetNumSetting(
@@ -1481 +1485 @@
 
     bool LiveTVInAllPrograms = gContext->GetNumSetting("LiveTVInAllPrograms",0);
 
+    VERBOSE(VB_IMPORTANT, "FillList() 2");
     if (!useCachedData || !progCache || progCache->empty())
     {
         clearProgramCache();
 
         progCache = RemoteGetRecordedList(allOrder == 0 || type == Delete);
     }
+    VERBOSE(VB_IMPORTANT, "FillList() 3");
 
     if (progCache)
     {
@@ -1606 +1612 @@
             }
         }
     }
+    VERBOSE(VB_IMPORTANT, "FillList() 4");
 
     if (sortedList.count() == 0)
     {
@@ -1615 +1622 @@
         titleIndex = 0;
         playList.clear();
 
+        VERBOSE(VB_IMPORTANT, "FillList() -- end A");
         return 0;
     }
 
@@ -1663 +1671 @@
         }
     }
 
+    VERBOSE(VB_IMPORTANT, "FillList() 5");
+
     if (progLists[watchGroup].count() > 1)
     {
         QDateTime now = QDateTime::currentDateTime();
@@ -1823 +1833 @@
         }
         progLists[watchGroup].Sort(comp_recpriority2);
     }
+    VERBOSE(VB_IMPORTANT, "FillList() 6");
 
     // Try to find our old place in the title list.  Scan the new
     // titles backwards until we find where we were or go past.  This
@@ -1851 +1862 @@
             break;
     }
 
+    VERBOSE(VB_IMPORTANT, "FillList() 7");
     // Now do pretty much the same thing for the individual shows on
     // the specific program list if needed.
     if (oldtitle != titleList[titleIndex] || oldchanid.isNull())
@@ -1927 +1939 @@
         }
     }
 
+    VERBOSE(VB_IMPORTANT, "FillList() -- end B");
     return (progCache != NULL);
 }
 

programs/mythbackend/mainserver.cpp

@@ -8 +8 @@
 #include <qwaitcondition.h>
 #include <qregexp.h>
 
+#include <sys/time.h>
 #include <cstdlib>
 #include <cerrno>
 #include <math.h>
@@ -1004 +1005 @@
 
 void MainServer::HandleQueryRecordings(QString type, PlaybackSock *pbs)
 {
+    VERBOSE(VB_IMPORTANT, "HandleQueryRecordings("<<type<<") -- begin");
     MythSocket *pbssock = pbs->getSocket();
     bool islocal = pbs->isLocal();
     QString playbackhost = pbs->getHostname();
@@ -1231 +1233 @@
                     proginfo->pathname = QString("myth://") + ip + ":" + port
                                          + "/" + basename;
 
+                struct timeval stat_beg, stat_end;
+                gettimeofday(&stat_beg, NULL);
                 if (proginfo->filesize == 0)
                 {
                     struct stat st;
@@ -1243 +1247 @@
 
                     proginfo->SetFilesize(size);
                 }
+                gettimeofday(&stat_end, NULL);
+
+                double stat_time = (stat_end.tv_sec - stat_beg.tv_sec ) +
+                    (stat_end.tv_usec - stat_beg.tv_usec) / 1000000.0;
+                if (stat_time > 1.0)
+                {
+                    VERBOSE(VB_IMPORTANT, QString(
+                                "stat took %1 seconds on file '%2'"
+                                "\n\t\t\ttitle: '%3' \nsubtitle: '%4' with avail '%5'")
+                            .arg(stat_time)
+                            .arg(proginfo->pathname).arg(proginfo->title)
+                            .arg(proginfo->subtitle).arg(
+                                (proginfo->availableStatus==asAvailable) ?
+                                "available" :
+                                ((proginfo->availableStatus==asPendingDelete) ?
+                                 "deleting" :
+                                 ((proginfo->availableStatus==asFileNotFound) ?
+                                  "file not found" : "unnknown"))));
+                }
             }
             else
             {