T-SQL tirsdag #67:Ny sikkerhedskopiering og gendannelse af udvidede begivenheder

Til sidste måneds T-SQL-tirsdag skrev jeg om nogle udokumenterede sporingsflag, der hjælper dig med at passe på langvarige backup- og gendannelsesoperationer. Denne måned er Jes Borlands emne Extended Events, og jeg tænkte, at jeg ville vise nye muligheder i SQL Server 2016, der stort set gør disse sporingsflag forældede.

Hvis du spiller med CTP2 (du kan downloade det her), vil du muligvis bemærke en ny kategori backup_restore og ny hændelse backup_restore_progress_trace :

Opdagelse af en ny begivenhed i CTP2's dialogboks Ny session

Her er en hurtig og beskidt XE-session til at fange dataene for denne begivenhed (jeg har tilføjet kommentarer til filtrering til kun at tage backup eller kun gendannelsesoperationer; som standard er begge inkluderet):

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

Lad os nu sige, at jeg kører følgende operationer – opret en database, læg en smule data tilbage, slip den og gendan den:

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;

Nu kan vi forespørge dataene fra begivenhedsmålfilen:

;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;

For en sikkerhedskopi undertrykker sporingsflag 3226 ikke noget af det output, der fanges af udvidede hændelser. Jeg har udeladt outputkolonner på grund af filtrene for kortheds skyld:

Hændelsesdata til en sikkerhedskopiering

For en gendannelse vil du se disse rækker:

Hændelsesdata til en gendannelseshandling

Hvis du fejlfinder en langsom sikkerhedskopiering eller gendannelse, kan du nemt filtrere på varigheden, så du f.eks. kun ser hændelser, der tog længere tid end n millisekunder. Det eneste, jeg ikke kan se i dette output, er nogen måde at fortælle, om øjeblikkelig filinitialisering var i kraft under gendannelsen – du har muligvis stadig brug for sporingsflag 3004, som beskrevet i mit indlæg til sidste måneds T-SQL-tirsdag.

Glem ikke at stoppe sessionen (men behold gerne sessionsdefinitionen på serveren, så du kan bruge den igen):

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

Jeg udførte ikke nogen præstationstest eller konsekvensanalyse, men generelt vil jeg sige, at - ligesom sporingsflaggene - dette ikke er noget, du ønsker at køre hele tiden, men kun ved fejlfinding af en specifik operation. Det er lidt nemmere at konfigurere denne session og forespørge dataene, IMHO, end at slå sporingsflag til og analysere alt output fra SQL Servers fejllog.