This section gives an example of Database Trace output that you might see in the log file and briefly explains each portion of the output.
The example traces a connection with Sum Timing enabled. The output was generated while running an InfoMaker application that displays information about authors in a publications database. The SELECT statement shown retrieves information from the Author table.
The precision (for example, microseconds) used when Database Trace records internal commands depends on your operating system’s timing mechanism. Therefore, the timing precision in your Database Trace log might vary from this example.
CONNECT TO TRACE SYC Adaptive Server Enterprise:
DATABASE=pubs2
LOGID=bob
SERVER=HOST12
DPPARM=Release='12.5.2',StaticBind=0
PREPARE:
SELECT authors.au_id, authors.au_lname, authors.state FROM authors
WHERE ( authors.state not in ( 'CA' ) )
ORDER BY authors.au_lname ASC (3.386 MS / 20.349 MS)
DESCRIBE: (0.021 MS / 20.370 MS)
name=au_id,len=12,type=CHAR,pbt=1,dbt=1,ct=0,prec=0, scale=0
name=au_lname,len=41,type=CHAR,pbt=1,dbt=1,ct=0, prec=0,scale=0
name=state,len=3,type=CHAR,pbt=1,dbt=1,ct=0,prec=0, scale=0
BIND SELECT OUTPUT BUFFER (DataWindow):
(0.007 MS / 20.377 MS)
name=au_id,len=12,type=CHAR,pbt=1,dbt=1,ct=0,prec=0, scale=0
name=au_lname,len=41,type=CHAR,pbt=1,dbt=1,ct=0, prec=0,scale=0
name=state,len=3,type=CHAR,pbt=1,dbt=1,ct=0,prec=0, scale=0
EXECUTE: (0.001 MS / 20.378 MS)
FETCH NEXT: (0.028 MS / 20.406 MS)
au_id=648-92-1872 au_lname=Blotchet-Hall state=OR
FETCH NEXT: (0.012 MS / 20.418 MS)
au_id=722-51-5454 au_lname=DeFrance state=IN
...
FETCH NEXT: (0.010 MS / 20.478 MS)
au_id=341-22-1782 au_lname=Smith state=KS
FETCH NEXT: (0.025 MS / 20.503 MS)
*** DBI_FETCHEND *** (rc 100)
PREPARE:
UPDATE authors SET state = 'NM'
WHERE au_id = '648-92-1872' AND au_lname = 'Blotchet-Halls' AND state = 'OR' (3.284 MS / 23.787 MS)
EXECUTE: (0.001 MS / 23.788 MS)
GET AFFECTED ROWS: (0.001 MS / 23.789 MS)
^ 1 Rows Affected
COMMIT: (1.259 MS / 25.048 MS)
DISCONNECT: (0.764 MS / 25.812 MS)
SHUTDOWN DATABASE INTERFACE: (0.001 MS / 25.813 MS)
