![\"twitter\"](\"https:\/\/blog.capdata.fr\/wp-content\/plugins\/social-media-feather\/synved-social\/image\/social\/regular\/48x48\/twitter.png\" "\\"Share")
<\/a><\/a>![\"linkedin\"](\"https:\/\/blog.capdata.fr\/wp-content\/plugins\/social-media-feather\/synved-social\/image\/social\/regular\/48x48\/linkedin.png\" "\\"Share")
<\/a>![\"mail\"](\"https:\/\/blog.capdata.fr\/wp-content\/plugins\/social-media-feather\/synved-social\/image\/social\/regular\/48x48\/mail.png\" "\\"Share")
<\/a>Deuxi\u00e8me \u00e9pisode de la trilogie sur SQLDIAG et les outils avanc\u00e9s de diagnostic autour de SQL Server. Nous parlerons aujourd’hui du r\u00e9sultat de la trace lanc\u00e9e lors du rapport pr\u00e9c\u00e9dent<\/a> et de son contenu. Nous verrons comment charger le contenu de la trace SQL et du fichier BLG dans SQL Profiler pour interpr\u00e9tation. Puis nous verrons comment mettre en place RML Utilities pour agr\u00e9ger et afficher le r\u00e9sultat d’une trace SQLDIAG.<\/p>\n Une fois la trace termin\u00e9e, Ctrl-C stoppe le collecteur et commence \u00e0 \u00e9crire les fichiers r\u00e9sultats sous l’arborescence. On retrouve une arborescence telle que:<\/p>\n Les deux axes qui vont nous int\u00e9resser sont principalement les fichiers de trace SQL et le fichier BLG. Les autres fichiers seront plus \u00e0 utiliser \u00e0 des fins de diagnostic.<\/p>\n Il est possible de charger un fichier de compteurs perfmon et une trace SQL dans SQL Profiler pour corr\u00e9lation. Ce n’est possible toutefois que si les deux traces ont \u00e9t\u00e9 g\u00e9n\u00e9r\u00e9es ensembles, le plus souvent \u00e0 l’aide d’un outil comme SQLDIAG ou PSSDIAG. Tout d’abord, ouvrir la trace SQL dans Profiler, puis File->Import Performance Data…<\/em> S\u00e9lectionner le fichier BLG g\u00e9n\u00e9r\u00e9 dans la m\u00eame trace, puis les compteurs \u00e0 charger (pour plus de lisibilit\u00e9, on ne prendra que les compteurs disque dans notre cas), et importer la trace:<\/p>\n L’\u00e9cran se divise en quatre parties. Les zones extr\u00eames repr\u00e9sentent l’affichage classique de SQL Profiler: texte et statistiques des requ\u00eates. La partie centrale pr\u00e9sente les graphes associ\u00e9s aux compteurs du fichier BLG, et la partie inf\u00e9rieure la liste des compteurs avec les valeurs Min, Max, Average:<\/p>\n Il est possible de zoomer et de d\u00e9placer le curseur temporel dans la partie centrale, et Profiler va pointer sur les requ\u00eates ex\u00e9cut\u00e9es \u00e0 l’instant s\u00e9lectionn\u00e9, et la valeur de ‘Selected<\/em>‘ dans la partie inf\u00e9rieure est \u00e9galement mise \u00e0 jour pour chaque compteur.<\/p>\n Maintenant il faut bien faire attention \u00e0 ce que l’on lit ou ce que l’on interpr\u00e8te. Il faut bien se rendre compte que SQL Trace collecte les statistiques pour un \u00e9v\u00e8nement une fois qu’il s’est termin\u00e9, donc ce n’est pas parce qu’on va pointer sur un pic CPU ou un de temps de service disque\u00a0 qu’on va tomber pile sur le probl\u00e8me. On va dire que \u00e7a facilite la t\u00e2che, \u00e7a permet de corr\u00e9ler des informations, mais \u00e7a ne fait pas le travail \u00e0 votre place. Une requ\u00eate peut commencer \u00e0 faire des lectures physiques depuis le disque pendant une minute, puis faire d’autres op\u00e9rations en m\u00e9moire (des tris, des agr\u00e9gations) et se terminer un certain temps\u00a0 apr\u00e8s le pic. Un petit exemple d’une requ\u00eate qui fait des acc\u00e8s physiques pendant 1 minute et qui se termine \u00e0 la fin du pic. Si on ne regarde pas bien, on rate l’information:<\/p>\n RML Utilities est une petite application due, comme pas mal d’outils et d’avanc\u00e9es sur le moteur, \u00e0 l’initiative de l’\u00e9quipe Customer Support Services<\/a>. La premi\u00e8re version de RML s’appuyait sur deux binaires Ostress et ReadTrace<\/a> principalement, elle a \u00e9t\u00e9 refondue en 2007 pour incorporer une petite application d’affichage des traces, bas\u00e9e sur des reports RDL. Les versions x86 et x64 sont t\u00e9l\u00e9chargeables depuis la page d’accueil de RML<\/a>. L’installation n’a rien de compliqu\u00e9, donc on va passer directement \u00e0 la phase d’int\u00e9gration des traces.<\/p>\n Cette int\u00e9gration se fait avec ReadTrace. Tout d’abord, on va cr\u00e9er une base pour stocker les donn\u00e9es des traces:<\/p>\n Puis ReadTrace avec les options -I<chemin vers fichier trc initial> -S<instance> -d<Baserepository> (important de ne pas laisser d’espace entre le commutateur et le param\u00e8tre)<\/p>\n Les deux warnings indiquent juste qu’il va manquer certains \u00e9v\u00e8nements, notamment les \u00e9v\u00e8nements pour pouvoir faire un replay dans Profiler.\u00a0 A la fin du chargement, l’application ‘Reporter<\/em>‘ est lanc\u00e9e et permet de visualiser les informations de la trace:<\/p>\n Ensuite, il est assez simple de naviguer dans l’interface et de visualiser l’information agr\u00e9g\u00e9e, par exemple en cliquant sur ‘Unique Batches’<\/em>:<\/p>\nContenu d’une trace SQLDIAG:<\/h2>\n
<NOM TRACE>\r\n|___ log_*.trc\r\n|___ MS2K8-WIN2008-1__sp_sqldiag_Shutdown.OUT\r\n|___ MS2K8-WIN2008-1_MSINFO32.txt\r\n|___ MS2K8-WIN2008-1_SQLDIAG__sp_trace_*.trc\r\n|___ SQLDIAG.lbg\r\n|___ internal\r\n\u00a0\u00a0\u00a0\u00a0 |___ *.txt\r\n\u00a0\u00a0\u00a0\u00a0 |___ *.OUT\r\n<\/span><\/pre>\n\n
\n
\n
\n
\n
\n
Charger une trace SQL et un fichier BLG dans SQL Profiler:<\/strong><\/h2>\n
![\"\"](\"https:\/\/blog.capdata.fr\/wp-content\/uploads\/2010\/05\/SQLDIAG11.jpg\" "\\"SQLDIAG1\\"")
<\/a><\/p>\n![\"\"](\"https:\/\/blog.capdata.fr\/wp-content\/uploads\/2010\/05\/SQLDIAG21-1024x703.jpg\" "\\"SQLDIAG2\\"")
<\/a><\/p>\n![\"\"](\"https:\/\/blog.capdata.fr\/wp-content\/uploads\/2010\/05\/SQLDIAG3-1024x460.jpg\" "\\"SQLDIAG3\\"")
<\/a><\/p>\nRML Utilities:<\/h2>\n
create database RMLDB<\/span><\/pre>\n$ .\/ReadTrace -IE:\/SQLDIAG\/TRACE3\/SQL2008__sp_trace.trc -SMS2K8-Win2008-1 -E -dRMLDB<\/span>\r\n\r\n05\/10\/10 17:13:20.920 [0X000013B4] Output path was not specified; defaulting to C:\\Users\\capdata\\AppData\\Local\\Temp\\2\\\\output\r\n05\/10\/10 17:13:21.000 [0X000013B4] Readtrace a SQL Server trace processing utility.\r\nVersion 9.01.0109 built for x86.\r\nCopyright (c) Microsoft Corporation 1997-2008. All rights reserved.\r\n05\/10\/10 17:13:21.002 [0X000013B4] Number of processors: 1\r\n05\/10\/10 17:13:21.002 [0X000013B4]\u00a0\u00a0\u00a0\u00a0 Active proc mask: 0x00000001\r\n05\/10\/10 17:13:21.008 [0X000013B4]\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0 Architecture: 0\r\n05\/10\/10 17:13:21.009 [0X000013B4]\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0 Page size: 4096\r\n05\/10\/10 17:13:21.009 [0X000013B4]\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0 Highest node: 0\r\n05\/10\/10 17:13:21.010 [0X000013B4]\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0 Package mask: 0x00000001\r\n05\/10\/10 17:13:21.011 [0X000013B4]\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0 Processor(s): 0x00000001 Function units: Separated\r\n05\/10\/10 17:13:21.011 [0X000013B4]\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0 Processors: 0x00000001 assigned to Numa node: 0\r\n05\/10\/10 17:13:21.012 [0X000013B4] -IE:\/SQLDIAG\/TRACE3\/SQL2008__sp_trace.trc\r\n05\/10\/10 17:13:21.012 [0X000013B4] -SMS2K8-Win2008-1\r\n05\/10\/10 17:13:21.013 [0X000013B4] -E\r\n05\/10\/10 17:13:21.013 [0X000013B4] -dRMLDB\r\n05\/10\/10 17:13:21.014 [0X000013B4] Using language id (LCID): 1024 [French_France.1252] for character formatting with NLS: 0x00500100 and Defined: 0x00050100\r\n05\/10\/10 17:13:21.015 [0X000013B4] Attempting to cleanup existing RML files from previous execution\r\n05\/10\/10 17:13:21.016 [0X000013B4] Using extended RowsetFastload synchronization\r\n05\/10\/10 17:13:21.017 [0X000013B4] Establishing initial database connection:\r\n05\/10\/10 17:13:21.017 [0X000013B4] Server: MS2K8-Win2008-1\r\n05\/10\/10 17:13:21.018 [0X000013B4] Database: RMLDB\r\n05\/10\/10 17:13:21.018 [0X000013B4] Authentication: Windows\r\n05\/10\/10 17:13:21.470 [0X000013B4] Using SQL Client version 10\r\n05\/10\/10 17:13:21.471 [0X000013B4] Creating or clearing the performance database\r\n05\/10\/10 17:13:22.916 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace.trc (SQL 2005)\r\n05\/10\/10 17:13:22.918 [0X000013B4] Validating core events exist\r\n05\/10\/10 17:13:22.919 [0X000013B4] Validating necessary events exist for analysis\r\n05\/10\/10 17:13:22.921 [0X000013B4] Validating necessary events exist for RML breakout\r\n05\/10\/10 17:13:22.922 [0X000013B4] WARNING: Event [Server: Server Memory Change] missing from trace\r\n05\/10\/10 17:13:22.923 [0X000013B4] WARNING REPLAY: The following trace events were not captured: [Sessions:ExistingConnection, Security Audit: Audit Login, Se\r\nrity Audit: Audit Logout, Stored Procedures: RPC Output Parameter, Cursors:Unprepare, Cursors:CursorClose, Cursors:CursorPrepare, Cursors:CursorCreated, Curso\r\n:CursorExecute, TSQL: Prepare SQL, TSQL: Unprepare SQL, Errors and Warnings:Attention, Transactions:SQLTransaction, Transactions:DTCTransaction, Transactions:\r\n:Begin Tran starting and completed, Transactions:TM:Commit Tran starting and completed, Transactions:TM:Rollback Tran starting and completed, Transactions:TM:\r\nve Tran starting and completed, Transactions:TM:Promote Tran starting and completed, SQL:BatchStarting and SQL:BatchCompleted, RPC:Starting and RPC:Completed]\r\nReview the help file to ensure that you have collected the appropriate set of events and columns for RML replay needs.\u00a0 Lack of these events can lead to sever\r\nreplay problems.\r\n\r\nTrace flag -T28 disables the check allowing RML output processing. *** Use with caution as the output and behavior could be unpredictable.\r\n05\/10\/10 17:13:22.993 [0X000013B4] Events Read: 1000 Queued: 1000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.026 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_1.trc (SQL 2005)\r\n05\/10\/10 17:13:23.038 [0X000013B4] Events Read: 2000 Queued: 2000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.070 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_2.trc (SQL 2005)\r\n05\/10\/10 17:13:23.082 [0X000013B4] Events Read: 3000 Queued: 3000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.261 [0X000013B4] Events Read: 4000 Queued: 4000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.271 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_3.trc (SQL 2005)\r\n05\/10\/10 17:13:23.284 [0X000013B4] Events Read: 5000 Queued: 5000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.328 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_4.trc (SQL 2005)\r\n05\/10\/10 17:13:23.342 [0X000013B4] Events Read: 6000 Queued: 6000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.361 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_5.trc (SQL 2005)\r\n05\/10\/10 17:13:23.366 [0X000013B4] Events Read: 7000 Queued: 7000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.396 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_6.trc (SQL 2005)\r\n05\/10\/10 17:13:23.406 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_7.trc (SQL 2005)\r\n05\/10\/10 17:13:23.475 [0X000013B4] Events Read: 8000 Queued: 8000 Processed\/sec: 0\r\n05\/10\/10 17:13:23.574 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_8.trc (SQL 2005)\r\n05\/10\/10 17:13:24.175 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_9.trc (SQL 2005)\r\n05\/10\/10 17:13:24.313 [0X000013B4] Events Read: 9000 Queued: 8998 Processed\/sec: 2\r\n05\/10\/10 17:13:24.854 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_10.trc (SQL 2005)\r\n05\/10\/10 17:13:24.905 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_11.trc (SQL 2005)\r\n05\/10\/10 17:13:24.939 [0X000013B4] Processing file: E:\\SQLDIAG\\TRACE3\\SQL2008__sp_trace_12.trc (SQL 2005)\r\n05\/10\/10 17:13:24.959 [0X000013B4] Found TRACE_STOP with 0 bytes of data\r\n05\/10\/10 17:13:24.961 [0X000013B4] Found [TRACE_STOP] event indicating the end of the trace files.\r\n05\/10\/10 17:13:24.962 [0X000013B4] Reads completed\r\n05\/10\/10 17:13:24.965 [0X000013B4] Shutting down the worker thread message queues.\r\n05\/10\/10 17:13:24.996 [0X000013B4] Waiting for the worker threads to complete final actions.\r\n05\/10\/10 17:13:26.074 [0X000013B4] Performing general cleanup actions.\r\n05\/10\/10 17:13:26.076 [0X000013B4] Reducing cached memory.\r\n05\/10\/10 17:13:26.096 [0X000013B4] Total Events Processed: 11117\r\n05\/10\/10 17:13:26.101 [0X000013B4]\u00a0 Total Events Filtered: 0\r\n05\/10\/10 17:13:26.102 [0X000013B4] Parse errors: 0\r\n05\/10\/10 17:13:26.224 [0X000013B4] Table ReadTrace.tblUniqueBatches: loaded ~445 rows\r\n05\/10\/10 17:13:26.225 [0X000013B4] Table ReadTrace.tblUniqueStatements: loaded ~0 rows\r\n05\/10\/10 17:13:26.225 [0X000013B4] Table ReadTrace.tblUniquePlans: loaded ~0 rows\r\n05\/10\/10 17:13:26.226 [0X000013B4] Table ReadTrace.tblUniquePlanRows: loaded ~0 rows\r\n05\/10\/10 17:13:26.241 [0X000013B4] Table ReadTrace.tblBatches: loaded ~4355 rows\r\n05\/10\/10 17:13:26.242 [0X000013B4] Table ReadTrace.tblStatements: loaded ~0 rows\r\n05\/10\/10 17:13:26.243 [0X000013B4] Table ReadTrace.tblPlans: loaded ~0 rows\r\n05\/10\/10 17:13:26.244 [0X000013B4] Table ReadTrace.tblPlanRows: loaded ~0 rows\r\n05\/10\/10 17:13:26.257 [0X000013B4] Table ReadTrace.tblInterestingEvents: loaded ~3 rows\r\n05\/10\/10 17:13:26.258 [0X000013B4] Table ReadTrace.tblConnections: loaded ~0 rows\r\n05\/10\/10 17:13:26.262 [0X000013B4] WARNING: One or more warning conditions exist that may affect the quality of the analysis data.\u00a0 See RMLDB.ReadTrace.tblWar\r\nngs table and the ReadTrace log for complete details.\r\n05\/10\/10 17:13:26.269 [0X000013B4] Indexing tables...\r\n05\/10\/10 17:13:27.413 [0X000013B4] Doing post-load data cleanup...\r\n05\/10\/10 17:13:27.863 [0X000013B4] Computing partial aggregates...\r\n05\/10\/10 17:13:28.977 [0X000013B4] Data load completed.\r\n05\/10\/10 17:13:28.979 [0X000013B4] Using execution path: c:\\Program Files\\Microsoft Corporation\\RMLUtils\r\n05\/10\/10 17:13:28.980 [0X000013B4] Launching [c:\\Program Files\\Microsoft Corporation\\RMLUtils\\Reporter.exe]\r\n05\/10\/10 17:13:29.253 [0X000013B4] *******************************************************************************\r\n* ReadTrace encountered one or more WARNINGS. A warning condition typically\u00a0\u00a0 *\r\n* continues processing with reduced functionality, but the ReadTrace output\u00a0\u00a0 *\r\n* may be adversely affected. Review the log file for details.\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0\u00a0 *\r\n*******************************************************************************\r\n05\/10\/10 17:13:29.324 [0X000013B4]\r\n<\/em><\/span>\u00a0<\/span><\/pre>\n![\"\"](\"https:\/\/blog.capdata.fr\/wp-content\/uploads\/2010\/05\/RML1.jpg\" "\\"RML1\\"")
<\/a><\/p>\n
![\"\"](\"https:\/\/blog.capdata.fr\/wp-content\/uploads\/2010\/05\/RML2.jpg\" "\\"RML2\\"")
<\/a><\/p>\n![\"\"](\"https:\/\/blog.capdata.fr\/wp-content\/uploads\/2010\/05\/RML4.jpg\" "\\"RML4\\"")
<\/a><\/p>\n