Changeset 2857 in vbox for trunk/src/VBox

Timestamp:

May 24, 2007 8:00:42 PM (18 years ago)

Author:

vboxsync

Message:

More logging.

Location:

trunk/src/VBox/VMM

Files:

• TM.cpp (modified) (3 diffs)
• TMInternal.h (modified) (1 diff)
• VMMAll/TMAllVirtual.cpp (modified) (1 diff)

trunk/src/VBox/VMM/TM.cpp

@@ -1451 +1451 @@
             "          offVirtualSyncGivenUp=%016RX64\n"
             "      u64VirtualSyncCatchUpPrev=%016RX64\n"
-            "        u64VirtualSyncStoppedTS=%016RX64\n"
+            "        u64VirtualSyncStoppedTS=%016RX64 %s by %#d (cur %#d)\n"
             "u32VirtualSyncCatchUpPercentage=%08RX32\n"
             "            fVirtualSyncTicking=%RTbool (prev=%RTbool)\n"
@@ -1466 +1466 @@
             pVM->tm.s.offVirtualSyncGivenUp,
             pVM->tm.s.u64VirtualSyncCatchUpPrev,
-            pVM->tm.s.u64VirtualSyncStoppedTS,
+            pVM->tm.s.u64VirtualSyncStoppedTS, pVM->tm.s.fVirtualSyncStoppedInGC ? "GC" : "R3", pVM->tm.s.u8VirtualSyncStoppedApicId, ASMGetApicId(),
             pVM->tm.s.u32VirtualSyncCatchUpPercentage,
             pVM->tm.s.fVirtualSyncTicking, fWasTicking, 
@@ -1634 +1634 @@
             {
                 /* don't bother */
-if (offLag & BIT64(63)) //debugging - remove.
-    LogRel(("TM: offLag is negative! offLag=%RI64 (%#RX64) offNew=%#RX64 u64Elapsed=%#RX64 offSlack=%#RX64 u64VirtualNow2=%#RX64 u64VirtualNow=%#RX64 u64VirtualSync=%#RX64 offVirtualSyncGivenUp=%#RX64 u64Now=%#RX64 u64Max=%#RX64\n", 
-            offLag, offLag, offNew, u64Elapsed, offSlack, u64VirtualNow2, u64VirtualNow, pVM->tm.s.u64VirtualSync, pVM->tm.s.offVirtualSyncGivenUp, u64Now, u64Max));
+//debugging - remove - start
+static unsigned s_cRelLogEntries = 0;
+if (offLag > _1P && s_cRelLogEntries++ < 128)
+    LogRel(("TM: offLag=%RI64 is way too large/negative! Please add this to #1414.\n"
+            "                 offLag=%016RX64\n"
+            "                 offNew=%016RX64\n"
+            "             u64Elapsed=%016RX64\n"
+            "               offSlack=%016RX64\n"
+            "         u64VirtualNow2=%016RX64\n"
+            "          u64VirtualNow=%016RX64\n"
+            "         u64VirtualSync=%016RX64\n"
+            "         offVirtualSync=%016RX64\n"
+            "  offVirtualSyncGivenUp=%016RX64\n"
+            "                 u64Now=%016RX64\n"
+            "                 u64Max=%016RX64\n" 
+            "u64VirtualSyncStoppedTS=%016RX64 %s by %#d (cur %#d)\n",
+            offLag,
+            offLag,
+            offNew,
+            u64Elapsed,
+            offSlack,
+            u64VirtualNow2,
+            u64VirtualNow,
+            pVM->tm.s.u64VirtualSync,
+            pVM->tm.s.offVirtualSync,
+            pVM->tm.s.offVirtualSyncGivenUp,
+            u64Now,
+            u64Max,
+            pVM->tm.s.u64VirtualSyncStoppedTS, pVM->tm.s.fVirtualSyncStoppedInGC ? "GC" : "R3", pVM->tm.s.u8VirtualSyncStoppedApicId, ASMGetApicId()));
+//debugging - remove - end
                 STAM_COUNTER_INC(&pVM->tm.s.StatVirtualSyncGiveUpBeforeStarting);
                 ASMAtomicXchgU64((uint64_t volatile *)&pVM->tm.s.offVirtualSyncGivenUp, offNew);

trunk/src/VBox/VMM/TMInternal.h

@@ -342 +342 @@
     uint64_t                    offVirtualSyncGivenUp;
 uint64_t volatile           u64VirtualSyncStoppedTS;/**< for debugging - remove later */
+bool volatile               fVirtualSyncStoppedInGC;/**< for debugging - remove later */
+uint8_t volatile            u8VirtualSyncStoppedApicId;/**< for debugging - remove later */
+uint8_t au8PaddingDbg[2];/**< for debugging - remove later */
+uint32_t u32PaddingDbg;/**< for debugging - remove later */
     /** The TMCLOCK_VIRTUAL at the previous TMVirtualGetSync call when catch-up is active. */
     uint64_t volatile           u64VirtualSyncCatchUpPrev;

trunk/src/VBox/VMM/VMMAll/TMAllVirtual.cpp

@@ -265 +265 @@
             ASMAtomicXchgU64(&pVM->tm.s.u64VirtualSync, u64);
             ASMAtomicXchgBool(&pVM->tm.s.fVirtualSyncTicking, false);
+//debugging - remove this later - start
 pVM->tm.s.u64VirtualSyncStoppedTS = u64VirtualNow;
+#ifdef IN_GC
+pVM->tm.s.fVirtualSyncStoppedInGC = true;
+#else
+pVM->tm.s.fVirtualSyncStoppedInGC = false;
+#endif 
+pVM->tm.s.u8VirtualSyncStoppedApicId = ASMGetApicId();
+//debugging - remove this later - end
             if (    fCheckTimers
                 &&  !VM_FF_ISSET(pVM, VM_FF_TIMER))