About FilePermission Test failures

[r-deshmukh]

Hello,

This is regarding following test cases of Protocol Test Manager - FileServer 3.20.1.0 :

1.Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.BVT_FilePermission_AccessAllow_UserSid
2.Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.FilePermission_AccessAllow_GroupSid

Both above test cases are failing when run from client (Windows Server 2019) and server (Windows Server 2022)

The logs for the failing test cases are as follows:

======================== Logs Start ====================
24:17.410 [Comment] Expected execution time of the test suite (in seconds) is:
2024-06-19 11:24:17.505 [EnterMethod] Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.BVT_FilePermission_AccessAllow_UserSid
2024-06-19 11:24:17.521    [Comment] This test case is designed to test whether a user can read a file when ACCESS_ALLOWED_ACE with user SID exists in file Security Descriptor.
2024-06-19 11:24:17.568    [Debug] Power Shell adapter: ISutProtocolControlAdapter, method: Initialize not found, skipped.
2024-06-19 11:24:17.568    [Debug] Test whether the specific share exists: \10.1.1.191\share
2024-06-19 11:24:17.896    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:17.896    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:17.896    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:18.005    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:18.036    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:18.318    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:18.349    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:18.349    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:18.349    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:18.349    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:18.496    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:18.516    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:18.521    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:18.521    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146481
2024-06-19 11:24:18.552    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:18.552    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:18.552    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:18.552    [Debug] Share exists: \10.1.1.191\share
2024-06-19 11:24:18.552    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:18.577    [Debug] R TREE DISCONNECT
2024-06-19 11:24:18.583    [Debug] C LOGOFF, SessionId=83563152146481
2024-06-19 11:24:18.599    [Debug] R LOGOFF
2024-06-19 11:24:18.664    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:18.664    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:18.664    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:18.664    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:18.664    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:18.664    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:18.677    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:18.677    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:18.677    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:18.677    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:18.677    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:18.677    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:18.708    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:18.708    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146493
2024-06-19 11:24:18.708    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:18.708    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:18.708    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:18.724    [Debug] C CREATE, File=BVT_FilePermission_AccessAllow_UserSid_12e9cb8a-86bc-43da-b73a-dc7bd2694125, DesiredAccess=FILE_LIST_DIRECTORY, FILE_ADD_FILE, FILE_ADD_SUBDIRECTORY, FILE_READ_EA, FILE_WRITE_EA, FILE_READ_ATTRIBUTES, FILE_WRITE_ATTRIBUTES, READ_CONTROL, WRITE_DAC, SYNCHRONIZE, ShareAccess=(None)
2024-06-19 11:24:18.724    [Debug] R CREATE, FileId={ Persistent: 0x0000001300000561, Volatile: 0x0000001300000001 }
2024-06-19 11:24:18.724    [Debug] C CLOSE, FileId={ Persistent: 0x0000001300000561, Volatile: 0x0000001300000001 }
2024-06-19 11:24:18.740    [Debug] R CLOSE
2024-06-19 11:24:18.740    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:18.755    [Debug] R TREE DISCONNECT
2024-06-19 11:24:18.755    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:18.755    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:18.755    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:18.755    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:18.755    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:18.755    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:18.755    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:18.755    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:18.771    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:18.771    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:18.771    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:18.771    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:18.786    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:18.802    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146497
2024-06-19 11:24:18.802    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:18.802    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:18.802    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:18.802    [Debug] C CREATE, File=BVT_FilePermission_AccessAllow_UserSid_12e9cb8a-86bc-43da-b73a-dc7bd2694125, DesiredAccess=FILE_READ_ATTRIBUTES, READ_CONTROL, WRITE_DAC, ShareAccess=(RWD)
2024-06-19 11:24:18.818    [Debug] R CREATE, FileId={ Persistent: 0x0000001300000562, Volatile: 0x0000001300000001 }
2024-06-19 11:24:18.818    [Debug] C QUERY INFO, FileId={ Persistent: 0x0000001300000562, Volatile: 0x0000001300000001 }, InfoType=SMB2_0_INFO_SECURITY
2024-06-19 11:24:18.818    [Debug] R QUERY INFO
2024-06-19 11:24:18.881    [Debug] C CLOSE, FileId={ Persistent: 0x0000001300000562, Volatile: 0x0000001300000001 }
2024-06-19 11:24:18.900    [Debug] R CLOSE
2024-06-19 11:24:18.900    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:18.900    [Debug] R TREE DISCONNECT
2024-06-19 11:24:18.900    [Debug] C LOGOFF, SessionId=83563152146497
2024-06-19 11:24:18.911    [Debug] R LOGOFF
2024-06-19 11:24:18.943    [TestStep] Original Security Descriptor of the file (\10.1.1.191\share\BVT_FilePermission_AccessAllow_UserSid_12e9cb8a-86bc-43da-b73a-dc7bd2694125): O:BAG:S-1-5-21-205327941-1678213932-2934174113-513D:(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-1154)(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-512)(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-513)(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-514)
2024-06-19 11:24:18.943    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:18.943    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:18.943    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:19.474    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:19.474    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:19.474    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:19.474    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:19.474    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:19.474    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:19.505    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:19.505    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:19.505    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:19.505    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:19.505    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:19.505    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:19.505    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:19.521    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146501
2024-06-19 11:24:19.521    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:19.521    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:19.521    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:19.521    [Debug] C CREATE, File=BVT_FilePermission_AccessAllow_UserSid_12e9cb8a-86bc-43da-b73a-dc7bd2694125, DesiredAccess=FILE_READ_ATTRIBUTES, DELETE, ShareAccess=(D)
2024-06-19 11:24:19.536    [Debug] R CREATE, FileId={ Persistent: 0x0000001300000563, Volatile: 0x0000001300000001 }
2024-06-19 11:24:19.536    [Debug] C CLOSE, FileId={ Persistent: 0x0000001300000563, Volatile: 0x0000001300000001 }
2024-06-19 11:24:19.536    [Debug] R CLOSE
2024-06-19 11:24:19.536    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:19.552    [Debug] R TREE DISCONNECT
2024-06-19 11:24:22.459    [TestFailed] Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.BVT_FilePermission_AccessAllow_UserSid
2024-06-19 11:24:22.459 [ExitMethod] Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.BVT_FilePermission_AccessAllow_UserSid
2024-06-19 11:24:22.646 [EnterMethod] Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.FilePermission_AccessAllow_GroupSid
2024-06-19 11:24:22.646    [Comment] This test case is designed to test whether a user can read a file when ACCESS_ALLOWED_ACE with user's group SID exists in file Security Descriptor.
2024-06-19 11:24:22.646    [Debug] Test whether the specific share exists: \10.1.1.191\share
2024-06-19 11:24:22.646    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:22.646    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:22.646    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:22.646    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:22.662    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:22.662    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:22.662    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:22.662    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:22.662    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:22.662    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:22.677    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.677    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:22.677    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.677    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146505
2024-06-19 11:24:22.693    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:22.693    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:22.693    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:22.693    [Debug] Share exists: \10.1.1.191\share
2024-06-19 11:24:22.693    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:22.693    [Debug] R TREE DISCONNECT
2024-06-19 11:24:22.693    [Debug] C LOGOFF, SessionId=83563152146505
2024-06-19 11:24:22.709    [Debug] R LOGOFF
2024-06-19 11:24:22.709    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:22.709    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:22.709    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:22.709    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:22.709    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:22.724    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:22.724    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:22.724    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:22.724    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:22.724    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:22.724    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.724    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:22.740    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.740    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146513
2024-06-19 11:24:22.740    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:22.755    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:22.771    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:22.771    [Debug] C CREATE, File=FilePermission_AccessAllow_GroupSid_8d6aafb3-186a-407e-b3eb-96178b1191a9, DesiredAccess=FILE_LIST_DIRECTORY, FILE_ADD_FILE, FILE_ADD_SUBDIRECTORY, FILE_READ_EA, FILE_WRITE_EA, FILE_READ_ATTRIBUTES, FILE_WRITE_ATTRIBUTES, READ_CONTROL, WRITE_DAC, SYNCHRONIZE, ShareAccess=(None)
2024-06-19 11:24:22.771    [Debug] R CREATE, FileId={ Persistent: 0x0000001300000567, Volatile: 0x0000001300000001 }
2024-06-19 11:24:22.771    [Debug] C CLOSE, FileId={ Persistent: 0x0000001300000567, Volatile: 0x0000001300000001 }
2024-06-19 11:24:22.787    [Debug] R CLOSE
2024-06-19 11:24:22.787    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:22.802    [Debug] R TREE DISCONNECT
2024-06-19 11:24:22.802    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:22.802    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:22.802    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:22.802    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:22.802    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:22.802    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:22.818    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:22.818    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:22.818    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:22.818    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:22.818    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.818    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:22.818    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.834    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146517
2024-06-19 11:24:22.834    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:22.834    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:22.834    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:22.834    [Debug] C CREATE, File=FilePermission_AccessAllow_GroupSid_8d6aafb3-186a-407e-b3eb-96178b1191a9, DesiredAccess=FILE_READ_ATTRIBUTES, READ_CONTROL, WRITE_DAC, ShareAccess=(RWD)
2024-06-19 11:24:22.849    [Debug] R CREATE, FileId={ Persistent: 0x0000001300000568, Volatile: 0x0000001300000001 }
2024-06-19 11:24:22.849    [Debug] C QUERY INFO, FileId={ Persistent: 0x0000001300000568, Volatile: 0x0000001300000001 }, InfoType=SMB2_0_INFO_SECURITY
2024-06-19 11:24:22.849    [Debug] R QUERY INFO
2024-06-19 11:24:22.849    [Debug] C CLOSE, FileId={ Persistent: 0x0000001300000568, Volatile: 0x0000001300000001 }
2024-06-19 11:24:22.865    [Debug] R CLOSE
2024-06-19 11:24:22.865    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:22.865    [Debug] R TREE DISCONNECT
2024-06-19 11:24:22.902    [Debug] C LOGOFF, SessionId=83563152146517
2024-06-19 11:24:22.902    [Debug] R LOGOFF
2024-06-19 11:24:22.943    [TestStep] Original Security Descriptor of the file (\10.1.1.191\share\FilePermission_AccessAllow_GroupSid_8d6aafb3-186a-407e-b3eb-96178b1191a9): O:BAG:S-1-5-21-205327941-1678213932-2934174113-513D:(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-1154)(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-512)(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-513)(A;;FA;;;S-1-5-21-205327941-1678213932-2934174113-514)
2024-06-19 11:24:22.943    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:22.943    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:22.943    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:22.943    [CheckSucceeded] Assume.IsTrue succeeded. SutIPAddress should be a valid IP address or a resolvable host name!
2024-06-19 11:24:22.943    [CheckSucceeded] Assert.IsTrue succeeded. serverIp should not be empty when transport type is TCP.
2024-06-19 11:24:22.959    [Debug] Connect to server 10.1.1.191 over TCP
2024-06-19 11:24:22.959    [Debug] Client sends NEGOTIATE message.
2024-06-19 11:24:22.959    [Debug] C NEGOTIATE, Dialects={Smb2002,Smb21,Smb30,Smb302,Smb311}, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_PERSISTENT_HANDLES, GLOBAL_CAP_DIRECTORY_LEASING, GLOBAL_CAP_ENCRYPTION
2024-06-19 11:24:22.959    [Debug] R NEGOTIATE, Dialect=Smb311, HashAlgorithms={SHA_512}, Capabilities=GLOBAL_CAP_DFS, GLOBAL_CAP_LEASING, GLOBAL_CAP_LARGE_MTU, GLOBAL_CAP_MULTI_CHANNEL, GLOBAL_CAP_DIRECTORY_LEASING
2024-06-19 11:24:22.959    [CheckSucceeded] Assert.IsTrue succeeded. The server SHOULD<168> grant the client a non-zero value of credits in response to any non-zero value requested, within administratively configured limits. The server MUST grant the client at least 1 credit when responding to SMB2 NEGOTIATE, actually server returns 1
2024-06-19 11:24:22.959    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES.HashAlgorithmCount MUST be set to 1
2024-06-19 11:24:22.974    [CheckSucceeded] Assert.AreEqual succeeded. The response's SMB2_PREAUTH_INTEGRITY_CAPABILITIES Salt buffer length must same as SaltLength
2024-06-19 11:24:22.974    [Debug] Client sends SESSION_SETUP message using account: [email protected].
2024-06-19 11:24:22.974    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.974    [Debug] R SESSION_SETUP, ErrorCode=STATUS_MORE_PROCESSING_REQUIRED
2024-06-19 11:24:22.974    [Debug] C SESSION_SETUP, SecurityMode=NEGOTIATE_SIGNING_ENABLED, Capabilities=GLOBAL_CAP_DFS
2024-06-19 11:24:22.990    [Debug] R SESSION_SETUP, SessionFlags=NONE, SessionId=83563152146521
2024-06-19 11:24:22.990    [Debug] Client sends TREE_CONNECT message to access share: \10.1.1.191\share.
2024-06-19 11:24:22.990    [Debug] C TREE CONNECT, Path=\10.1.1.191\share
2024-06-19 11:24:22.990    [Debug] R TREE CONNECT, TreeId=0x1
2024-06-19 11:24:23.010    [Debug] C CREATE, File=FilePermission_AccessAllow_GroupSid_8d6aafb3-186a-407e-b3eb-96178b1191a9, DesiredAccess=FILE_READ_ATTRIBUTES, DELETE, ShareAccess=(D)
2024-06-19 11:24:23.010    [Debug] R CREATE, FileId={ Persistent: 0x0000001300000569, Volatile: 0x0000001300000001 }
2024-06-19 11:24:23.010    [Debug] C CLOSE, FileId={ Persistent: 0x0000001300000569, Volatile: 0x0000001300000001 }
2024-06-19 11:24:23.021    [Debug] R CLOSE
2024-06-19 11:24:23.021    [Debug] C TREE DISCONNECT, TreeId=0x1
2024-06-19 11:24:23.021    [Debug] R TREE DISCONNECT
2024-06-19 11:24:23.380    [TestFailed] Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.FilePermission_AccessAllow_GroupSid
2024-06-19 11:24:23.380 [ExitMethod] Microsoft.Protocols.TestSuites.FileSharing.Auth.TestSuite.FilePermissionTest.FilePermission_AccessAllow_GroupSid
2024-06-19 11:24:23.787 [Comment] Actual time taken for the test suite execution (in seconds) is: 14.0848259
2024-06-19 11:24:23.896 [Comment] begin output test statistics.
2024-06-19 11:24:23.896 [Comment] PTFTestResult.TestsExecuted: 2
2024-06-19 11:24:23.896 [Comment] PTFTestResult.TestsFailed: 2
======================== Logs End =====================

From the logs it is not clear why test cases failed Or there is no clear statement where it is mentioned
what is expected and what it got.

Looking for help/thoughts/suggestions (to debug this more?) to understand why those tests are failing.
Please let me know if more information is needed. Thanks,