This project is read-only.

RML syntax error processing trace.

Dec 7, 2010 at 2:54 PM

Hi,

I'm having the following error when i try to import a particular trace:


[RML] C:\Documents and Settings\josemyj\Escritorio\Datos\Dirairo>Readtrace -ITrazaSentencias2.trc -Saxpresql2 -E -f

12/07/10 15:07:07.742 [0X00001394] Output path was not specified; defaulting to C:\Documents and Settings\josemyj\Configuraci¾n local\Temp\\output
12/07/10 15:07:07.758 [0X00001394] Readtrace a SQL Server trace processing utility.
Version 9.01.0109 built for x86.
Copyright (c) Microsoft Corporation 1997-2008. All rights reserved.
12/07/10 15:07:07.758 [0X00001394] Number of processors: 2
12/07/10 15:07:07.758 [0X00001394]     Active proc mask: 0x00000003
12/07/10 15:07:07.758 [0X00001394]         Architecture: 0
12/07/10 15:07:07.758 [0X00001394]            Page size: 4096
12/07/10 15:07:07.758 [0X00001394]         Highest node: 0
12/07/10 15:07:07.758 [0X00001394]         Processor(s): 0x00000001 Function units: Separated
12/07/10 15:07:07.758 [0X00001394]         Package mask: 0x00000003
12/07/10 15:07:07.758 [0X00001394]         Processor(s): 0x00000002 Function units: Separated
12/07/10 15:07:07.758 [0X00001394]           Processors: 0x00000003 assigned to Numa node: 0
12/07/10 15:07:07.758 [0X00001394] -ITrazaSentencias2.trc
12/07/10 15:07:07.774 [0X00001394] -Saxpresql2
12/07/10 15:07:07.774 [0X00001394] -E
12/07/10 15:07:07.774 [0X00001394] -f
12/07/10 15:07:07.774 [0X00001394] Using language id (LCID): 1024 [English_United States.1252] for character formatting
12/07/10 15:07:07.774 [0X00001394] Attempting to cleanup existing RML files from previous execution
12/07/10 15:07:07.774 [0X00001394] Using extended RowsetFastload synchronization
12/07/10 15:07:07.774 [0X00001394] Establishing initial database connection:
12/07/10 15:07:07.774 [0X00001394] Server: axpresql2
12/07/10 15:07:07.774 [0X00001394] Database: PerfAnalysis
12/07/10 15:07:07.774 [0X00001394] Authentication: Windows
12/07/10 15:07:08.086 [0X00001394] Using SQL Client version 10
12/07/10 15:07:08.086 [0X00001394] Creating or clearing the performance database
12/07/10 15:07:08.946 [0X00001394] Processing file: C:\Documents and Settings\josemyj\Escritorio\Datos\Dirairo\TrazaSentencias2.trc (SQL 2005)
12/07/10 15:07:08.946 [0X00001394] Validating core events exist
12/07/10 15:07:08.946 [0X00001394] Validating necessary events exist for analysis
12/07/10 15:07:08.946 [0X00001394] WARNING: The following trace events were not captured: [SQL:BatchStarting, RPC:Starting, SP:StmtStarting, Audit:Log
in, Audit:Logout, Showplan Statistics]. Review the help file to ensure that you have collected the appropriate set of events and columns for your inte
nded analysis.
12/07/10 15:07:08.977 [0X00001394] Events Read: 1000 Queued: 1000 Processed/sec: 0
12/07/10 15:07:09.009 [0X00001394] Events Read: 2000 Queued: 1685 Processed/sec: 315
12/07/10 15:07:09.040 [0X00001394] Events Read: 3000 Queued: 2454 Processed/sec: 546
12/07/10 15:07:09.071 [0X00001394] Events Read: 4000 Queued: 2303 Processed/sec: 1697
12/07/10 15:07:09.087 [0X00001394] Events Read: 5000 Queued: 2720 Processed/sec: 2280
12/07/10 15:07:09.102 [0X00001394] Events Read: 6000 Queued: 3473 Processed/sec: 2527
12/07/10 15:07:09.149 [0X00001394] Events Read: 7000 Queued: 2998 Processed/sec: 4002
12/07/10 15:07:09.181 [0X00001394] Events Read: 8000 Queued: 3662 Processed/sec: 4338
12/07/10 15:07:09.196 [0X00001394] Events Read: 9000 Queued: 4046 Processed/sec: 4954
12/07/10 15:07:09.603 [0X00001394] Events Read: 25000 Queued: 8031 Processed/sec: 16969
12/07/10 15:07:10.447 [0X00001394] Events Read: 50000 Queued: 8116 Processed/sec: 41884
12/07/10 15:07:11.119 [0X00001394] Events Read: 75000 Queued: 12006 Processed/sec: 31497
12/07/10 15:07:11.353 [0X00001394] *** ERROR: Error encountered with operating system error 0x80004005 (Error no especificado) State: 0
12/07/10 15:07:11.353 [0X00001394] Reads completed - Global Error Status 0xffffffe7
12/07/10 15:07:11.369 [0X00001394] Shutting down the worker thread message queues.
12/07/10 15:07:11.369 [0X00001394] Waiting for the worker threads to complete final actions.
12/07/10 15:07:11.385 [0X00001394] Performing general cleanup actions.
12/07/10 15:07:11.385 [0X00001394] Total Events Processed: 82066
12/07/10 15:07:11.385 [0X00001394]  Total Events Filtered: 0
12/07/10 15:07:11.385 [0X00001394] Parse errors: 0
12/07/10 15:07:11.432 [0X00001394] *******************************************************************************
* ReadTrace encountered one or more ERRORS. An error condition typically      *
* stops processing early and the ReadTrace output may be unusable.            *
* Review the log file for details.                                            *
*******************************************************************************
12/07/10 15:07:11.447 [0X00001394] ***** ReadTrace exit code: -25
12/07/10 15:07:11.494 [0X00001394]


