BookmarkSubscribeRSS Feed
HULK
Obsidian | Level 7

Hi!

 

The question in understanding what is written to RTDM Performance log and how to map the SQL query with the activity.

2022-01-09 15:39:32,968|TableCacheJDBCLoader||TableCacheJDBCLoader.select() times(nanos)[Prepare: 4701 Execute: 448799 SQL: SELECT SOMETHING FROM SOMEWHERE WHERE SOMEID = ?]
2022-01-09 15:39:32,968|TableCacheJDBCLoader||TableCacheJDBCLoader.select() times(nanos)[Prepare: 3675 Execute: 319816 SQL: SELECT SOMETHING2 FROM SOMEWHERE2 WHERE SOMEID2 = ?]
2022-01-09 15:39:32,968|ACTIVITY_TIME||Activity GeneralIORead.SCReadTable took 991644 nanos
2022-01-09 15:39:32,968|TableCacheJDBCLoader||TableCacheJDBCLoader.select() times(nanos)[Prepare: 3528 Execute: 444013 SQL: SELECT SOMETHING3 FROM SOMEWHERE3 WHERE SOMEID3 = ?]
2022-01-09 15:39:32,968|ACTIVITY_TIME||Activity GeneralIORead.SCReadTable took 732798 nanos
2022-01-09 15:39:32,968|ACTIVITY_TIME||Activity GeneralIORead.SCReadTable took 830829 nanos

 

 

1. What do the numbers in the Prepare and Execute parameters mean before the database query text? If everything is clear with activity (GeneralIORead.SCReadTable took 991644 nanos) - reading time in nanoseconds, then these parameters raise questions with their interpretation.

 

2. In this example, there are 3 records with queries to the database and 3 records with a report of the time of the corresponding activity. Is there a way to unambiguously map them with each other? From the log entry, this is at least not obvious.

2 REPLIES 2
Prasenjit_Sen
SAS Employee

Are you familiar with/worked with prepared statements?

HULK
Obsidian | Level 7
Prasenjit_Sen, no. Can you please tell me how this is related to my question about the interpretation of messages in the log?