Changeset 91198 in vbox

Timestamp:

Sep 10, 2021 12:55:54 PM (3 years ago)

Author:

vboxsync

Message:

Audio/Validation Kit: Some more diagnostics to find out why some testbox guests refuse to play any test tones. ​bugref:10008

Location:

trunk/src/VBox

Files:

• Devices/Audio/DrvHostAudioValidationKit.cpp (modified) (2 diffs)
• ValidationKit/utils/audio/vkatCommon.cpp (modified) (2 diffs)

trunk/src/VBox/Devices/Audio/DrvHostAudioValidationKit.cpp

@@ -781 +781 @@
         {
             pTst->msStartedTS = RTTimeMilliTS();
-            LogRel(("ValKit: Injecting audio input data (%RU16Hz, %RU32ms, %RU32 bytes) started (delay is %RU32ms)\n",
+            LogRel(("ValKit: Injecting audio input data (%RU16Hz, %RU32ms, %RU32 bytes) for test #%RU32 started (delay is %RU32ms)\n",
                     (uint16_t)pTst->t.TestTone.Tone.rdFreqHz,
                     pTst->t.TestTone.Parms.msDuration, pTst->t.TestTone.u.Rec.cbToWrite,
-                    RTTimeMilliTS() - pTst->msRegisteredTS));
+                    Parms.TestTone.Hdr.idxSeq, RTTimeMilliTS() - pTst->msRegisteredTS));
+
+            char szTimeCreated[RTTIME_STR_LEN];
+            RTTimeToString(&Parms.TestTone.Hdr.tsCreated, szTimeCreated, sizeof(szTimeCreated));
+            LogRel(("ValKit: Test created (caller UTC): %s\n", szTimeCreated));
         }
 
@@ -921 +925 @@
         {
             pTst->msStartedTS = RTTimeMilliTS();
-            LogRel(("ValKit: Test #%RU32: Recording audio data (%RU16Hz, %RU32ms) started (delay is %RU32ms)\n",
+            LogRel(("ValKit: Test #%RU32: Recording audio data (%RU16Hz, %RU32ms) for test #%RU32 started (delay is %RU32ms)\n",
                     pTst->idxTest, (uint16_t)Parms.TestTone.dbFreqHz, Parms.TestTone.msDuration,
-                    RTTimeMilliTS() - pTst->msRegisteredTS));
+                    Parms.TestTone.Hdr.idxSeq, RTTimeMilliTS() - pTst->msRegisteredTS));
+
+            char szTimeCreated[RTTIME_STR_LEN];
+            RTTimeToString(&Parms.TestTone.Hdr.tsCreated, szTimeCreated, sizeof(szTimeCreated));
+            LogRel(("ValKit: Test created (caller UTC): %s\n", szTimeCreated));
         }
     }

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

@@ -606 +606 @@
     char szTimeCreated[RTTIME_STR_LEN];
     RTTimeToString(&pToneParms->Hdr.tsCreated, szTimeCreated, sizeof(szTimeCreated));
-    RTTestPrintf(g_hTest, RTTESTLVL_ALWAYS, "Created (caller UTC): %s\n", szTimeCreated);
+    RTTestPrintf(g_hTest, RTTESTLVL_ALWAYS, "Test created (caller UTC): %s\n", szTimeCreated);
 
     const PAUDIOTESTSTREAM pTstStream = &pTstEnv->aStreams[0]; /** @todo Make this dynamic. */
@@ -654 +654 @@
     char szTimeCreated[RTTIME_STR_LEN];
     RTTimeToString(&pToneParms->Hdr.tsCreated, szTimeCreated, sizeof(szTimeCreated));
-    RTTestPrintf(g_hTest, RTTESTLVL_ALWAYS, "Created (caller UTC): %s\n", szTimeCreated);
+    RTTestPrintf(g_hTest, RTTESTLVL_ALWAYS, "Test created (caller UTC): %s\n", szTimeCreated);
 
     const PAUDIOTESTSTREAM pTstStream = &pTstEnv->aStreams[0]; /** @todo Make this dynamic. */