Under SQL Server error log, the error logged is this:


Date        12/7/2010 3:07:12 PM
Log        SQL Server Agent (Current - 12/7/2010 3:07:00 PM)

Message
[264] An attempt was made to send an email when no email session has been established


Pretty strange as I'm the only user of this test instance (virtual server), only operations are the RML ReadTrace ones and e-mail profile is correctly configured.

Under windows log I find this error also:


A fatal error occurred while reading the input stream from the network. The session will be terminated.

For more information, see Help and Support Center at http://go.microsoft.com/fwlink/events.asp.

Event ID 4014.


Of course, other traces with the same Events are correctly imported. Can someone help me identify what may be causing this particular error?

The only server difference that I recall is that the trace is from a CASE SENSITIVE database. But I Think is not related.

Thanks anyway,

Jose Mª Yohn

 

 

Dec 15, 2010 at 11:17 AM
Edited Dec 15, 2010 at 11:40 AM

Did you start the import with the second file (TrazaSentencias2.trc) or the first file was already imported successfully? 0x80004005 generally means an access denied. Can you check if you can import the same file using fn_trace_gettable. Also, can you try the import from SQL Nexus using the following: 

In SQL Nexus, on import box, go to Options > ReadTrace import > use -T35

Dec 16, 2010 at 9:38 AM

Hi,

Thanks for the reply.

Looks like it's not a local access grants issue (SQLServer server is a remote machine). Local machine is an XP and user is local administrator. File opened also in Profiler without errors. I used -T35 option, no change on error message:


