[…] WRITELOG—When a SQL Server session waits on the WRITELOG wait type, it is waiting to write the contents of the log cache to disk where the transaction log is stored. […]
When a SQL Server session waits on the WRITELOG wait type, it is waiting to write the contents of the log cache to disk where the transaction log is stored.
To explain the process in more detail, assume a session starts a transaction that will perform several INSERT statements. While the data is being inserted, two actions occur:
- The data page in the buffer cache is updated with the new data.
- Data is written to the log cache which is a segment of memory used to record data that will be used for rolling back the transaction or be written to the log file.
This process continues until the transaction has completed (committed) at which time the data in the log cache is immediately written to the physical log file. When SQL Server is in the act of flushing the log cache to disk, the session will wait on the WRITELOG wait type.
Getting more information
If sessions are consistently waiting on the WRITELOG wait type, review the following Perfmon data for signs of disk bottlenecks where the transaction log is stored:
- PhysicalDisk Object
-
- Avg. Disk Queue Length – the average number of IO requests that were queued. If this is consistently greater than one, this could indicate a disk bottleneck.
- Avg. Disk sec/Read and Avg. Disk sec/Write – if either of these are higher than 15-20 ms, this could indicate the transaction log is stored on a slow device
- SQLServer: Buffer Manager
-
- Checkpoint pages/sec – number of pages flushed by checkpoint operations that require all dirty buffers to be written to disk
Using SolarWinds Database Performance Analyzer or other tools, also determine the top SQL statements waiting on the WRITELOG event. If many statements are found waiting, this could indicate that one of the above items is the problem. If only a few SQL statements are found waiting on WRITELOG, it could indicate an inefficient use of transactions (discussed with examples below).
WRITELOG Wait Type: Fixing the problem
Disk Subsystem Performance – In much of the documentation about the WRITELOG wait type, it seems the problem is often mischaracterized as solely a disk subsystem issue. In the cases where disks are the problem, counters from the PhysicalDisk Object in Perfmon will be high and fixes often include:
- Adding additional IO bandwidth to the disk subsystem where the transaction log is stored.
- Moving non-transaction log IO from the disk.
- Moving the transaction log to a less busy disk.
- Reducing the size of the transaction log has also helped in some cases.
Committing Data Too Often – In many cases that we see during performance consultations, excessive waits on the WRITELOG wait type can be caused by an over zealous usage of transactions, i.e. committing data too often. To illustrate this problem, consider the following code examples:
Example 1: The following code took 418 seconds to execute and waited 410 seconds on the WRITELOG wait type. Note how the COMMIT statement is located inside the loop and executed 100,000 times.
DECLARE @i INT SET @i = 1 WHILE @i < 100000 BEGIN BEGIN TRANSACTION INSERT INTO [splendidCRM].[dbo].[product] ([productid], [category], [name], [descn]) VALUES (@i, floor(@i / 1000), 'PROD' + REPLACE(str(@i),' ',''), 'PROD' + REPLACE(str(@i),' ','')) SET @i = @i + 1 COMMIT END
Example 2: The following code, which also inserts 100,000 rows as above, took 3 seconds and waited less than a second on the WRITELOG wait type. Note how the COMMIT statement is located outside the loop and only executed once.
DECLARE @i INT SET @i = 1 BEGIN TRANSACTION WHILE @i < 100000 BEGIN INSERT INTO [splendidCRM].[dbo].[product] ([productid], [category], [name], [descn]) VALUES (@i, floor(@i / 1000), 'PROD' + REPLACE(str(@i),' ',''), 'PROD' + REPLACE(str(@i),' ','')) SET @i = @i + 1 END COMMIT
Final thoughts
When sessions are found waiting on the WRITELOG wait type, it is imperative to perform a full analysis of the situation, including a review of disk performance data, as well as a review of all query statements found waiting on WRITELOG. Only then can you be assured you are solving the correct problem and not just adding expensive hardware that will not fix the root cause.
John Walker says
Yes, that is correct, look for the sql procedures/batches/statements that perform lot of DML operation within a loop such as described in the article, that’s a first indication of high write log waits.I have found another post for the same see here: https://atdhebuja.wordpress.com/2016/06/20/resolving-sql-server-transaction-log-waits/
Kapil Mahershi says
Sometimes System Stuck hence first try to clear wait stats
1. Dbcc Sqlperf(sys.dm_os_wait_stats,clear)
2. Segregate database datafile and Log file is separate drive.
3. If Logfile size is too big then restrict their size and insert another LDF.
4. If Avg disk Queue length is greater then One then it would lead Disk Bottleneck. need to check DISK raid level as well.
Thanks
Kapil Mahershi