Hi Bill,
I tried also the memfs sample driver, and that resulted in same limitations, that the SQL Server can't see the drives.
C:\Program Files
(x86)\Microsoft Visual Studio\2019\Community>fsutil fsinfo
drives
Drives: C:\ D:\ E:\ X:\
C:\Program Files (x86)\Microsoft Visual
Studio\2019\Community>sqlcmd
1> exec xp_cmdshell 'fsutil fsinfo drives'
2> go
output
---------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
NULL
Drives: C:\ D:\
E:\
NULL
(3 rows affected)
1>
The debug log file from memfs:
C:\Program Files
(x86)\WinFsp\bin>memfs-x64.exe -d -1 -D - -m X: -u
\memfs\X
memfs -t -1 -n 1024 -s 16777216 -u \memfs\X -m X:
The service memfs has been started.
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\Desktop.ini", FILE_OPEN, CreateOptions=60, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=120089,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=c0000034[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\Desktop.ini", FILE_OPEN, CreateOptions=60, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=120089,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=c0000034[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\AutoRun.inf", FILE_OPEN, CreateOptions=200000,
FileAttributes=0, Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=80,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=c0000034[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=200000, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=80,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=0[1] UserContext=000001BECE108990, GrantedAccess=80,
FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0,
FileSize=0:0, CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=200000, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=80,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=0[1] UserContext=000001BECE108990, GrantedAccess=80,
FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0,
FileSize=0:0, CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=200000, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=80,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=0[1] UserContext=000001BECE108990, GrantedAccess=80,
FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0,
FileSize=0:0, CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=200000, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=80,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=0[1] UserContext=000001BECE108990, GrantedAccess=80,
FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0,
FileSize=0:0, CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE6987C60: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=200000, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=80,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE6987C60: <<Create
IoStatus=0[1] UserContext=000001BECE108990, GrantedAccess=80,
FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0,
FileSize=0:0, CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE6987C60: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=800021, FileAttributes=10,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=100000,
GrantedAccess=0, ShareAccess=0
memfs-x64[TID=1288]: FFFF9A8EE6987C60: <<Create
IoStatus=0[1] UserContext=000001BECE108990,
GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0,
AllocationSize=0:0, FileSize=0:0,
CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE6987C60:
>>QueryVolumeInformation
memfs-x64[TID=1288]: FFFF9A8EE6987C60:
<<QueryVolumeInformation IoStatus=0[0]
VolumeInfo={TotalSize=4:0, FreeSize=3:ff000000,
VolumeLabel="MEMFS"}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=800021, FileAttributes=10,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=100000,
GrantedAccess=0, ShareAccess=0
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=0[1] UserContext=000001BECE108990,
GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0,
AllocationSize=0:0, FileSize=0:0,
CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=200000, FileAttributes=0,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=80,
GrantedAccess=0, ShareAccess=7
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=0[1] UserContext=000001BECE108990, GrantedAccess=80,
FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0,
FileSize=0:0, CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
memfs-x64[TID=1288]: FFFF9A8EE7098940: >>Create [UT---C]
"\", FILE_OPEN, CreateOptions=21, FileAttributes=10,
Security=NULL, AllocationSize=0:0,
AccessToken=0000000000000198[PID=d48], DesiredAccess=100000,
GrantedAccess=0, ShareAccess=3
memfs-x64[TID=1288]: FFFF9A8EE7098940: <<Create
IoStatus=0[1] UserContext=000001BECE108990,
GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0,
AllocationSize=0:0, FileSize=0:0,
CreationTime=2021-02-16T10:32:49.566Z,
LastAccessTime=2021-02-16T10:32:49.566Z,
LastWriteTime=2021-02-16T10:32:49.566Z,
ChangeTime=2021-02-16T10:32:49.566Z, IndexNumber=0:1}
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: >>Close
000001BECE108990
memfs-x64[TID=1288]: FFFF9A8EE5C7ED50: <<Close
IoStatus=0[0]
The service memfs has been stopped.
C:\Program Files (x86)\WinFsp\bin>
Hi Bill,
How are you doing?
We are trying to roll out our WinFsp based solution that we call SPFS to our governmental institutes here in Sweden, but we run into an issue.
Not sure were this issue is related, as we couldn't find any WinFsp functions getting called in the trace logs.
I have mounted the winfsp drive as administrator, and can see the drives using fsutil tool
C:\Program Files (x86)\Microsoft Visual Studio\2019\Community>fsutil fsinfo drives
Drives: C:\ D:\ E:\ Z:\
C:\Program Files (x86)\Microsoft Visual Studio\2019\Community>
but when I tried to list the drives from my SQL Server, the drive is not listed?
C:\Program Files (x86)\Microsoft Visual Studio\2019\Community>sqlcmd
1> exec xp_cmdshell 'fsutil fsinfo drives'
2> go
output
---------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
NULL
Drives: C:\ D:\ E:\
NULL
(3 rows affected)
1>
But I can't see any indications that the WinFsp driver is being called in the log fileswhy isn’t the drive letter from winfsp available and visible for the ‘NT Service\MSSQLSERVER’ account?Even if I add the 'NT Service\MSSQLSERVER' credentials on the volume, it is still not listed and available under the MS SQL Server account.
How do I troubleshoot, and resolve that the WinFsp drive is not visible under the 'NT Service\MSSQLSERVER' account?
C:\Users\Administrator\Desktop\repos\spictera\WIN-SPFS>spfs -p \\SPFS\X -m Z: -d -1 -D -
2021-02-16.11:10:17 SP99999D spgs:FspLoad:LEAVING:return FspServiceRun()
2021-02-16.11:10:17 SP99999D SvcStart():ENTRY
2021-02-16.11:10:17 SP99999D SvcStart():mountpoint:\\localhost\X
2021-02-16.11:10:17 SP99999D SvcStart():filespace:\\localhost\X
2021-02-16.11:10:17 SP99999D SvcStart():dataspace:\\localhost\X
2021-02-16.11:10:17 SP99999D SvcStart():driveletter:Z
...
The service spfs has been started.
spfs[TID=0c78]: FFFF9A8EE5E66BC0: >>QueryVolumeInformation
..
2021-02-16.11:10:22 SP99999E GetVolumeInfo(\\localhost\X:1):releaseworker()
spfs[TID=0c78]: FFFF9A8EE5E66BC0: <<QueryVolumeInformation IoStatus=0[0] VolumeInfo={TotalSize=300:0, FreeSize=300:0, VolumeLabel="SPICTERA backup data"}
spfs[TID=0c78]: FFFF9A8EE5C29950: >>Create [UT---C] "\autorun.inf", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F4[PID=d48], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=1254]: FFFF9A8EE5A0F470: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F8[PID=d48], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=1254]: FFFF9A8EE5A0F470: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:22.189Z, LastAccessTime=2021-02-16T10:10:22.189Z, LastWriteTime=2021-02-16T10:10:22.189Z, ChangeTime=2021-02-16T10:10:22.189Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5EC8960: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=1254]: FFFF9A8EE5EC8960: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5EC8960: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=800021, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F8[PID=d48], DesiredAccess=100000, GrantedAccess=0, ShareAccess=0
spfs[TID=1254]: FFFF9A8EE5EC8960: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:22.297Z, LastAccessTime=2021-02-16T10:10:22.297Z, LastWriteTime=2021-02-16T10:10:22.297Z, ChangeTime=2021-02-16T10:10:22.297Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5A0F470: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=1254]: FFFF9A8EE5A0F470: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5EC8960: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=800021, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F8[PID=d48], DesiredAccess=100000, GrantedAccess=0, ShareAccess=0
spfs[TID=1254]: FFFF9A8EE5EC8960: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:22.329Z, LastAccessTime=2021-02-16T10:10:22.329Z, LastWriteTime=2021-02-16T10:10:22.329Z, ChangeTime=2021-02-16T10:10:22.329Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5A0F470: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=1254]: FFFF9A8EE5A0F470: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5EC8960: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F8[PID=d48], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=1254]: FFFF9A8EE5EC8960: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:22.329Z, LastAccessTime=2021-02-16T10:10:22.329Z, LastWriteTime=2021-02-16T10:10:22.329Z, ChangeTime=2021-02-16T10:10:22.329Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5A0F470: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=1254]: FFFF9A8EE5A0F470: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5C29950: <<Create IoStatus=c0000034[0]
spfs[TID=0c78]: FFFF9A8EE5C29950: >>Create [UT---C] "\autorun.inf", FILE_OPEN, CreateOptions=60, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F4[PID=d48], DesiredAccess=120089, GrantedAccess=0, ShareAccess=7
spfs[TID=0c78]: FFFF9A8EE5C29950: <<Create IoStatus=c0000034[0]
spfs[TID=0c78]: FFFF9A8EE5C29950: >>Create [UT---C] "\autorun.inf", FILE_OPEN, CreateOptions=60, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F4[PID=d48], DesiredAccess=120089, GrantedAccess=0, ShareAccess=7
spfs[TID=0c78]: FFFF9A8EE5C29950: <<Create IoStatus=c0000034[0]
spfs[TID=0c78]: FFFF9A8EE5C29950: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F4[PID=d48], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=0c78]: FFFF9A8EE5C29950: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=80, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:22.890Z, LastAccessTime=2021-02-16T10:10:22.890Z, LastWriteTime=2021-02-16T10:10:22.890Z, ChangeTime=2021-02-16T10:10:22.890Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5EC8960: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=0c78]: FFFF9A8EE5EC8960: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5EC8960: >>Create [UT---C] "\AutoRun.inf", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=00000000000009F4[PID=d48], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=0c78]: FFFF9A8EE5EC8960: <<Create IoStatus=c0000034[0]
spfs[TID=1254]: FFFF9A8EE5891D20: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=000000000000061C[PID=d54], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=1254]: FFFF9A8EE5891D20: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:48.813Z, LastAccessTime=2021-02-16T10:10:48.813Z, LastWriteTime=2021-02-16T10:10:48.813Z, ChangeTime=2021-02-16T10:10:48.813Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5891D20: >>QueryVolumeInformation
spfs[TID=1254]: FFFF9A8EE5891D20: <<QueryVolumeInformation IoStatus=0[0] VolumeInfo={TotalSize=300:0, FreeSize=300:0, VolumeLabel="SPICTERA backup data"}
spfs[TID=1254]: FFFF9A8EE4EA9380: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=1254]: FFFF9A8EE4EA9380: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5891D20: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=000000000000061C[PID=d54], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=0c78]: FFFF9A8EE5891D20: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=80, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:48.813Z, LastAccessTime=2021-02-16T10:10:48.813Z, LastWriteTime=2021-02-16T10:10:48.813Z, ChangeTime=2021-02-16T10:10:48.813Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5A0F470: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=0c78]: FFFF9A8EE5A0F470: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5891D20: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=000000000000061C[PID=d54], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=1254]: FFFF9A8EE5891D20: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=80, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:48.813Z, LastAccessTime=2021-02-16T10:10:48.813Z, LastWriteTime=2021-02-16T10:10:48.813Z, ChangeTime=2021-02-16T10:10:48.813Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5E66BC0: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=1254]: FFFF9A8EE5E66BC0: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5E66BC0: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=000000000000061C[PID=d54], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=1254]: FFFF9A8EE5E66BC0: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=80, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:49.563Z, LastAccessTime=2021-02-16T10:10:49.563Z, LastWriteTime=2021-02-16T10:10:49.563Z, ChangeTime=2021-02-16T10:10:49.563Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5891D20: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=1254]: FFFF9A8EE5891D20: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5E66BC0: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=800021, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=000000000000061C[PID=d54], DesiredAccess=100000, GrantedAccess=0, ShareAccess=0
spfs[TID=0c78]: FFFF9A8EE5E66BC0: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF851DD5A0, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:10:49.578Z, LastAccessTime=2021-02-16T10:10:49.578Z, LastWriteTime=2021-02-16T10:10:49.578Z, ChangeTime=2021-02-16T10:10:49.578Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE4EA9380: >>Close 0000000000000000:000001EF851DD5A0
spfs[TID=0c78]: FFFF9A8EE4EA9380: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5891D20: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=1034], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=0c78]: FFFF9A8EE5891D20: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:38.838Z, LastAccessTime=2021-02-16T10:13:38.838Z, LastWriteTime=2021-02-16T10:13:38.838Z, ChangeTime=2021-02-16T10:13:38.838Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5891D20: >>QueryVolumeInformation
spfs[TID=0c78]: FFFF9A8EE5891D20: <<QueryVolumeInformation IoStatus=0[0] VolumeInfo={TotalSize=300:0, FreeSize=300:0, VolumeLabel="SPICTERA backup data"}
spfs[TID=0c78]: FFFF9A8EE5E66EA0: >>Close 0000000000000000:000001EF83699220
spfs[TID=0c78]: FFFF9A8EE5E66EA0: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5891D20: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=1034], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=1254]: FFFF9A8EE5891D20: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=80, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:38.869Z, LastAccessTime=2021-02-16T10:13:38.869Z, LastWriteTime=2021-02-16T10:13:38.869Z, ChangeTime=2021-02-16T10:13:38.869Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5A0F470: >>Close 0000000000000000:000001EF83699220
spfs[TID=1254]: FFFF9A8EE5A0F470: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5891D20: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=1034], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
spfs[TID=0c78]: FFFF9A8EE5891D20: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=80, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:38.869Z, LastAccessTime=2021-02-16T10:13:38.869Z, LastWriteTime=2021-02-16T10:13:38.869Z, ChangeTime=2021-02-16T10:13:38.869Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5BD2010: >>Close 0000000000000000:000001EF83699220
spfs[TID=0c78]: FFFF9A8EE5BD2010: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5F90B80: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=9e4], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=0c78]: FFFF9A8EE5F90B80: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:39.634Z, LastAccessTime=2021-02-16T10:13:39.634Z, LastWriteTime=2021-02-16T10:13:39.634Z, ChangeTime=2021-02-16T10:13:39.634Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5F7BAB0: >>Close 0000000000000000:000001EF83699220
spfs[TID=0c78]: FFFF9A8EE5F7BAB0: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5F90B80: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=9e4], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=1254]: FFFF9A8EE5F90B80: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:39.634Z, LastAccessTime=2021-02-16T10:13:39.634Z, LastWriteTime=2021-02-16T10:13:39.634Z, ChangeTime=2021-02-16T10:13:39.634Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5E66EA0: >>Close 0000000000000000:000001EF83699220
spfs[TID=1254]: FFFF9A8EE5E66EA0: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5F90B80: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=800021, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=9e4], DesiredAccess=100000, GrantedAccess=0, ShareAccess=0
spfs[TID=0c78]: FFFF9A8EE5F90B80: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:39.634Z, LastAccessTime=2021-02-16T10:13:39.634Z, LastWriteTime=2021-02-16T10:13:39.634Z, ChangeTime=2021-02-16T10:13:39.634Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5A0F470: >>Close 0000000000000000:000001EF83699220
spfs[TID=0c78]: FFFF9A8EE5A0F470: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5F7CA80: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=9e4], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=0c78]: FFFF9A8EE5F7CA80: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:40.463Z, LastAccessTime=2021-02-16T10:13:40.463Z, LastWriteTime=2021-02-16T10:13:40.463Z, ChangeTime=2021-02-16T10:13:40.463Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5F7CA80: >>QueryVolumeInformation
spfs[TID=0c78]: FFFF9A8EE5F7CA80: <<QueryVolumeInformation IoStatus=0[0] VolumeInfo={TotalSize=300:0, FreeSize=300:0, VolumeLabel="SPICTERA backup data"}
spfs[TID=0c78]: FFFF9A8EE4EA9380: >>Close 0000000000000000:000001EF83699220
spfs[TID=0c78]: FFFF9A8EE4EA9380: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5F7CA80: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=9e4], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=1254]: FFFF9A8EE5F7CA80: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:40.478Z, LastAccessTime=2021-02-16T10:13:40.478Z, LastWriteTime=2021-02-16T10:13:40.478Z, ChangeTime=2021-02-16T10:13:40.478Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE5F7BAB0: >>Close 0000000000000000:000001EF83699220
spfs[TID=1254]: FFFF9A8EE5F7BAB0: <<Close IoStatus=0[0]
spfs[TID=0c78]: FFFF9A8EE5F7CA80: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=9e4], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
spfs[TID=0c78]: FFFF9A8EE5F7CA80: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:40.478Z, LastAccessTime=2021-02-16T10:13:40.478Z, LastWriteTime=2021-02-16T10:13:40.478Z, ChangeTime=2021-02-16T10:13:40.478Z, IndexNumber=0:0}
spfs[TID=0c78]: FFFF9A8EE5E66EA0: >>Close 0000000000000000:000001EF83699220
spfs[TID=0c78]: FFFF9A8EE5E66EA0: <<Close IoStatus=0[0]
spfs[TID=1254]: FFFF9A8EE5F7CA80: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=800021, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000240[PID=9e4], DesiredAccess=100000, GrantedAccess=0, ShareAccess=0
spfs[TID=1254]: FFFF9A8EE5F7CA80: <<Create IoStatus=0[1] UserContext=0000000000000000:000001EF83699220, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:1000, FileSize=0:1000, CreationTime=2021-02-16T10:13:40.478Z, LastAccessTime=2021-02-16T10:13:40.478Z, LastWriteTime=2021-02-16T10:13:40.478Z, ChangeTime=2021-02-16T10:13:40.478Z, IndexNumber=0:0}
spfs[TID=1254]: FFFF9A8EE4EA9380: >>Close 0000000000000000:000001EF83699220
spfs[TID=1254]: FFFF9A8EE4EA9380: <<Close IoStatus=0[0]