12/16/10 10:04:45.809 [0X00000878] Output path was not specified; defaulting to C:\Documents and Settings\josemyj\Configuraci¾n local\Temp\\output
12/16/10 10:04:45.825 [0X00000878] Readtrace a SQL Server trace processing utility.
Version 9.01.0109 built for x86.
Copyright (c) Microsoft Corporation 1997-2008. All rights reserved.
12/16/10 10:04:45.825 [0X00000878] Number of processors: 2
12/16/10 10:04:45.825 [0X00000878]     Active proc mask: 0x00000003
12/16/10 10:04:45.825 [0X00000878]         Architecture: 0
12/16/10 10:04:45.825 [0X00000878]            Page size: 4096
12/16/10 10:04:45.825 [0X00000878]         Highest node: 0
12/16/10 10:04:45.825 [0X00000878]         Processor(s): 0x00000001 Function units: Separated
12/16/10 10:04:45.825 [0X00000878]         Package mask: 0x00000003
12/16/10 10:04:45.825 [0X00000878]         Processor(s): 0x00000002 Function units: Separated
12/16/10 10:04:45.825 [0X00000878]           Processors: 0x00000003 assigned to Numa node: 0
12/16/10 10:04:45.825 [0X00000878] -ITrazaSentencias2.trc
12/16/10 10:04:45.825 [0X00000878] -Saxpresql2
12/16/10 10:04:45.825 [0X00000878] -E
12/16/10 10:04:45.825 [0X00000878] -f
12/16/10 10:04:45.825 [0X00000878] -T35
12/16/10 10:04:45.825 [0X00000878] WARNING: -T35 enabled, SPID IDs simulated using formula [ (SPID * 10) + BATCHID ]
12/16/10 10:04:45.825 [0X00000878] Using language id (LCID): 1024 [English_United States.1252] for character formatting
12/16/10 10:04:45.825 [0X00000878] Attempting to cleanup existing RML files from previous execution
12/16/10 10:04:45.825 [0X00000878] Using extended RowsetFastload synchronization
12/16/10 10:04:45.825 [0X00000878] Establishing initial database connection:
12/16/10 10:04:45.825 [0X00000878] Server: axpresql2
12/16/10 10:04:45.825 [0X00000878] Database: PerfAnalysis
12/16/10 10:04:45.825 [0X00000878] Authentication: Windows
12/16/10 10:04:46.215 [0X00000878] Using SQL Client version 10
12/16/10 10:04:46.215 [0X00000878] Creating or clearing the performance database
12/16/10 10:04:47.371 [0X00000878] Processing file: C:\Documents and Settings\josemyj\Escritorio\Datos\Dirairo\TrazaSentencias2.trc (SQL 2005)
12/16/10 10:04:47.371 [0X00000878] Validating core events exist
12/16/10 10:04:47.371 [0X00000878] Validating necessary events exist for analysis
12/16/10 10:04:47.371 [0X00000878] WARNING: The following trace events were not captured: [SQL:BatchStarting, RPC:Starting, SP:StmtStarting, Audit:Log
in, Audit:Logout, Showplan Statistics]. Review the help file to ensure that you have collected the appropriate set of events and columns for your inte
nded analysis.
12/16/10 10:04:47.403 [0X00000878] Events Read: 1000 Queued: 998 Processed/sec: 2
12/16/10 10:04:47.418 [0X00000878] Events Read: 2000 Queued: 1996 Processed/sec: 4
12/16/10 10:04:47.528 [0X00000878] Events Read: 3000 Queued: 2994 Processed/sec: 6
12/16/10 10:04:47.559 [0X00000878] Events Read: 4000 Queued: 3663 Processed/sec: 337
12/16/10 10:04:47.575 [0X00000878] Events Read: 5000 Queued: 4181 Processed/sec: 819
12/16/10 10:04:47.606 [0X00000878] Events Read: 6000 Queued: 4521 Processed/sec: 1479
12/16/10 10:04:47.637 [0X00000878] Events Read: 7000 Queued: 4908 Processed/sec: 2092
12/16/10 10:04:47.653 [0X00000878] Events Read: 8000 Queued: 5619 Processed/sec: 2381
12/16/10 10:04:47.684 [0X00000878] Events Read: 9000 Queued: 6250 Processed/sec: 2750
12/16/10 10:04:48.106 [0X00000878] Events Read: 25000 Queued: 9760 Processed/sec: 15240
12/16/10 10:04:48.950 [0X00000878] Events Read: 50000 Queued: 11391 Processed/sec: 38609
12/16/10 10:04:49.793 [0X00000878] Events Read: 75000 Queued: 9694 Processed/sec: 32653
12/16/10 10:04:50.028 [0X00000878] *** ERROR: Error encountered with operating system error 0x80004005 (Error no especificado) State: 0
12/16/10 10:04:50.043 [0X00000878] Reads completed - Global Error Status 0xffffffe7
12/16/10 10:04:50.059 [0X00000878] Shutting down the worker thread message queues.
12/16/10 10:04:50.075 [0X00000878] Waiting for the worker threads to complete final actions.
12/16/10 10:04:50.075 [0X00000878] Performing general cleanup actions.
12/16/10 10:04:50.075 [0X00000878] Total Events Processed: 82066
12/16/10 10:04:50.075 [0X00000878]  Total Events Filtered: 0
12/16/10 10:04:50.075 [0X00000878] Parse errors: 0
12/16/10 10:04:50.106 [0X00000878] *******************************************************************************
* ReadTrace encountered one or more ERRORS. An error condition typically      *
* stops processing early and the ReadTrace output may be unusable.            *
* Review the log file for details.                                            *
*******************************************************************************
12/16/10 10:04:50.106 [0X00000878] ***** ReadTrace exit code: -25
12/16/10 10:04:50.153 [0X00000878]

I executed the trace import from Server with no error:

 

SELECT * INTO #temp_trc
FROM fn_trace_gettable
(N'd:\Transfer\TrazaSentencias2.trc',1)

SQL Server parse and compile time:
   CPU time = 0 ms, elapsed time = 5 ms.
Table 'Worktable'. Scan count 1, logical reads 4104299, physical reads 0, read-ahead reads 0, lob logical reads 3112168, lob physical reads 0, lob read-ahead reads 14502.

