Hi,
I have a Windows 10 VM (VirtualBox) I want to use for testing user mode drivers.
When I try to provision the VM according to: https://docs.microsoft.com/en-us/windows-hardware/drivers/gettingstarted/provision-a-target-computer-wdk-8-1 I get the following error in the "Configuration Process":
Installing driver test framework
ERROR: Task "Installing driver test framework" failed to complete successfully. Look at the logs in the driver test group explorer for more details on the failure.
I tried to install the corresponding msi in the VM manually, but this seems to be aborted without error message.
When I ignore this and try to run the virtual serial example from https://github.com/microsoft/Windows-driver-samples/tree/master/serial/VirtualSerial I get an error message that "DbgengRemoteCommand" is missing.
Since the driver compiled successfully I also tried to install it on the host machine via right click -> install on the inf file. However I just get an error message that there has been an error when adding the driver to the storage (I have no idea what this could mean, unfortunately no other information is provided).
My goal is to develop a user mode driver to forward traffic between a virtual serial port and a named pipe. However I am unable to even run the examples. Please help.
Heres the log of the unsuccessful provisioning:
Install Driver Test Components: Host Architecture: x86
Install Driver Test Components: Host 64bit Operating System: True
Install Driver Test Components: Host Operating System Version: 10.0.19042.0
Install Driver Test Components: Process Administrator Privilege: False
Progress event: Current: 1, Max: 15, Message: ""
Progress event: Current: 1, Max: 15, Message: "Copying required files"
[12:08:03:006]: Copying required files
Task result status updated: Pass
[12:08:35:376]: Task "Copying required files" completed successfully
Progress event: Current: 1, Max: 15, Message: " Task "Copying required files" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 2, Max: 15, Message: "Configuring WDK Remote User Account"
[12:08:35:589]: Configuring WDK Remote User Account
Task result status updated: Pass
[12:08:35:644]: Task "Configuring WDK Remote User Account" completed successfully
Progress event: Current: 2, Max: 15, Message: " Task "Configuring WDK Remote User Account" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 3, Max: 15, Message: "Installing .NET Framework (possible reboot)"
[12:08:35:859]: Installing .NET Framework (possible reboot)
[12:08:35:859]: [Installing .NET Framework (possible reboot)] Command Line:
%SystemDrive%\DriverTest\Setup\dotNetFx40_Full_x86_x64.exe /q /norestart /log %SystemDrive%\DriverTest\Logs\DotNetInstall.log
Result file "%SystemDrive%\DriverTest\Logs\DotNetInstall*.log" could not be retrieved from the target machine. Error: An error occurred on the target machine while retrieving a file.
Error code: 123
Error message: Unable to open file 'C:\DriverTest\Logs\DotNetInstall*.log'.
Result file "%SystemDrive%\DriverTest\Logs\DotNetInstall*.html" could not be retrieved from the target machine. Error: An error occurred on the target machine while retrieving a file.
Error code: 123
Error message: Unable to open file 'C:\DriverTest\Logs\DotNetInstall*.html'.
Task result status updated: Pass
Task result status updated: Pass
[12:08:55:149]: Task "Installing .NET Framework (possible reboot)" completed successfully
Progress event: Current: 3, Max: 15, Message: " Task "Installing .NET Framework (possible reboot)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 4, Max: 15, Message: "Installing VC Redist (x64)"
[12:08:55:363]: Installing VC Redist (x64)
[12:08:55:364]: [Installing VC Redist (x64)] Command Line:
%SystemDrive%\DriverTest\Setup\x64\vcredist_x64.exe /q /log %SystemDrive%\DriverTest\Logs\vcredist_x64.log
Task result status updated: Pass
Task result status updated: Pass
[12:08:58:512]: Task "Installing VC Redist (x64)" completed successfully
Progress event: Current: 4, Max: 15, Message: " Task "Installing VC Redist (x64)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 5, Max: 15, Message: "Installing test automation (x86)"
[12:08:58:727]: Installing test automation (x86)
[12:08:58:727]: [Installing test automation (x86)] Command Line:
msiexec.exe /i "%SystemDrive%\DriverTest\Setup\Test Authoring and Execution Framework x86-x86_en-us.msi" /qb- KITSROOT="%ProgramFiles%\Windows Kits\10" KITTARGET=1 /l*v "%SystemDrive%\DriverTest\Logs\Test Authoring and Execution Framework x86-x86_en-us.msi_install.log"
Task result status updated: Pass
Task result status updated: Pass
[12:09:00:596]: Task "Installing test automation (x86)" completed successfully
Progress event: Current: 5, Max: 15, Message: " Task "Installing test automation (x86)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 6, Max: 15, Message: "Installing test automation (x64)"
[12:09:00:821]: Installing test automation (x64)
[12:09:00:821]: [Installing test automation (x64)] Command Line:
msiexec.exe /i "%SystemDrive%\DriverTest\Setup\Test Authoring and Execution Framework x64-x64_en-us.msi" /qb- KITSROOT="%ProgramFiles(x86)%\Windows Kits\10" KITTARGET=1 /l*v "%SystemDrive%\DriverTest\Logs\Test Authoring and Execution Framework x64-x64_en-us.msi_install.log"
Task result status updated: Pass
Task result status updated: Pass
[12:09:02:317]: Task "Installing test automation (x64)" completed successfully
Progress event: Current: 6, Max: 15, Message: " Task "Installing test automation (x64)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 7, Max: 15, Message: "Installing debuggers (x86)"
[12:09:02:535]: Installing debuggers (x86)
[12:09:02:535]: [Installing debuggers (x86)] Command Line:
msiexec.exe /i "%SystemDrive%\DriverTest\Setup\X86 Debuggers And Tools-x86_en-us.msi" /qb- /l*v "%SystemDrive%\DriverTest\Logs\X86 Debuggers And Tools-x86_en-us.msi_install.log"
Task result status updated: Pass
Task result status updated: Pass
[12:09:04:905]: Task "Installing debuggers (x86)" completed successfully
Progress event: Current: 7, Max: 15, Message: " Task "Installing debuggers (x86)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 8, Max: 15, Message: "Installing debuggers (x64)"
[12:09:05:122]: Installing debuggers (x64)
[12:09:05:123]: [Installing debuggers (x64)] Command Line:
msiexec.exe /i "%SystemDrive%\DriverTest\Setup\x64 Debuggers And Tools-x64_en-us.msi" /qb- /l*v "%SystemDrive%\DriverTest\Logs\x64 Debuggers And Tools-x64_en-us.msi_install.log"
Task result status updated: Pass
Task result status updated: Pass
[12:09:08:183]: Task "Installing debuggers (x64)" completed successfully
Progress event: Current: 8, Max: 15, Message: " Task "Installing debuggers (x64)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 9, Max: 15, Message: "Installing driver test framework"
[12:09:08:407]: Installing driver test framework
[12:09:08:407]: [Installing driver test framework] Command Line:
msiexec.exe /i "%SystemDrive%\DriverTest\Setup\Windows Driver Testing Framework (WDTF) Runtime Libraries-x64_en-us.msi" /qb- KITTARGET=1 /l*v "%SystemDrive%\DriverTest\Logs\Windows Driver Testing Framework (WDTF) Runtime Libraries-x64_en-us.msi_install.log"
Task result status updated: Fail
Task result status updated: Fail
[12:09:24:829]: ERROR: Task "Installing driver test framework" failed to complete successfully. Look at the logs in the driver test group explorer for more details on the failure.
Progress event: Current: 9, Max: 15, Message: " ERROR: Task "Installing driver test framework" failed to complete successfully. Look at the logs in the driver test group explorer for more details on the failure."
Result completed
Test process exit code: 1603
Progress event: Current: 10, Max: 15, Message: "Registering logging components"
[12:09:25:047]: Registering logging components
[12:09:25:047]: [Registering logging components] Command Line:
regsvr32.exe /s %SystemDrive%\DriverTest\WTTLogcm.dll
Task result status updated: Pass
Task result status updated: Pass
[12:09:25:323]: Task "Registering logging components" completed successfully
Progress event: Current: 10, Max: 15, Message: " Task "Registering logging components" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 11, Max: 15, Message: "Installing TAEF service"
[12:09:25:534]: Installing TAEF service
[12:09:25:535]: [Installing TAEF service] Command Line:
C:\Program Files (x86)\Windows Kits\10\Testing\Runtimes\TAEF\X64\Wex.Services.exe /install:Te.Service
Task result status updated: Pass
Task result status updated: Pass
[12:09:26:559]: Task "Installing TAEF service" completed successfully
Progress event: Current: 11, Max: 15, Message: " Task "Installing TAEF service" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 12, Max: 15, Message: "Configuring TAEF test service to start automatically"
[12:09:26:769]: Configuring TAEF test service to start automatically
[12:09:26:769]: [Configuring TAEF test service to start automatically] Command Line:
sc config Te.Service start= auto
Task result status updated: Pass
Task result status updated: Pass
[12:09:27:741]: Task "Configuring TAEF test service to start automatically" completed successfully
Progress event: Current: 12, Max: 15, Message: " Task "Configuring TAEF test service to start automatically" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 13, Max: 15, Message: "Configuring kernel debugger settings (possible reboot)"
[12:09:27:958]: Configuring kernel debugger settings (possible reboot)
[12:09:27:958]: Removing any existing files from test execution folder.
[12:09:27:991]: Copying required files for "Configuring kernel debugger settings (possible reboot)".
[12:09:29:165]: [Configuring kernel debugger settings (possible reboot)] Command Line:
$KitRoot$\Testing\Runtimes\TAEF\te.exe "%SystemDrive%\DriverTest\Run\DriverTestTasks.dll" /select:"@Name='DriverTestTasks::ConfigureKernelDebugger'" /p:"DebugTransport=NET" /p:"NetHost=192.168.56.1" /p:"NetPort=50438" /p:"NetKey=BLRDXQ3GPWTU.97EFXXI7MZPJ.XWECTCIKE4C.W5NV3LCS6IKE" /p:"StartPolicy=Active" /p:"IgnoreUserModeExceptions=0" /p:"SuppressReboot=1" /rebootStateFile:%SystemDrive%\DriverTest\Run\DriverTestReboot.xml /enableWttLogging /wttDeviceString:$LogFile:file="%SystemDrive%\DriverTest\Run\Configuring_kernel_debugger_settings(possible_reboot)00007.wtl",writemode=append,encoding=unicode,nofscache=true,EnableLvl="WexStartTest|WexEndTest|WexXml|WexProperty|WexCreateContext|WexCloseContext|*" /runas:Elevated
Result file "%SystemDrive%\DriverTest\Run\Configuring_kernel_debugger_settings(possible_reboot)00007.wtl.trace" could not be retrieved from the target machine. Error: An error occurred on the target machine while retrieving a file.
Error code: 2
Error message: Unable to open file 'C:\DriverTest\Run\Configuring_kernel_debugger_settings(possible_reboot)_00007.wtl.trace'.
[12:09:31:713]: Result Summary: Total=1, Passed=1, Failed=0, Blocked=0, Warned=0, Skipped=0
Task result status updated: Pass
Task result status updated: Pass
[12:09:31:714]: Task "Configuring kernel debugger settings (possible reboot)" completed successfully
Progress event: Current: 13, Max: 15, Message: " Task "Configuring kernel debugger settings (possible reboot)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 14, Max: 15, Message: "Configuring computer settings (possible reboot)"
[12:09:32:748]: Configuring computer settings (possible reboot)
[12:09:32:748]: Removing any existing files from test execution folder.
[12:09:32:765]: Copying required files for "Configuring computer settings (possible reboot)".
[12:09:33:878]: [Configuring computer settings (possible reboot)] Command Line:
$KitRoot$\Testing\Runtimes\TAEF\te.exe "%SystemDrive%\DriverTest\Run\DriverTestTasks.dll" /select:"@Name='DriverTestTasks::ConfigureCommonSettings'" /p:"OsSkuType=0" /rebootStateFile:%SystemDrive%\DriverTest\Run\DriverTestReboot.xml /enableWttLogging /wttDeviceString:$LogFile:file="%SystemDrive%\DriverTest\Run\Configuring_computer_settings(possible_reboot)00007.wtl",writemode=append,encoding=unicode,nofscache=true,EnableLvl="WexStartTest|WexEndTest|WexXml|WexProperty|WexCreateContext|WexCloseContext|*" /runas:Elevated
Result file "%SystemDrive%\DriverTest\Run\Configuring_computer_settings(possible_reboot)00007.wtl.trace" could not be retrieved from the target machine. Error: An error occurred on the target machine while retrieving a file.
Error code: 2
Error message: Unable to open file 'C:\DriverTest\Run\Configuring_computer_settings(possible_reboot)_00007.wtl.trace'.
[12:09:38:202]: Result Summary: Total=1, Passed=1, Failed=0, Blocked=0, Warned=0, Skipped=0
Task result status updated: Pass
Task result status updated: Pass
[12:09:38:203]: Task "Configuring computer settings (possible reboot)" completed successfully
Progress event: Current: 14, Max: 15, Message: " Task "Configuring computer settings (possible reboot)" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 15, Max: 15, Message: "Creating system restore point"
[12:09:39:356]: Creating system restore point
[12:09:39:356]: [Creating system restore point] Command Line:
cscript.exe %SystemDrive%\DriverTest\Setup\CreateRestore.js
Task result status updated: Pass
Task result status updated: Pass
[12:09:41:953]: Task "Creating system restore point" completed successfully
Progress event: Current: 15, Max: 15, Message: " Task "Creating system restore point" completed successfully"
Result completed
Test process exit code: 0
Progress event: Current: 15, Max: 15, Message: ""
Progress event: Current: 15, Max: 15, Message: "Computer configuration log file://C:/Users/wwendnagel/AppData/Roaming/Microsoft/WDKTestInfrastructure/ProvisioningLogs/Driver%20Test%20Computer%20Configuration%2020210507120802733.log"