T-SQL úterý #67:Nové zálohování a obnovení rozšířených událostí

Pro úterý T-SQL z minulého měsíce jsem psal o některých nezdokumentovaných příznacích trasování, které vám pomohou hlídat dlouhotrvající operace zálohování a obnovy. Tento měsíc je tématem Jes Borland Extended Events a napadlo mě, že ukážu nové možnosti v SQL Server 2016, díky kterým jsou tyto příznaky trasování z velké části zastaralé.

Pokud hrajete s CTP2 (můžete si jej stáhnout zde), můžete si všimnout nové kategorie backup_restore a nová událost backup_restore_progress_trace :

Zjištění nové události v dialogovém okně Nová relace CTP2

Zde je rychlá a nečistá relace XE pro zachycení dat pro tuto událost (přidal jsem komentáře pro filtrování pouze pro operace zálohování nebo obnovy; ve výchozím nastavení jsou zahrnuty obě):

CREATE EVENT SESSION [Backup progress] ON SERVER 
ADD EVENT sqlserver.backup_restore_progress_trace
(
    ACTION(package0.event_sequence)
 
    -- to only capture backup operations:
    --WHERE [operation_type] = 0
 
    -- to only capture restore operations:
    --WHERE [operation_type] = 1 
)
ADD TARGET package0.event_file 
(
  SET filename = N'C:\temp\BackupProgress.xel'
); -- default options are probably ok
GO
 
ALTER EVENT SESSION [Backup progress] ON SERVER STATE = START;
GO

Nyní řekněme, že spustím následující operace – vytvořím databázi, zálohuji část dat, zahodím je a obnovím:

USE [master];
GO
CREATE DATABASE floob;
GO
SELECT s1.* INTO floob.dbo.what 
  FROM sys.all_objects AS s1 
  CROSS JOIN sys.all_objects;
GO
BACKUP DATABASE floob TO DISK = 'c:\temp\floob.bak' 
  WITH INIT, COMPRESSION, STATS = 30;
GO
DROP DATABASE floob;
GO
RESTORE DATABASE floob FROM DISK = 'c:\temp\floob.bak' 
  WITH REPLACE, RECOVERY;

Nyní se můžeme dotazovat na data z cílového souboru události:

;WITH x AS 
(
  SELECT ts,op,db,msg,es
  FROM 
  (
   SELECT 
    ts  = x.value(N'(event/@timestamp)[1]', N'datetime2'),
    op  = x.value(N'(event/data[@name="operation_type"]/text)[1]', N'nvarchar(32)'),
    db  = x.value(N'(event/data[@name="database_name"])[1]', N'nvarchar(128)'),
    msg = x.value(N'(event/data[@name="trace_message"])[1]', N'nvarchar(max)'),
    es  = x.value(N'(event/action[@name="event_sequence"])[1]', N'int')
   FROM 
   (
    SELECT x = CONVERT(XML, event_data) 
     FROM sys.fn_xe_file_target_read_file
          (N'c:\temp\Backup--Progress*.xel', NULL, NULL, NULL)
   ) AS y
  ) AS x 
  WHERE op = N'Backup' -- N'Restore'
  AND db = N'floob'
  AND ts > CONVERT(DATE, SYSUTCDATETIME())
)
SELECT /* x.db, x.op, x.ts, */ 
  [Message] = x.msg, 
  Duration = COALESCE(DATEDIFF(MILLISECOND, x.ts, 
             LEAD(x.ts, 1) OVER(ORDER BY es)),0)
FROM x
ORDER BY es;

U zálohy příznak trasování 3226 nepotlačuje žádný výstup zachycený rozšířenými událostmi. Kvůli stručnosti jsem kvůli filtrům vynechal výstupní sloupce:

Data událostí pro operaci zálohování

Pro obnovení uvidíte tyto řádky:

Data události pro operaci obnovení

Pokud řešíte problémy s pomalou operací zálohování nebo obnovy, můžete snadno filtrovat dobu trvání, takže uvidíte pouze události, které trvaly například déle než n milisekund. Jediná věc, kterou v tomto výstupu nevidím, je způsob, jak zjistit, zda během obnovy proběhla okamžitá inicializace souboru – stále možná budete potřebovat příznak trasování 3004, jak je popsáno v mém příspěvku pro úterý T-SQL z minulého měsíce.

Nezapomeňte zastavit relaci (ale klidně ponechte definici relace na serveru, abyste ji mohli znovu použít):

ALTER EVENT SESSION [Backup progress] ON SERVER STATE = STOP;

Neprováděl jsem žádné testy výkonu ani analýzu dopadu, ale obecně bych řekl, že – stejně jako příznaky trasování – to není něco, co byste chtěli spouštět neustále, ale pouze při odstraňování problémů s konkrétní operací. Je o něco snazší nastavit tuto relaci a dotazovat se na data, IMHO, než zapnout příznaky trasování a analyzovat veškerý výstup z protokolu chyb serveru SQL Server.