SQL Server Execution Times:
   CPU time = 145719 ms,  elapsed time = 159754 ms.

(1223640 row(s) affected)
SQL Server parse and compile time:
   CPU time = 0 ms, elapsed time = 1 ms.

SQL Server Execution Times:
   CPU time = 0 ms,  elapsed time = 1 ms.

Still no clue of error.

 

Dec 16, 2010 at 7:57 PM

Can you check if you can import the trace files after copying the trace files locally? If that doesn't work, can you please mail the ReadTrace.log to sstlbugs@microsoft.com.

Dec 17, 2010 at 9:48 AM

Hi amitban,

I'm already importing traces locally. Our servers are dedicated and we do not perform operations on them. Neither RML Utils and SQLNexus are on production server. Allways in local PCs. SQLNexus database and PerfAnalisys database are on test servers. So now i installed Reporter and RML utilities on AXPRESQL2 (The dedicated SQL Server Test Machine) and we have the same error:

[RML] D:\Transfer>Readtrace -ITrazaSentencias2.trc -Saxpresql2 -E -f

12/17/10 10:45:16.733 [0X00007408] Output path was not specified; defaulting to C:\Documents and Settings\SQLServer\Local S
12/17/10 10:45:16.779 [0X00007408] Readtrace a SQL Server trace processing utility.
Version 9.01.0109 built for x86.
Copyright (c) Microsoft Corporation 1997-2008. All rights reserved.
12/17/10 10:45:16.779 [0X00007408] Number of processors: 1
12/17/10 10:45:16.779 [0X00007408]     Active proc mask: 0x00000001
12/17/10 10:45:16.795 [0X00007408]         Architecture: 0
12/17/10 10:45:16.795 [0X00007408]            Page size: 4096
12/17/10 10:45:16.795 [0X00007408]         Highest node: 0
12/17/10 10:45:16.795 [0X00007408]         Processor(s): 0x00000001 Function units: Separated
12/17/10 10:45:16.795 [0X00007408]           Processors: 0x00000001 assigned to Numa node: 0
12/17/10 10:45:16.795 [0X00007408] -ITrazaSentencias2.trc
12/17/10 10:45:16.795 [0X00007408] -Saxpresql2
12/17/10 10:45:16.795 [0X00007408] -E
12/17/10 10:45:16.795 [0X00007408] -f
12/17/10 10:45:16.795 [0X00007408] Using language id (LCID): 1024 [English_United States.1252] for character formatting wit
0001
12/17/10 10:45:16.795 [0X00007408] Attempting to cleanup existing RML files from previous execution
12/17/10 10:45:16.795 [0X00007408] Using extended RowsetFastload synchronization
12/17/10 10:45:16.795 [0X00007408] Establishing initial database connection:
12/17/10 10:45:16.795 [0X00007408] Server: axpresql2
12/17/10 10:45:16.795 [0X00007408] Database: PerfAnalysis
12/17/10 10:45:16.795 [0X00007408] Authentication: Windows
12/17/10 10:45:16.951 [0X00007408] Creating or clearing the performance database
12/17/10 10:45:17.483 [0X00007408] Processing file: D:\Transfer\TrazaSentencias2.trc (SQL 2005)
12/17/10 10:45:17.498 [0X00007408] Validating core events exist
12/17/10 10:45:17.498 [0X00007408] Validating necessary events exist for analysis
12/17/10 10:45:17.498 [0X00007408] WARNING: The following trace events were not captured: [SQL:BatchStarting, RPC:Starting,
Logout, Showplan Statistics]. Review the help file to ensure that you have collected the appropriate set of events and colu
12/17/10 10:45:17.561 [0X00007408] Events Read: 1000 Queued: 488 Processed/sec: 512
12/17/10 10:45:17.608 [0X00007408] Events Read: 2000 Queued: 976 Processed/sec: 1024
12/17/10 10:45:17.639 [0X00007408] Events Read: 3000 Queued: 1464 Processed/sec: 1536
12/17/10 10:45:17.686 [0X00007408] Events Read: 4000 Queued: 1952 Processed/sec: 2048
12/17/10 10:45:17.779 [0X00007408] Events Read: 5000 Queued: 1928 Processed/sec: 3072
12/17/10 10:45:17.842 [0X00007408] Events Read: 6000 Queued: 1904 Processed/sec: 4096
12/17/10 10:45:17.889 [0X00007408] Events Read: 7000 Queued: 1880 Processed/sec: 5120
12/17/10 10:45:17.951 [0X00007408] Events Read: 8000 Queued: 1856 Processed/sec: 6144
12/17/10 10:45:18.014 [0X00007408] Events Read: 9000 Queued: 2344 Processed/sec: 6656
12/17/10 10:45:19.108 [0X00007408] Events Read: 25000 Queued: 1960 Processed/sec: 23040
12/17/10 10:45:20.639 [0X00007408] Events Read: 50000 Queued: 2966 Processed/sec: 15678
12/17/10 10:45:22.061 [0X00007408] Events Read: 75000 Queued: 4485 Processed/sec: 17628
12/17/10 10:45:22.498 [0X00007408] *** ERROR: Error encountered with operating system error 0x80004005 (Unspecified error)
12/17/10 10:45:22.498 [0X00007408] Reads completed - Global Error Status 0xffffffe7
12/17/10 10:45:22.514 [0X00007408] Shutting down the worker thread message queues.
12/17/10 10:45:22.514 [0X00007408] Waiting for the worker threads to complete final actions.
12/17/10 10:45:22.514 [0X00007408] Performing general cleanup actions.
12/17/10 10:45:22.514 [0X00007408] Total Events Processed: 82066
12/17/10 10:45:22.514 [0X00007408]  Total Events Filtered: 0
12/17/10 10:45:22.514 [0X00007408] Parse errors: 0
12/17/10 10:45:22.545 [0X00007408] *******************************************************************************
* ReadTrace encountered one or more ERRORS. An error condition typically      *
* stops processing early and the ReadTrace output may be unusable.            *
* Review the log file for details.                                            *
*******************************************************************************
12/17/10 10:45:22.545 [0X00007408] ***** ReadTrace exit code: -25
12/17/10 10:45:22.639 [0X00007408]
[RML] D:\Transfer>

 

 

