none
SQL Network Speed reduced ocasionally RRS feed

  • Question

  • Hi All,

    Urgently need advice on a network speed limitation in a domain network environment.

    I have a brand new server installation running MS Windows Server 19. The third party software VMaster using the SQL database was installed by there company. (Im a SQL beginner)

    on this network there are 10 pc that running the VMaster (the program) that needs to access the SQL database on the server.

    Occasionally and with no apparent pattern different computers are reduced to a network traffic of no more than 2MB/s. While the pc is limited like this i can copy any other files over the network with full speed, it only limits the SQL traffic. This behavior is seen over the whole network with all machines but not simultaneously.  one might be limited the rest work fine, then 3 might be limited the rest work fine. all might be limited. and with no apparent reason the limitation is lifted and the pc works fine again.

    The VMaster support is ambient that it has nothing to do with them. I how suspect that there might be a setting in SQL that dose this. 

    Any advice will be appreciated.


    Monday, July 22, 2019 7:27 AM

All replies

  • There is no setting as such in SQL Server that could reduce the network speed. Then again, how are you measuring the network speed in this case?

    You say that copying other files over the network still works at full speed. Have you tested copying files to the SQL Server machine?


    Erland Sommarskog, SQL Server MVP, esquel@sommarskog.se

    Monday, July 22, 2019 9:13 AM
  • What you are describing is most likely not related to network speed, but SQL query response time.  The most likely cause of this is SQL Server is page swapping because the memory is not configured correctly.

    Look at the SQL Server log and see if there are any messages "a significant part of sql server has been paged out".

    https://docs.microsoft.com/en-us/sql/database-engine/configure-windows/server-memory-server-configuration-options?view=sql-server-2014

    Monday, July 22, 2019 12:05 PM
  • I have opened the Task manager while ruining the different queries from the client computer. I can then clearly see when the network is suddenly limited to 2mb so while it was still ruining that query I then copied a file from the client to the server and in the network graph I can see how the transfer speed climes while copying the other file while the sql is still running in the background.   my file finishes copying the speed drops back down until the SQL finishes as well. sometimes the next SQL query is still slow sometimes its running fast again.

    Hope that answers you question.   It doesn't really make sense to me. Hence me being here :) 

    Monday, July 22, 2019 2:43 PM
  • Hi Tom,

    Sorry Im really a SQL server Newbie :)     where would i find the log that would indicate that?

     

    Monday, July 22, 2019 2:47 PM
  • I have opened the Task manager while ruining the different queries from the client computer. I can then clearly see when the network is suddenly limited to 2mb so while it was still ruining that query I then copied a file from the client to the server and in the network graph I can see how the transfer speed climes while copying the other file while the sql is still running in the background.   my file finishes copying the speed drops back down until the SQL finishes as well. sometimes the next SQL query is still slow sometimes its running fast again.

    That could indicate a number of things. When SQL Server runs a query that produces a result set, you may get rows as they are produced while the query is running, or you may get all rows at the end in one bang. This depends on the query plan, and more precisely what operators there are in the plan. Take a simple query like this:

     SELECT * FROM tbl

    In this case, SQL Server can just deliver the rows in the order it finds them. For a query of this type you will see a good network speed. Say now that the query is

     SELECT * FROM tbl ORDER BY somecol

    unless there is an index on somecol, SQL Server must now sort the data, and no rows can be returned before the sorting has completed. In terms of network speed you will not see much difference.

    But assume now a more complex query with joins and all that does not include any stopping operator like sorts. The rows may now come slowly dripping, because there are still many operators, and maybe scans coming.

    This does not have to be an issue as such, but it is possible that the query could be improved by adding better indexes, or rewriting the query.

    Normally, when you evaluate performance in SQL Server, you look at response times. Network speed as such is not an interesting value - unless you susspect the network to be the problem.


    Erland Sommarskog, SQL Server MVP, esquel@sommarskog.se

    Monday, July 22, 2019 9:42 PM
  • That could indicate a number of things. When SQL Server runs a query that produces a result set, you may get rows as they are produced while the query is running, or you may get all rows at the end in one bang. This depends on the query plan, and more precisely what operators there are in the plan. Take a simple query like this:


    Thanks for the good explanation. while I understand the theory behind it i dont think that is the issue here.

    Even if I run the same command over and over i get the problem that sometimes it returns the results within 2 seconds and then again for some reason it will take the same command close to a minute to complete.

    Running the same command over multiple computers simultaneously thy might all complete within seconds for multiple times and then for some reason one or two would run slow.  

    Im really stumped as i cant seem to find any pattern or trigger.

    Tuesday, July 23, 2019 6:28 AM
  • Thanks for the link Tom   

    Went through the Logs but couldn't find anything indicating "pageout" or memory related

    Is it possible that the log dosen't record it by default? or dose it mean we need to look for something else

    Tuesday, July 23, 2019 6:58 AM
  • Hi IngoBushwizard,

    From your description, the issue is occurred sometimes not always. If I misunderstood, please let me know. Did you test many times, the issue occurred when SQL server executed queries ?
    Could you please check whether your network is stable?

    Best regards,
    Cathy Ji

    MSDN Community Support
    Please remember to click "Mark as Answer" the responses that resolved your issue, and to click "Unmark as Answer" if not. This can be beneficial to other community members reading this thread. If you have any compliments or complaints to  MSDN Support, feel free to contact MSDNFSF@microsoft.com

    Tuesday, July 23, 2019 8:13 AM
  • Please post the first 50 or so lines of your log file, we don't need the entire log.  The screen sorts in reverse order.  The log should start with "Microsoft SQL Server …".

    You can click the "Export" button a the top of the log, to export it to a text file.

    Tuesday, July 23, 2019 12:09 PM
  • From your description, the issue is occurred sometimes not always. If I misunderstood, please let me know. Did you test many times, the issue occurred when SQL server executed queries ?
    Could you please check whether your network is stable?

    Hi Cathy,

    We have tested it now over 2 months and repeatedly get this problem.

    The network is stable. I have other programs running that crash completely if there is any network cutouts and those programs are running perfectly. (or do you have a specific test in mind?)

    Thanks Ingo

    Tuesday, July 23, 2019 2:06 PM
  • Please post the first 50 or so lines of your log file, we don't need the entire log.  The screen sorts in reverse order.  The log should start with "Microsoft SQL Server …".

    You can click the "Export" button a the top of the log, to export it to a text file.

    number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{17EB3C49-5CA0-4C81-A07F-06AAB6FA4C24}1'}). This is an informational message only. No user action is required.
    06/08/2019 22:00:07,spid55,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/08/2019 22:00:07,spid54,Unknown,I/O was resumed on database msdb. No user action is required.
    06/08/2019 22:00:07,spid52,Unknown,I/O was resumed on database master. No user action is required.
    06/08/2019 22:00:07,spid53,Unknown,I/O was resumed on database model. No user action is required.
    06/08/2019 22:00:04,spid52,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/08/2019 22:00:04,spid55,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/08/2019 22:00:04,spid54,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/08/2019 22:00:04,spid53,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/08/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.455 seconds (0.000 MB/sec).
    06/08/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.282 seconds (0.000 MB/sec).
    06/08/2019 21:00:28,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:3021:37<c/> last LSN: 35:3038:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{7D185227-7DA5-45D7-8F70-3B5493D4595C}1'}). This is an informational message only. No user action is required.
    06/08/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 14.800 seconds (0.000 MB/sec).
    06/08/2019 21:00:28,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1945<c/> first LSN: 107:16:19<c/> last LSN: 107:26:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{7D185227-7DA5-45D7-8F70-3B5493D4595C}2'}). This is an informational message only. No user action is required.
    06/08/2019 21:00:28,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97714<c/> first LSN: 65016:171:59<c/> last LSN: 65016:197:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{7D185227-7DA5-45D7-8F70-3B5493D4595C}3'}). This is an informational message only. No user action is required.
    06/08/2019 21:00:27,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 14.475 seconds (0.000 MB/sec).
    06/08/2019 21:00:27,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8752:465:106<c/> last LSN: 8752:509:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{7D185227-7DA5-45D7-8F70-3B5493D4595C}4'}). This is an informational message only. No user action is required.
    06/08/2019 21:00:15,spid53,Unknown,I/O was resumed on database model. No user action is required.
    06/08/2019 21:00:15,spid55,Unknown,I/O was resumed on database master. No user action is required.
    06/08/2019 21:00:15,spid54,Unknown,I/O was resumed on database msdb. No user action is required.
    06/08/2019 21:00:15,spid56,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/08/2019 21:00:12,spid55,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/08/2019 21:00:12,spid56,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/08/2019 21:00:12,spid54,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/08/2019 21:00:12,spid53,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 22:00:09,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 4.142 seconds (0.000 MB/sec).
    06/07/2019 22:00:09,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2997:37<c/> last LSN: 35:3014:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{3CF8695A-7E87-4FDD-B993-5D0AC7BDFDBE}2'}). This is an informational message only. No user action is required.
    06/07/2019 22:00:09,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.603 seconds (0.000 MB/sec).
    06/07/2019 22:00:09,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97594<c/> first LSN: 64987:213:37<c/> last LSN: 64987:230:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{3CF8695A-7E87-4FDD-B993-5D0AC7BDFDBE}4'}). This is an informational message only. No user action is required.
    06/07/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 4.338 seconds (0.000 MB/sec).
    06/07/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.933 seconds (0.000 MB/sec).
    06/07/2019 22:00:08,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1949<c/> first LSN: 106:314:180<c/> last LSN: 106:389:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{3CF8695A-7E87-4FDD-B993-5D0AC7BDFDBE}3'}). This is an informational message only. No user action is required.
    06/07/2019 22:00:08,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8627:424:106<c/> last LSN: 8627:474:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{3CF8695A-7E87-4FDD-B993-5D0AC7BDFDBE}1'}). This is an informational message only. No user action is required.
    06/07/2019 22:00:08,spid54,Unknown,I/O was resumed on database master. No user action is required.
    06/07/2019 22:00:08,spid56,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/07/2019 22:00:08,spid55,Unknown,I/O was resumed on database model. No user action is required.
    06/07/2019 22:00:08,spid57,Unknown,I/O was resumed on database msdb. No user action is required.
    06/07/2019 22:00:04,spid54,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 22:00:04,spid56,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 22:00:04,spid57,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 22:00:04,spid55,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.551 seconds (0.000 MB/sec).
    06/07/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 14.818 seconds (0.000 MB/sec).
    06/07/2019 21:00:28,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2973:37<c/> last LSN: 35:2990:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{E196F941-74BA-48B4-9400-F0C3F04D1507}1'}). This is an informational message only. No user action is required.
    06/07/2019 21:00:28,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97597<c/> first LSN: 64987:97:172<c/> last LSN: 64987:167:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{E196F941-74BA-48B4-9400-F0C3F04D1507}3'}). This is an informational message only. No user action is required.
    06/07/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.373 seconds (0.000 MB/sec).
    06/07/2019 21:00:28,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1949<c/> first LSN: 106:196:180<c/> last LSN: 106:271:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{E196F941-74BA-48B4-9400-F0C3F04D1507}2'}). This is an informational message only. No user action is required.
    06/07/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 14.494 seconds (0.000 MB/sec).
    06/07/2019 21:00:28,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8622:108:106<c/> last LSN: 8622:152:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{E196F941-74BA-48B4-9400-F0C3F04D1507}4'}). This is an informational message only. No user action is required.
    06/07/2019 21:00:16,spid55,Unknown,I/O was resumed on database msdb. No user action is required.
    06/07/2019 21:00:16,spid56,Unknown,I/O was resumed on database master. No user action is required.
    06/07/2019 21:00:16,spid57,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/07/2019 21:00:16,spid54,Unknown,I/O was resumed on database model. No user action is required.
    06/07/2019 21:00:13,spid56,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 21:00:13,spid57,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 21:00:13,spid55,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 21:00:13,spid54,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/07/2019 07:27:30,Backup,Unknown,BACKUP DATABASE successfully processed 97460 pages in 135.148 seconds (5.633 MB/sec).
    06/07/2019 07:27:30,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97468<c/> first LSN: 64935:325:125<c/> last LSN: 64935:376:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_07Jun20199929.BAK'}). This is an informational message only. No user action is required.
    06/07/2019 07:23:46,Backup,Unknown,BACKUP DATABASE successfully processed 97459 pages in 136.486 seconds (5.578 MB/sec).
    06/07/2019 07:23:46,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97468<c/> first LSN: 64935:250:36<c/> last LSN: 64935:286:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_07Jun20198771.BAK'}). This is an informational message only. No user action is required.
    06/06/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 4.009 seconds (0.000 MB/sec).
    06/06/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 4.164 seconds (0.000 MB/sec).
    06/06/2019 22:00:08,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2949:37<c/> last LSN: 35:2966:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{4EA71D33-1B5D-4520-95D2-3EBF394624D0}2'}). This is an informational message only. No user action is required.
    06/06/2019 22:00:08,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8496:434:106<c/> last LSN: 8496:484:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{4EA71D33-1B5D-4520-95D2-3EBF394624D0}1'}). This is an informational message only. No user action is required.
    06/06/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.793 seconds (0.000 MB/sec).
    06/06/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.504 seconds (0.000 MB/sec).
    06/06/2019 22:00:08,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1941<c/> first LSN: 106:54:180<c/> last LSN: 106:129:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{4EA71D33-1B5D-4520-95D2-3EBF394624D0}3'}). This is an informational message only. No user action is required.
    06/06/2019 22:00:08,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97522<c/> first LSN: 64926:402:37<c/> last LSN: 64926:419:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{4EA71D33-1B5D-4520-95D2-3EBF394624D0}4'}). This is an informational message only. No user action is required.
    06/06/2019 22:00:08,spid58,Unknown,I/O was resumed on database msdb. No user action is required.
    06/06/2019 22:00:08,spid56,Unknown,I/O was resumed on database master. No user action is required.
    06/06/2019 22:00:08,spid57,Unknown,I/O was resumed on database model. No user action is required.
    06/06/2019 22:00:08,spid59,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/06/2019 22:00:04,spid56,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 22:00:04,spid59,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 22:00:04,spid58,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 22:00:04,spid57,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 21:00:29,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.414 seconds (0.000 MB/sec).
    06/06/2019 21:00:29,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97521<c/> first LSN: 64926:346:18<c/> last LSN: 64926:356:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F3037257-C0EE-48C8-A85B-127C80D2FBAA}3'}). This is an informational message only. No user action is required.
    06/06/2019 21:00:29,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 16.053 seconds (0.000 MB/sec).
    06/06/2019 21:00:29,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2925:37<c/> last LSN: 35:2942:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F3037257-C0EE-48C8-A85B-127C80D2FBAA}1'}). This is an informational message only. No user action is required.
    06/06/2019 21:00:29,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.862 seconds (0.000 MB/sec).
    06/06/2019 21:00:29,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1941<c/> first LSN: 105:431:180<c/> last LSN: 105:506:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F3037257-C0EE-48C8-A85B-127C80D2FBAA}2'}). This is an informational message only. No user action is required.
    06/06/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.172 seconds (0.000 MB/sec).
    06/06/2019 21:00:28,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8491:127:106<c/> last LSN: 8491:171:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F3037257-C0EE-48C8-A85B-127C80D2FBAA}4'}). This is an informational message only. No user action is required.
    06/06/2019 21:00:16,spid59,Unknown,I/O was resumed on database master. No user action is required.
    06/06/2019 21:00:16,spid57,Unknown,I/O was resumed on database model. No user action is required.
    06/06/2019 21:00:16,spid56,Unknown,I/O was resumed on database msdb. No user action is required.
    06/06/2019 21:00:16,spid58,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/06/2019 21:00:13,spid59,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 21:00:13,spid58,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 21:00:13,spid56,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 21:00:13,spid57,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/06/2019 07:28:15,Backup,Unknown,BACKUP DATABASE successfully processed 97387 pages in 131.587 seconds (5.781 MB/sec).
    06/06/2019 07:28:15,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97396<c/> first LSN: 64882:418:103<c/> last LSN: 64882:460:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_06Jun20198242.BAK'}). This is an informational message only. No user action is required.
    06/06/2019 07:23:08,Backup,Unknown,BACKUP DATABASE successfully processed 97390 pages in 136.298 seconds (5.582 MB/sec).
    06/06/2019 07:23:08,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97396<c/> first LSN: 64882:285:36<c/> last LSN: 64882:374:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_06Jun20194577.BAK'}). This is an informational message only. No user action is required.
    06/05/2019 22:00:09,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.796 seconds (0.000 MB/sec).
    06/05/2019 22:00:09,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2901:37<c/> last LSN: 35:2918:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{8E3F65DF-6BF9-4627-8DA4-84B9E006336E}2'}). This is an informational message only. No user action is required.
    06/05/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.383 seconds (0.000 MB/sec).
    06/05/2019 22:00:08,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97490<c/> first LSN: 64873:159:69<c/> last LSN: 64873:188:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{8E3F65DF-6BF9-4627-8DA4-84B9E006336E}4'}). This is an informational message only. No user action is required.
    06/05/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.900 seconds (0.000 MB/sec).
    06/05/2019 22:00:08,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 457<c/> first LSN: 8366:16:28<c/> last LSN: 8366:36:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{8E3F65DF-6BF9-4627-8DA4-84B9E006336E}1'}). This is an informational message only. No user action is required.
    06/05/2019 22:00:08,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.646 seconds (0.000 MB/sec).
    06/05/2019 22:00:08,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1941<c/> first LSN: 105:298:180<c/> last LSN: 105:373:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{8E3F65DF-6BF9-4627-8DA4-84B9E006336E}3'}). This is an informational message only. No user action is required.
    06/05/2019 22:00:08,spid53,Unknown,I/O was resumed on database master. No user action is required.
    06/05/2019 22:00:08,spid55,Unknown,I/O was resumed on database model. No user action is required.
    06/05/2019 22:00:08,spid58,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/05/2019 22:00:08,spid56,Unknown,I/O was resumed on database msdb. No user action is required.
    06/05/2019 22:00:05,spid53,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 22:00:05,spid58,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 22:00:05,spid56,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 22:00:05,spid55,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.457 seconds (0.000 MB/sec).
    06/05/2019 21:00:28,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2877:37<c/> last LSN: 35:2894:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F13CA513-C459-41A9-AE76-DBA6CBFF23A8}1'}). This is an informational message only. No user action is required.
    06/05/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 14.904 seconds (0.000 MB/sec).
    06/05/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.149 seconds (0.000 MB/sec).
    06/05/2019 21:00:28,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97491<c/> first LSN: 64873:70:88<c/> last LSN: 64873:111:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F13CA513-C459-41A9-AE76-DBA6CBFF23A8}3'}). This is an informational message only. No user action is required.
    06/05/2019 21:00:28,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1941<c/> first LSN: 105:181:180<c/> last LSN: 105:256:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F13CA513-C459-41A9-AE76-DBA6CBFF23A8}2'}). This is an informational message only. No user action is required.
    06/05/2019 21:00:28,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 14.593 seconds (0.000 MB/sec).
    06/05/2019 21:00:28,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8360:164:106<c/> last LSN: 8360:208:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{F13CA513-C459-41A9-AE76-DBA6CBFF23A8}4'}). This is an informational message only. No user action is required.
    06/05/2019 21:00:16,spid56,Unknown,I/O was resumed on database msdb. No user action is required.
    06/05/2019 21:00:16,spid58,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/05/2019 21:00:16,spid59,Unknown,I/O was resumed on database master. No user action is required.
    06/05/2019 21:00:16,spid55,Unknown,I/O was resumed on database model. No user action is required.
    06/05/2019 21:00:13,spid59,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 21:00:13,spid58,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 21:00:13,spid56,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 21:00:13,spid55,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/05/2019 07:44:03,Backup,Unknown,BACKUP DATABASE successfully processed 97278 pages in 131.908 seconds (5.761 MB/sec).
    06/05/2019 07:44:03,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97284<c/> first LSN: 64811:178:85<c/> last LSN: 64811:274:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_05Jun20193697.BAK'}). This is an informational message only. No user action is required.
    06/05/2019 07:41:03,Backup,Unknown,BACKUP DATABASE successfully processed 97285 pages in 131.174 seconds (5.794 MB/sec).
    06/05/2019 07:41:03,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97292<c/> first LSN: 64810:396:36<c/> last LSN: 64811:102:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_05Jun20196116.BAK'}). This is an informational message only. No user action is required.
    06/04/2019 22:00:09,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.975 seconds (0.000 MB/sec).
    06/04/2019 22:00:09,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2853:37<c/> last LSN: 35:2870:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{2B6F7F82-52EE-4A51-8D97-8BBBADFA1833}2'}). This is an informational message only. No user action is required.
    06/04/2019 22:00:09,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.554 seconds (0.000 MB/sec).
    06/04/2019 22:00:09,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.275 seconds (0.000 MB/sec).
    06/04/2019 22:00:09,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 4.175 seconds (0.000 MB/sec).
    06/04/2019 22:00:09,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1933<c/> first LSN: 105:45:180<c/> last LSN: 105:120:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{2B6F7F82-52EE-4A51-8D97-8BBBADFA1833}3'}). This is an informational message only. No user action is required.
    06/04/2019 22:00:09,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97330<c/> first LSN: 64801:178:37<c/> last LSN: 64801:195:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{2B6F7F82-52EE-4A51-8D97-8BBBADFA1833}4'}). This is an informational message only. No user action is required.
    06/04/2019 22:00:09,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8235:43:106<c/> last LSN: 8235:93:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{2B6F7F82-52EE-4A51-8D97-8BBBADFA1833}1'}). This is an informational message only. No user action is required.
    06/04/2019 22:00:08,spid55,Unknown,I/O was resumed on database model. No user action is required.
    06/04/2019 22:00:08,spid54,Unknown,I/O was resumed on database master. No user action is required.
    06/04/2019 22:00:08,spid56,Unknown,I/O was resumed on database msdb. No user action is required.
    06/04/2019 22:00:08,spid58,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/04/2019 22:00:05,spid54,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 22:00:05,spid58,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 22:00:05,spid56,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 22:00:05,spid55,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 21:00:29,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 16.548 seconds (0.000 MB/sec).
    06/04/2019 21:00:29,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 16.830 seconds (0.000 MB/sec).
    06/04/2019 21:00:29,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1933<c/> first LSN: 104:424:180<c/> last LSN: 104:499:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{B478A7E1-86B4-4D98-8D12-47CAFE6C3908}2'}). This is an informational message only. No user action is required.
    06/04/2019 21:00:29,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2829:37<c/> last LSN: 35:2846:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{B478A7E1-86B4-4D98-8D12-47CAFE6C3908}1'}). This is an informational message only. No user action is required.
    06/04/2019 21:00:29,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 16.252 seconds (0.000 MB/sec).
    06/04/2019 21:00:29,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.777 seconds (0.000 MB/sec).
    06/04/2019 21:00:29,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97331<c/> first LSN: 64801:86:112<c/> last LSN: 64801:132:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{B478A7E1-86B4-4D98-8D12-47CAFE6C3908}3'}). This is an informational message only. No user action is required.
    06/04/2019 21:00:29,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8229:219:106<c/> last LSN: 8229:263:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{B478A7E1-86B4-4D98-8D12-47CAFE6C3908}4'}). This is an informational message only. No user action is required.
    06/04/2019 21:00:17,spid56,Unknown,I/O was resumed on database model. No user action is required.
    06/04/2019 21:00:17,spid54,Unknown,I/O was resumed on database msdb. No user action is required.
    06/04/2019 21:00:17,spid59,Unknown,I/O was resumed on database master. No user action is required.
    06/04/2019 21:00:17,spid58,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/04/2019 21:00:13,spid59,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 21:00:13,spid58,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 21:00:13,spid54,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 21:00:13,spid56,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/04/2019 10:39:30,Backup,Unknown,BACKUP DATABASE successfully processed 97233 pages in 132.351 seconds (5.739 MB/sec).
    06/04/2019 10:39:30,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97244<c/> first LSN: 64763:16:110<c/> last LSN: 64763:156:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_04Jun20192360.BAK'}). This is an informational message only. No user action is required.
    06/04/2019 10:32:02,Backup,Unknown,BACKUP DATABASE successfully processed 97226 pages in 133.884 seconds (5.673 MB/sec).
    06/04/2019 10:32:02,Backup,Unknown,Database backed up. Database: VetMaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97236<c/> first LSN: 64762:155:36<c/> last LSN: 64762:172:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=DISK: {'C:\Libralex\VetMaster\VetMaster21715_04Jun20194996.BAK'}). This is an informational message only. No user action is required.
    06/04/2019 10:29:46,spid54,Unknown,Using 'xpstar.dll' version '2017.140.1000' to execute extended stored procedure 'xp_fixeddrives'. This is an informational message only; no user action is required.
    06/04/2019 10:29:46,spid54,Unknown,Attempting to load library 'xpstar.dll' into memory. This is an informational message only. No user action is required.
    06/03/2019 22:00:14,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.335 seconds (0.000 MB/sec).
    06/03/2019 22:00:14,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 2.857 seconds (0.000 MB/sec).
    06/03/2019 22:00:14,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.985 seconds (0.000 MB/sec).
    06/03/2019 22:00:14,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97211<c/> first LSN: 64743:333:83<c/> last LSN: 64743:369:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{FCF3C186-2FE9-4849-BCB1-C0FE6E57ACC6}4'}). This is an informational message only. No user action is required.
    06/03/2019 22:00:14,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1933<c/> first LSN: 104:291:180<c/> last LSN: 104:366:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{FCF3C186-2FE9-4849-BCB1-C0FE6E57ACC6}3'}). This is an informational message only. No user action is required.
    06/03/2019 22:00:14,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8104:104:106<c/> last LSN: 8104:154:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{FCF3C186-2FE9-4849-BCB1-C0FE6E57ACC6}1'}). This is an informational message only. No user action is required.
    06/03/2019 22:00:14,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 3.777 seconds (0.000 MB/sec).
    06/03/2019 22:00:14,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2805:37<c/> last LSN: 35:2822:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{FCF3C186-2FE9-4849-BCB1-C0FE6E57ACC6}2'}). This is an informational message only. No user action is required.
    06/03/2019 22:00:13,spid58,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/03/2019 22:00:13,spid54,Unknown,I/O was resumed on database master. No user action is required.
    06/03/2019 22:00:13,spid56,Unknown,I/O was resumed on database msdb. No user action is required.
    06/03/2019 22:00:13,spid55,Unknown,I/O was resumed on database model. No user action is required.
    06/03/2019 22:00:11,spid54,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 22:00:11,spid58,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 22:00:11,spid56,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 22:00:11,spid55,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 21:00:49,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.439 seconds (0.000 MB/sec).
    06/03/2019 21:00:49,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 16.319 seconds (0.000 MB/sec).
    06/03/2019 21:00:49,Backup,Unknown,Database backed up. Database: Vetmaster<c/> creation date(time): 2019/04/04(14:03:07)<c/> pages dumped: 97201<c/> first LSN: 64742:385:1<c/> last LSN: 64742:388:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{0A6538F7-83D0-4082-801A-C4C91EB6072A}3'}). This is an informational message only. No user action is required.
    06/03/2019 21:00:49,Backup,Unknown,Database backed up. Database: model<c/> creation date(time): 2003/04/08(09:13:36)<c/> pages dumped: 306<c/> first LSN: 35:2781:37<c/> last LSN: 35:2798:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{0A6538F7-83D0-4082-801A-C4C91EB6072A}1'}). This is an informational message only. No user action is required.
    06/03/2019 21:00:49,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 16.122 seconds (0.000 MB/sec).
    06/03/2019 21:00:48,Backup,Unknown,Database backed up. Database: msdb<c/> creation date(time): 2017/08/22(19:39:22)<c/> pages dumped: 1922<c/> first LSN: 104:227:37<c/> last LSN: 104:244:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{0A6538F7-83D0-4082-801A-C4C91EB6072A}2'}). This is an informational message only. No user action is required.
    06/03/2019 21:00:48,Backup,Unknown,BACKUP DATABASE successfully processed 0 pages in 15.085 seconds (0.000 MB/sec).
    06/03/2019 21:00:48,Backup,Unknown,Database backed up. Database: master<c/> creation date(time): 2019/06/03(15:21:16)<c/> pages dumped: 459<c/> first LSN: 8098:298:106<c/> last LSN: 8098:342:1<c/> number of dump devices: 1<c/> device information: (FILE=1<c/> TYPE=VIRTUAL_DEVICE: {'{0A6538F7-83D0-4082-801A-C4C91EB6072A}4'}). This is an informational message only. No user action is required.
    06/03/2019 21:00:36,spid61,Unknown,I/O was resumed on database msdb. No user action is required.
    06/03/2019 21:00:36,spid55,Unknown,I/O was resumed on database model. No user action is required.
    06/03/2019 21:00:36,spid56,Unknown,I/O was resumed on database Vetmaster. No user action is required.
    06/03/2019 21:00:36,spid58,Unknown,I/O was resumed on database master. No user action is required.
    06/03/2019 21:00:33,spid58,Unknown,I/O is frozen on database master. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 21:00:33,spid56,Unknown,I/O is frozen on database Vetmaster. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 21:00:33,spid61,Unknown,I/O is frozen on database msdb. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 21:00:33,spid55,Unknown,I/O is frozen on database model. No user action is required. However<c/> if I/O is not resumed promptly<c/> you could cancel the backup.
    06/03/2019 15:26:39,spid60,Unknown,Using 'xplog70.dll' version '2017.140.1000' to execute extended stored procedure 'xp_msver'. This is an informational message only; no user action is required.
    06/03/2019 15:26:39,spid60,Unknown,Attempting to load library 'xplog70.dll' into memory. This is an informational message only. No user action is required.
    06/03/2019 15:26:17,spid60,Unknown,Using 'dbghelp.dll' version '4.0.5'
    06/03/2019 15:21:27,spid51,Unknown,Parallel redo is shutdown for database 'Vetmaster' with worker pool size [4].
    06/03/2019 15:21:27,spid51,Unknown,Parallel redo is started for database 'Vetmaster' with worker pool size [4].
    06/03/2019 15:21:27,spid51,Unknown,Starting up database 'Vetmaster'.
    06/03/2019 15:21:21,spid8s,Unknown,Recovery is complete. This is an informational message only. No user action is required.
    06/03/2019 15:21:21,spid22s,Unknown,Service Broker manager has started.
    06/03/2019 15:21:21,spid22s,Unknown,The Database Mirroring endpoint is in disabled or stopped state.
    06/03/2019 15:21:21,spid22s,Unknown,The Service Broker endpoint is in disabled or stopped state.
    06/03/2019 15:21:21,spid12s,Unknown,Starting up database 'tempdb'.
    06/03/2019 15:21:20,spid12s,Unknown,Clearing tempdb database.
    06/03/2019 15:21:20,spid12s,Unknown,Polybase feature disabled.
    06/03/2019 15:21:20,spid12s,Unknown,Recovery is writing a checkpoint in database 'model' (3). This is an informational message only. No user action is required.
    06/03/2019 15:21:20,spid12s,Unknown,0 transactions rolled back in database 'model' (3:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:20,spid12s,Unknown,1 transactions rolled forward in database 'model' (3:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:19,spid12s,Unknown,Starting up database 'model'.
    06/03/2019 15:21:19,spid20s,Unknown,Parallel redo is shutdown for database 'Vetmaster' with worker pool size [4].
    06/03/2019 15:21:19,spid20s,Unknown,0 transactions rolled back in database 'Vetmaster' (5:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:19,spid19s,Unknown,Recovery is writing a checkpoint in database 'msdb' (4). This is an informational message only. No user action is required.
    06/03/2019 15:21:19,spid19s,Unknown,0 transactions rolled back in database 'msdb' (4:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:19,spid20s,Unknown,17 transactions rolled forward in database 'Vetmaster' (5:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:19,spid20s,Unknown,Parallel redo is started for database 'Vetmaster' with worker pool size [4].
    06/03/2019 15:21:19,spid19s,Unknown,7 transactions rolled forward in database 'msdb' (4:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:19,spid12s,Unknown,The resource database build version is 14.00.1000. This is an informational message only. No user action is required.
    06/03/2019 15:21:19,spid12s,Unknown,Starting up database 'mssqlsystemresource'.
    06/03/2019 15:21:19,spid20s,Unknown,Starting up database 'Vetmaster'.
    06/03/2019 15:21:19,spid19s,Unknown,Starting up database 'msdb'.
    06/03/2019 15:21:19,spid17s,Unknown,A new instance of the full-text filter daemon host process has been successfully started.
    06/03/2019 15:21:18,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: 192.168.25.112]
    06/03/2019 15:21:18,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:18,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: 192.168.25.111]
    06/03/2019 15:21:18,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:18,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: 192.168.25.110]
    06/03/2019 15:21:18,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:18,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: fe80::5929:88b0:5296:87dc%5]
    06/03/2019 15:21:18,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:17,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: 192.168.25.109]
    06/03/2019 15:21:17,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:17,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: 192.168.25.100]
    06/03/2019 15:21:17,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:17,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: 192.168.25.112]
    06/03/2019 15:21:17,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:17,Logon,Unknown,Login failed for user 'VetMasterUser'. Reason: Failed to open the explicitly specified database 'VetMaster'. [CLIENT: fe80::bce0:57a2:7db9:2894%5]
    06/03/2019 15:21:17,Logon,Unknown,Error: 18456<c/> Severity: 14<c/> State: 38.
    06/03/2019 15:21:17,Server,Unknown,The SQL Server Network Interface library could not register the Service Principal Name (SPN) [ MSSQLSvc/WAH01.wah.local:59852 ] for the SQL Server service. Windows return code: 0x2098<c/> state: 15. Failure to register a SPN might cause integrated authentication to use NTLM instead of Kerberos. This is an informational message. Further action is only required if Kerberos authentication is required by authentication policies and if the SPN has not been manually registered.
    06/03/2019 15:21:17,Server,Unknown,The SQL Server Network Interface library could not register the Service Principal Name (SPN) [ MSSQLSvc/WAH01.wah.local:SQL2017 ] for the SQL Server service. Windows return code: 0x2098<c/> state: 15. Failure to register a SPN might cause integrated authentication to use NTLM instead of Kerberos. This is an informational message. Further action is only required if Kerberos authentication is required by authentication policies and if the SPN has not been manually registered.
    06/03/2019 15:21:17,Server,Unknown,SQL Server is attempting to register a Service Principal Name (SPN) for the SQL Server service. Kerberos authentication will not be possible until a SPN is registered for the SQL Server service. This is an informational message. No user action is required.
    06/03/2019 15:21:17,spid16s,Unknown,SQL Server is now ready for client connections. This is an informational message; no user action is required.
    06/03/2019 15:21:17,spid16s,Unknown,Dedicated administrator connection support was not started because it is disabled on this edition of SQL Server. If you want to use a dedicated administrator connection<c/> restart SQL Server using the trace flag 7806. This is an informational message only. No user action is required.
    06/03/2019 15:21:17,spid16s,Unknown,Server local connection provider is ready to accept connection on [ \\.\pipe\MSSQL$SQL2017\sql\query ].
    06/03/2019 15:21:17,spid16s,Unknown,Server local connection provider is ready to accept connection on [ \\.\pipe\SQLLocal\SQL2017 ].
    06/03/2019 15:21:17,spid16s,Unknown,Server is listening on [ 'any' <ipv4> 59852].
    06/03/2019 15:21:17,spid16s,Unknown,Server is listening on [ 'any' <ipv6> 59852].
    06/03/2019 15:21:17,spid16s,Unknown,A self-generated certificate was successfully loaded for encryption.
    06/03/2019 15:21:17,spid8s,Unknown,Server name is 'WAH01\SQL2017'. This is an informational message only. No user action is required.
    06/03/2019 15:21:17,spid8s,Unknown,SQL Trace ID 1 was started by login "sa".
    06/03/2019 15:21:16,spid8s,Unknown,SQL Server Audit has started the audits. This is an informational message. No user action is required.
    06/03/2019 15:21:16,spid8s,Unknown,SQL Server Audit is starting the audits. This is an informational message. No user action is required.
    06/03/2019 15:21:16,spid8s,Unknown,Recovery is writing a checkpoint in database 'master' (1). This is an informational message only. No user action is required.
    06/03/2019 15:21:16,spid8s,Unknown,0 transactions rolled back in database 'master' (1:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:16,spid8s,Unknown,473 transactions rolled forward in database 'master' (1:0). This is an informational message only. No user action is required.
    06/03/2019 15:21:16,Server,Unknown,Common language runtime (CLR) functionality initialized using CLR version v4.0.30319 from C:\Windows\Microsoft.NET\Framework64\v4.0.30319\.
    06/03/2019 15:21:16,spid8s,Unknown,Starting up database 'master'.
    06/03/2019 15:21:15,Server,Unknown,Software Usage Metrics is disabled.
    06/03/2019 15:21:15,Server,Unknown,Query Store settings initialized with enabled = 1<c/>
    06/03/2019 15:21:15,Server,Unknown,CLR version v4.0.30319 loaded.
    06/03/2019 15:21:15,Server,Unknown,Database Instant File Initialization: disabled. For security and performance considerations see the topic 'Database Instant File Initialization' in SQL Server Books Online. This is an informational message only. No user action is required.
    06/03/2019 15:21:15,Server,Unknown,In-Memory OLTP initialized on standard machine.
    06/03/2019 15:21:15,Server,Unknown,Using dynamic lock allocation.  Initial allocation of 2500 Lock blocks and 5000 Lock Owner blocks per node.  This is an informational message only.  No user action is required.
    06/03/2019 15:21:15,Server,Unknown,Node configuration: node 0: CPU mask: 0x00000000000000ff:0 Active CPU mask: 0x00000000000000ff:0. This message provides a description of the NUMA configuration for this computer. This is an informational message only. No user action is required.
    06/03/2019 15:21:15,Server,Unknown,This instance of SQL Server last reported using a process ID of 6708 at 23/05/2019 08:20:08 (local) 23/05/2019 06:20:08 (UTC). This is an informational message only; no user action is required.
    06/03/2019 15:21:15,Server,Unknown,The maximum number of dedicated administrator connections for this instance is '1'
    06/03/2019 15:21:14,Server,Unknown,Implied authentication manager initialization failed. Implied authentication will be disabled.
    06/03/2019 15:21:14,Server,Unknown,InitializeExternalUserGroupSid failed. Implied authentication will be disabled.
    06/03/2019 15:21:14,Server,Unknown,Buffer pool extension is already disabled. No action is necessary.
    06/03/2019 15:21:14,Server,Unknown,Default collation: SQL_Latin1_General_CP1_CI_AS (us_english 1033)
    06/03/2019 15:21:14,Server,Unknown,Using conventional memory in the memory manager.
    06/03/2019 15:21:14,Server,Unknown,Detected 16213 MB of RAM. This is an informational message; no user action is required.
    06/03/2019 15:21:14,Server,Unknown,SQL Server is starting at normal priority base (=7). This is an informational message only. No user action is required.
    06/03/2019 15:21:14,Server,Unknown,SQL Server detected 1 sockets with 4 cores per socket and 8 logical processors per socket<c/> 8 total logical processors; using 8 logical processors based on SQL Server licensing. This is an informational message; no user action is required.
    06/03/2019 15:21:14,Server,Unknown,Command Line Startup Parameters:<nl/> -s "SQL2017"
    06/03/2019 15:21:14,Server,Unknown,Registry startup parameters: <nl/> -d C:\Program Files\Microsoft SQL Server\MSSQL14.SQL2017\MSSQL\DATA\master.mdf<nl/> -e C:\Program Files\Microsoft SQL Server\MSSQL14.SQL2017\MSSQL\Log\ERRORLOG<nl/> -l C:\Program Files\Microsoft SQL Server\MSSQL14.SQL2017\MSSQL\DATA\mastlog.ldf
    06/03/2019 15:21:14,Server,Unknown,The service account is 'NT Service\MSSQL$SQL2017'. This is an informational message; no user action is required.
    06/03/2019 15:21:14,Server,Unknown,Logging SQL Server messages in file 'C:\Program Files\Microsoft SQL Server\MSSQL14.SQL2017\MSSQL\Log\ERRORLOG'.
    06/03/2019 15:21:14,Server,Unknown,Authentication mode is MIXED.
    06/03/2019 15:21:14,Server,Unknown,System Manufacturer: 'Dell Inc.'<c/> System Model: 'PowerEdge T330'.
    06/03/2019 15:21:14,Server,Unknown,Server process ID is 6316.
    06/03/2019 15:21:14,Server,Unknown,All rights reserved.
    06/03/2019 15:21:14,Server,Unknown,(c) Microsoft Corporation.
    06/03/2019 15:21:14,Server,Unknown,UTC adjustment: 2:00
    06/03/2019 15:21:14,Server,Unknown,Microsoft SQL Server 2017 (RTM) - 14.0.1000.169 (X64) <nl/> Aug 22 2017 17:04:49 <nl/> Copyright (C) 2017 Microsoft Corporation<nl/> Express Edition (64-bit) on Windows Server 2019 Essentials 10.0 <X64> (Build 17763: )

    Tuesday, July 23, 2019 3:10 PM
  • Thanks for the good explanation. while I understand the theory behind it i dont think that is the issue here.

    Even if I run the same command over and over i get the problem that sometimes it returns the results within 2 seconds and then again for some reason it will take the same command close to a minute to complete.

    Well, a query can have different plans at different occasions, so this is not a possibility that cannot be ruled out entirely.

    From your errorlog, I make two observations:

    1) You are running SQL 2017 RTM with no Cumulative Update applied. I recommend that you download and install the latest Cumulative Update, which I believe is CU15. That may not resolve your problem, but you get quite a few bug fixes that way.

    The other thing I notice is that you run Express Edition. Express is limited to 1GB of buffer pool sa I recall, so it could be that at the times the queries are slow, data is in memory, but hsa to be retreived from disk.


    Erland Sommarskog, SQL Server MVP, esquel@sommarskog.se

    Tuesday, July 23, 2019 9:22 PM
  • I don't see anything of concern in your log, except you are running the RTM version of SQL 2017 Express.  I would highly suggest you install the current patch and see if the problem continues:

    https://support.microsoft.com/en-us/help/4498951/cumulative-update-15-for-sql-server-2017

    How big is your database and how many users?  SQL Express edition has memory and CPU limits.  It may just not be able to handle the load you are placing on it.

    Wednesday, July 24, 2019 12:01 PM