Changeset 91826 in vbox for trunk/src/VBox/ValidationKit/utils

Timestamp:

Oct 18, 2021 1:45:07 PM (4 years ago)

Author:

vboxsync

svn:sync-xref-src-repo-rev:

147614

Message:

Audio/Validation Kit: Log last successful writes to backends. ​bugref:10008

File:

• trunk/src/VBox/ValidationKit/utils/audio/vkatCommon.cpp (modified) (3 diffs)

trunk/src/VBox/ValidationKit/utils/audio/vkatCommon.cpp

@@ -557 +557 @@
         uint64_t        nsTimeout          = RT_MS_5MIN_64 * RT_NS_1MS;
         uint64_t        nsLastMsgCantWrite = 0; /* Timestamp (in ns) when the last message of an unwritable stream was shown. */
+        uint64_t        nsLastWrite        = 0;
 
         while (cbPlayedTotal < cbToPlayTotal)
         {
             uint64_t const nsNow = RTTimeNanoTS();
+            if (!nsLastWrite)
+                nsLastWrite = nsNow;
 
             /* Pace ourselves a little. */
@@ -641 +644 @@
                             if (cbPlayed != cbToPlay)
                                 RTTestFailed(g_hTest, "Only played %RU32/%RU32 bytes", cbPlayed, cbToPlay);
+
+                            if (cbPlayed)
+                                nsLastWrite = nsNow;
                         }
                     }
@@ -654 +660 @@
                 RTMSINTERVAL const msSleep = RT_MIN(RT_MAX(1, pStream->Cfg.Device.cMsSchedulingHint), 256);
 
-                if (!nsLastMsgCantWrite || nsNow - nsLastMsgCantWrite > RT_NS_10SEC) /* Don't spam the output too much. */
+                if (   !nsLastMsgCantWrite
+                    || nsNow - nsLastMsgCantWrite > RT_NS_10SEC) /* Don't spam the output too much. */
                 {
-                    RTTestPrintf(g_hTest, RTTESTLVL_ALWAYS, "Waiting %RU32ms for stream to be writable again ...\n", msSleep);
+                    RTTestPrintf(g_hTest, RTTESTLVL_ALWAYS, "Waiting %RU32ms for stream to be writable again (last write %RU64ns ago) ...\n",
+                                 msSleep, nsNow - nsLastWrite);
                     nsLastMsgCantWrite = nsNow;
                 }