Dec 28, 2010 at 5:06 PM

Error fixed with the version submitted by Microsoft.

I want to thank Microsoft sstl bug team. You have been clear and professional.

Keep up the good work.

Regards,

Jose Yohn.

 

Apr 25, 2011 at 7:37 PM
Edited Apr 25, 2011 at 7:44 PM

Hello. When I try to finish the execution of SLDIAG in a SQL2008 cluster, the DOS window remains suspended but continue to accumulate trace files. Finally, I choose to stop the trace from the SQL engine, and then send a command SQLDIAG STOP_ABORT.

When I try to read the traces from SQL Nexus, gives error:

04/25/11 14:29:47.746 [0X00002058] Readtrace a SQL Server trace processing utility.
Version 9.01.0109 built for x64.
Copyright (c) Microsoft Corporation 1997-2008. All rights reserved.
04/25/11 14:29:47.747 [0X00002058] Number of processors: 4
04/25/11 14:29:47.752 [0X00002058]     Active proc mask: 0x0000000F
04/25/11 14:29:47.752 [0X00002058]         Architecture: 9
04/25/11 14:29:47.752 [0X00002058]            Page size: 4096
04/25/11 14:29:47.752 [0X00002058]         Highest node: 1
04/25/11 14:29:47.752 [0X00002058]         Processor(s): 0x00000001 Function units: Separated
04/25/11 14:29:47.752 [0X00002058]         Package mask: 0x00000003
04/25/11 14:29:47.752 [0X00002058]         Processor(s): 0x00000002 Function units: Separated
04/25/11 14:29:47.752 [0X00002058]         Processor(s): 0x00000004 Function units: Separated
04/25/11 14:29:47.752 [0X00002058]         Package mask: 0x0000000C
04/25/11 14:29:47.753 [0X00002058]         Processor(s): 0x00000008 Function units: Separated
04/25/11 14:29:47.753 [0X00002058]           Processors: 0x00000003 assigned to Numa node: 0
04/25/11 14:29:47.753 [0X00002058]           Processors: 0x0000000C assigned to Numa node: 1
04/25/11 14:29:47.760 [0X00002058] -SANSESSQLVC1\SQL2A
04/25/11 14:29:47.760 [0X00002058] -dsqlnexus
04/25/11 14:29:47.760 [0X00002058] -E
04/25/11 14:29:47.761 [0X00002058] -T18
04/25/11 14:29:47.761 [0X00002058] -IE:\Performance\SQL Nexus\Templates\PerfStatsScript2008\SQLDiagOutput\ansessqlvc1_SQLDIAG_SQL2A_sp_trace.trc
04/25/11 14:29:47.761 [0X00002058] -f
04/25/11 14:29:47.761 [0X00002058] -oC:\Users\a023042\AppData\Local\Temp\5\RML
04/25/11 14:29:47.

