locked
WFP - HCK Support power managed states failing RRS feed

  • Question

  • Hi,

    I'm running HCK certification test on our WFP driver and all tests were passing just fine, however recently "Power managed states" test on Win 7 x64 started failing, even with the same driver version that it passed before. Looking at the log I can see that WFPLogo.exe is using some garbage for total length, source and destination addresses: please see the below log, specifically the following repeated lines: 

    [totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 

    In previous tests those values were normal - it used to look like:

     [totalLength: 1028][sourceAddress: 0x10000fe][destinationAddress: 0x1000001]

    Absolutely nothing has been changed on the test machine, so wondering what could affect WFPLogo.exe? Any help is appreciated. Please see below more info from the log:

    _________________________________

    Parent:   ArchitecturalDesign\SupportPowerManagedStates 
    Start Test 8/6/2018 5:58:58.441 PM +SUB_VAR+ 1 : [IPVersion: IPv4][Direction: Outbound][Protocol: TCP (6)][Source / Local Address: 1.0.0.1][Destination / Remote Address: 1.0.0.254][Source / Local Port: 42135][Destination / Remote Port: 48008][Action: PERMIT] PowerStates [status:0][IPv4][Outbound][From: 1.0.0.1][To: 1.0.0.254][PERMIT] 
    End Test 8/6/2018 5:58:58.441 PM +SUB_VAR+ 1 : [IPVersion: IPv4][Direction: Outbound][Protocol: TCP (6)][Source / Local Address: 1.0.0.1][Destination / Remote Address: 1.0.0.254][Source / Local Port: 42135][Destination / Remote Port: 48008][Action: PERMIT] PowerStates [status:0][IPv4][Outbound][From: 1.0.0.1][To: 1.0.0.254][PERMIT] 
    Result:   Pass 
    Message 8/6/2018 5:58:58.441 PM Script Run: cmd.exe /C " %WinDir%\System32\NetSh.exe AdvFirewall Firewall Delete Rule Name="WFPLogo" Dir=Out Program=%WinDir%\System32\WFPLogo.Exe LocalIP=1.0.0.1 RemoteIP=1.0.0.254 Protocol=6 Profile=Any" 
    Message 8/6/2018 5:59:13.441 PM Script Run: cmd.exe /C " %WinDir%\System32\NetSh.exe AdvFirewall Firewall Add Rule Name="WFPLogo" Description="Permit Inbound IPv4 with Power States" Dir=In Action=allow Program=%WinDir%\System32\WFPLogo.Exe LocalIP=1.0.0.1 RemoteIP=1.0.0.254 Protocol=17 Enable=Yes Profile=Any" 
    Message 8/6/2018 5:59:28.441 PM +VAR+INFO+ 1 : [IPVersion: IPv4][Direction: Inbound][Protocol: Raw UDP (17)][Source / Remote Address: 1.0.0.254][Destination / Local Address: 1.0.0.1][Source / Remote Port: 41164][Destination / Local Port: 45636][Action: PERMIT] 
    Message 8/6/2018 6:01:46.441 PM LocalRawUDP::SocketBind : bind() [Local Bound Address: 1.0.0.1][Local Bound Port: 0xb244] [status: 0] 
    Message 8/6/2018 6:01:46.441 PM LocalRawUDP::SocketSetToNonBlocking : ioctlsocket() [status: 0] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xc0a80aff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 44032][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 172][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 11264][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 44][isExpected: FALSE] 
    Message 8/6/2018 6:01:49.441 PM [IPv4][ipProtocol: 17][ipHeaderLength: 20][totalLength: 11264][sourceAddress: 0xc0a80a43][destinationAddress: 0xffffffff] 
    Message 8/6/2018 6:01:49.441 PM IsExpectedDataBuffer() [pBuffer: 0X000000000024C9A0][size: 44][isExpected: FALSE] 
    Message 8/6/2018 6:01:51.441 PM LocalRawUDP::SocketRecv : recv() [bytes received: 0] [status: 0x274c] 
    Message 8/6/2018 6:01:51.441 PM LocalRawUDP::SocketSetToNonBlocking : ioctlsocket() [status: 0] 
    Message 8/6/2018 6:01:51.441 PM AnalyzeTrafficResults() [Analysis: Blocked][local Error: 0x274c][peer Error: 0][packet(s) Rx'd: No][packet(s) Tx'd: Yes] 
    Message 8/6/2018 6:01:51.441 PM LocalRawUDP::SocketClose : closesocket() [status: 0] 
    Context _  _  
    Context Index:   391104053 
    Current:   +SUB_VAR+ 1 : [IPVersion: IPv4][Direction: Inbound][Protocol: Raw UDP (17)][Source / Remote Address: 1.0.0.254][Destination / Local Address: 1.0.0.1][Source / Remote Port: 41164][Destination / Local Port: 45636][Action: PERMIT] PowerStates [status: 0][IPv4][Inbound][From: 1.0.0.254][To: 1.0.0.1][PERMIT] 
    Parent:   ArchitecturalDesign\SupportPowerManagedStates 
    Start Test 8/6/2018 6:02:56.441 PM +SUB_VAR+ 1 : [IPVersion: IPv4][Direction: Inbound][Protocol: Raw UDP (17)][Source / Remote Address: 1.0.0.254][Destination / Local Address: 1.0.0.1][Source / Remote Port: 41164][Destination / Local Port: 45636][Action: PERMIT] PowerStates [status: 0][IPv4][Inbound][From: 1.0.0.254][To: 1.0.0.1][PERMIT] 
    End Test 8/6/2018 6:02:56.441 PM +SUB_VAR+ 1 : [IPVersion: IPv4][Direction: Inbound][Protocol: Raw UDP (17)][Source / Remote Address: 1.0.0.254][Destination / Local Address: 1.0.0.1][Source / Remote Port: 41164][Destination / Local Port: 45636][Action: PERMIT] PowerStates [status: 0][IPv4][Inbound][From: 1.0.0.254][To: 1.0.0.1][PERMIT] 
    Result:   Fail 



    • Edited by 3dlev Tuesday, August 7, 2018 4:51 AM
    Tuesday, August 7, 2018 4:50 AM