Sample Database Trace output

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 a PowerBuilder 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 database

CONNECT TO TRACE SYC Adaptive Server Enterprise:
DATABASE=pubs2
LOGID=bob
SERVER=HOST12
DPPARM=Release='12.5.2',StaticBind=0

Prepare SELECT statement

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)

Get column descriptions

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 memory buffers to columns

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 SELECT statement

EXECUTE: (0.001 MS / 20.378 MS)

Fetch rows from result set

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)

Update and commit database changes

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 from database

DISCONNECT: (0.764 MS / 25.812 MS)

Shut down database interface

SHUTDOWN DATABASE INTERFACE: (0.001 MS / 25.813 MS)