Apr 25, 2011 at 7:51 PM

Hello. I'm trying to read multiple trace files into a SQL 2008 cluster. When I try to finish the execution of SLDIAG, the DOS window remains suspended but continue to accumulate trace files. Finally, I choose to stop the trace from the SQL engine, and then send a command SQLDIAG STOP_ABORT.

When from SQL Nexus try to read the traces, gives error:


04/25/11 14:29:47.746 [0X00002058] Readtrace a SQL Server trace processing utility.
Version 9.01.0109 built for x64.
Copyright (c) Microsoft Corporation 1997-2008. All rights reserved.
04/25/11 14:29:47.747 [0X00002058] Number of processors: 4
04/25/11 14:29:47.752 [0X00002058]     Active proc mask: 0x0000000F
04/25/11 14:29:47.752 [0X00002058]         Architecture: 9
04/25/11 14:29:47.752 [0X00002058]            Page size: 4096
04/25/11 14:29:47.752 [0X00002058]         Highest node: 1
04/25/11 14:29:47.752 [0X00002058]         Processor(s): 0x00000001 Function units: Separated
04/25/11 14:29:47.752 [0X00002058]         Package mask: 0x00000003
04/25/11 14:29:47.752 [0X00002058]         Processor(s): 0x00000002 Function units: Separated
04/25/11 14:29:47.752 [0X00002058]         Processor(s): 0x00000004 Function units: Separated
04/25/11 14:29:47.752 [0X00002058]         Package mask: 0x0000000C
04/25/11 14:29:47.753 [0X00002058]         Processor(s): 0x00000008 Function units: Separated
04/25/11 14:29:47.753 [0X00002058]           Processors: 0x00000003 assigned to Numa node: 0
04/25/11 14:29:47.753 [0X00002058]           Processors: 0x0000000C assigned to Numa node: 1
04/25/11 14:29:47.760 [0X00002058] -SANSESSQLVC1\SQL2A
04/25/11 14:29:47.760 [0X00002058] -dsqlnexus
04/25/11 14:29:47.760 [0X00002058] -E
04/25/11 14:29:47.761 [0X00002058] -T18
04/25/11 14:29:47.761 [0X00002058] -IE:\Performance\SQL Nexus\Templates\PerfStatsScript2008\SQLDiagOutput\ansessqlvc1_SQLDIAG_SQL2A_sp_trace.trc
04/25/11 14:29:47.761 [0X00002058] -f
04/25/11 14:29:47.761 [0X00002058] -oC:\Users\a023042\AppData\Local\Temp\5\RML
04/25/11 14:29:47.762 [0X00002058] -H!PSSDIAG
04/25/11 14:29:47.769 [0X00002058] Using language id (LCID): 1024 [English_United States.1252] for character formatting with NLS: 0x00500100 and Defined: 0x00050100
04/25/11 14:29:47.769 [0X00002058] <FILTER>
 <COLUMN NAME="HOST">
  <AND>
   <ARGUMENT>
    <TYPE>NOT EQUAL<TYPE>
    <VALUE>PSSDIAG<VALUE>
   </ARGUMENT>
  </AND>
 </COLUMN>
</FILTER>
04/25/11 14:29:47.771 [0X00002058] Attempting to cleanup existing RML files from previous execution
04/25/11 14:29:47.771 [0X00002058] Using extended RowsetFastload synchronization
04/25/11 14:29:47.772 [0X00002058] Establishing initial database connection:
04/25/11 14:29:47.772 [0X00002058] Server: ANSESSQLVC1\SQL2A
04/25/11 14:29:47.772 [0X00002058] Database: sqlnexus
04/25/11 14:29:47.772 [0X00002058] Authentication: Windows
04/25/11 14:29:48.202 [0X00002058] Using SQL Client version 10
04/25/11 14:29:48.203 [0X00002058] Creating or clearing the performance database
04/25/11 14:29:55.709 [0X00002058] Processing file: E:\Performance\SQL Nexus\Templates\PerfStatsScript2008\SQLDiagOutput\ansessqlvc1_SQLDIAG_SQL2A_sp_trace.trc (SQL 2008)
04/25/11 14:29:55.928 [0X00002058] Validating core events exist
04/25/11 14:29:56.068 [0X00002058] Validating necessary events exist for analysis
04/25/11 14:29:56.229 [0X00002058] ERROR: SPID was detected using Multiple Active Result Sets (MARS).  ReadTrace can only process this set of trace files with -T35 enabled
04/25/11 14:29:56.230 [0X00002058] ***************************************************************
04/25/11 14:29:56.230 [0X00002058] WARNING: [SPID: 0] Disabling aggregation operations.
04/25/11 14:29:56.230 [0X00002058] ***************************************************************
04/25/11 14:29:56.231 [0X00002058] ERROR: All output types have been disabled, shutting down.
04/25/11 14:29:56.257 [0X00002058] *** ERROR: Error encountered with operating system error 0x80004005 (Unspecified error) State: 0
04/25/11 14:29:56.258 [0X00002058] Reads completed - Global Error Status 0xffffffea
04/25/11 14:29:56.258 [0X00002058] Shutting down the worker thread message queues.
04/25/11 14:29:56.259 [0X00002058] Waiting for the worker threads to complete final actions.
04/25/11 14:29:58.981 [0X00002058] Performing general cleanup actions.
04/25/11 14:29:58.981 [0X00002058] Total Events Processed: 36
04/25/11 14:29:58.982 [0X00002058]  Total Events Filtered: 0
04/25/11 14:29:58.982 [0X00002058] Parse errors: 0
04/25/11 14:29:59.195 [0X00002058] *******************************************************************************
* ReadTrace encountered one or more ERRORS. An error condition typically      *
* stops processing early and the ReadTrace output may be unusable.            *
* Review the log file for details.                                            *
*******************************************************************************
04/25/11 14:29:59.197 [0X00002058] ***** ReadTrace exit code: -22


The traces can be read with fn_gettrace. Trace files have all necessary permissions. I also tried turning on and off-T35.

What's wrong?

Thanks,

Mario

Apr 25, 2011 at 7:58 PM

Your log shows the following:

04/25/11 14:29:47.761 [0X00002058] -T18

....

04/25/11 14:29:56.229 [0X00002058] ERROR: SPID was detected using Multiple Active Result Sets (MARS). ReadTrace can only process this set of trace files with -T35 enabled
04/25/11 14:29:56.230 [0X00002058] ***************************************************************
04/25/11 14:29:56.230 [0X00002058] WARNING: [SPID: 0] Disabling aggregation operations.
04/25/11 14:29:56.230 [0X00002058] ***************************************************************
04/25/11 14:29:56.231 [0X00002058] ERROR: All output types have been disabled, shutting down.

So, it seems that -T35 is not being set. Can you manually import the trace files using Readtrace.exe along with command line parameters:

readtrace.exe -S<server name> -d<database name> -I<first trace file name> -T35 -E

Apr 26, 2011 at 12:24 PM

Thanks amitban. The output when using the-T35 is this:


04/26/11 08:14:02.783 [0X00001C38] Readtrace a SQL Server trace processing utility.
Version 9.01.0109 built for x64.
Copyright (c) Microsoft Corporation 1997-2008. All rights reserved.
04/26/11 08:14:02.787 [0X00001C38] Number of processors: 4
04/26/11 08:14:02.794 [0X00001C38]     Active proc mask: 0x0000000F
04/26/11 08:14:02.794 [0X00001C38]         Architecture: 9
04/26/11 08:14:02.794 [0X00001C38]            Page size: 4096
04/26/11 08:14:02.794 [0X00001C38]         Highest node: 1
04/26/11 08:14:02.794 [0X00001C38]         Processor(s): 0x00000001 Function units: Separated
04/26/11 08:14:02.794 [0X00001C38]         Package mask: 0x00000003
04/26/11 08:14:02.794 [0X00001C38]         Processor(s): 0x00000002 Function units: Separated
04/26/11 08:14:02.795 [0X00001C38]         Processor(s): 0x00000004 Function units: Separated
04/26/11 08:14:02.795 [0X00001C38]         Package mask: 0x0000000C
04/26/11 08:14:02.795 [0X00001C38]         Processor(s): 0x00000008 Function units: Separated
04/26/11 08:14:02.795 [0X00001C38]           Processors: 0x00000003 assigned to Numa node: 0
04/26/11 08:14:02.795 [0X00001C38]           Processors: 0x0000000C assigned to Numa node: 1
04/26/11 08:14:02.795 [0X00001C38] -SANSESSQLVC1\SQL2A
04/26/11 08:14:02.795 [0X00001C38] -dsqlnexus
04/26/11 08:14:02.795 [0X00001C38] -E
04/26/11 08:14:02.796 [0X00001C38] -T18
04/26/11 08:14:02.796 [0X00001C38] -IE:\Performance\SQL Nexus\Templates\PerfStatsScript2008\SQLDiagOutput\ansessqlvc1_SQLDIAG_SQL2A_sp_trace.trc
04/26/11 08:14:02.796 [0X00001C38] -f
04/26/11 08:14:02.796 [0X00001C38] -oC:\Users\a023042\AppData\Local\Temp\5\RML
04/26/11 08:14:02.797 [0X00001C38] -H!PSSDIAG
04/26/11 08:14:02.797 [0X00001C38] -T35
04/26/11 08:14:02.797 [0X00001C38] WARNING: -T35 enabled, SPID IDs simulated using formula [ (SPID * 10) + BATCHID ]
04/26/11 08:14:02.810 [0X00001C38] Using language id (LCID): 1024 [English_United States.1252] for character formatting with NLS: 0x00500100 and Defined: 0x00050100
04/26/11 08:14:02.814 [0X00001C38] <FILTER>
 <COLUMN NAME="HOST">
  <AND>
   <ARGUMENT>
    <TYPE>NOT EQUAL<TYPE>
    <VALUE>PSSDIAG<VALUE>
   </ARGUMENT>
  </AND>
 </COLUMN>
</FILTER>
04/26/11 08:14:02.815 [0X00001C38] Attempting to cleanup existing RML files from previous execution
04/26/11 08:14:02.816 [0X00001C38] Using extended RowsetFastload synchronization
04/26/11 08:14:02.816 [0X00001C38] Establishing initial database connection:
04/26/11 08:14:02.817 [0X00001C38] Server: ANSESSQLVC1\SQL2A
04/26/11 08:14:02.817 [0X00001C38] Database: sqlnexus
04/26/11 08:14:02.817 [0X00001C38] Authentication: Windows
04/26/11 08:14:03.333 [0X00001C38] Using SQL Client version 10
04/26/11 08:14:03.334 [0X00001C38] Creating or clearing the performance database
04/26/11 08:14:15.214 [0X00001C38] Processing file: E:\Performance\SQL Nexus\Templates\PerfStatsScript2008\SQLDiagOutput\ansessqlvc1_SQLDIAG_SQL2A_sp_trace.trc (SQL 2008)
04/26/11 08:14:16.839 [0X00001C38] Validating core events exist
04/26/11 08:14:16.842 [0X00001C38] Validating necessary events exist for analysis
04/26/11 08:14:17.973 [0X00001C38] Events Read: 1000 Queued: 1000 Processed/sec: 0
04/26/11 08:14:18.021 [0X00001C38] Events Read: 2000 Queued: 2000 Processed/sec: 0
04/26/11 08:14:18.132 [0X00001C38] Events Read: 3000 Queued: 3000 Processed/sec: 0
04/26/11 08:14:18.159 [0X00001C38] Events Read: 4000 Queued: 4000 Processed/sec: 0
04/26/11 08:14:18.176 [0X00001C38] Events Read: 5000 Queued: 4997 Processed/sec: 1
04/26/11 08:14:18.201 [0X00001C38] Events Read: 6000 Queued: 5996 Processed/sec: 1
04/26/11 08:14:18.246 [0X00001C38] Events Read: 7000 Queued: 6992 Processed/sec: 2
04/26/11 08:14:18.265 [0X00001C38] Events Read: 8000 Queued: 7992 Processed/sec: 2
04/26/11 08:14:18.286 [0X00001C38] Events Read: 9000 Queued: 8987 Processed/sec: 4
04/26/11 08:14:18.557 [0X00001C38] Events Read: 25000 Queued: 24910 Processed/sec: 22
04/26/11 08:14:18.801 [0X00001764] A serious error condition (true == pVariant->FIsUnknownType()) has been encountered.
 Utility Error: Attempt to use an invalid variant type
 File: .\rpcbinary.cpp
 Line: 264
 Return Address: 0x000000013F7C4212
 Function: FConvertNCharStringFromRPC



What else could be wrong?

Mario

 

 

Apr 27, 2011 at 2:08 PM

This is a known issue. Please send a mail to sstlbugs@microsoft.com with the RML Utilities log as an attachment. You will need an updated version of Readtrace.exe. The issue is documented here:

http://blogs.msdn.com/b/psssql/archive/2009/02/11/prb-rml-utilities-readtrace-processing-fails-with-attempt-to-use-an-invalid-variant-type.aspx

http://blogs.msdn.com/b/sqlserverfaq/archive/2011/02/07/sql-nexus-and-rml-utilities-a-primer-on-the-issues-that-i-have-dealt-with-last-year.aspx

May 9, 2011 at 3:15 PM

Problem solved.

Thanks amitban!

May 12, 2011 at 5:50 PM

Good to know that you got your problem resolved. :)