TKPROF: Release 9.2.0.6.0 - Production on Thu Dec 14 09:45:33 2006 Copyright (c) 1982, 2002, Oracle Corporation. All rights reserved. Trace file: orcl9i_ora_5656_test_event_tracing.trc Sort options: default ******************************************************************************** count = number of times OCI procedure was executed cpu = cpu time in seconds executing elapsed = elapsed time in seconds executing disk = number of physical reads of buffers from disk query = number of buffers gotten for consistent read current = number of buffers gotten in current mode (usually for update) rows = number of rows processed by the fetch or execute call ******************************************************************************** alter session set events '10046 trace name context forever, level 12' call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 0 0.00 0.00 0 0 0 0 Execute 1 0.00 0.00 0 0 0 0 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 1 0.00 0.00 0 0 0 0 Misses in library cache during parse: 0 Misses in library cache during execute: 1 Optimizer goal: CHOOSE Parsing user id: 5 (SYSTEM) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ SQL*Net message to client 1 0.00 0.00 SQL*Net message from client 1 0.00 0.00 ******************************************************************************** select a.employee_id, a.last_name, b.department_id, b.department_name,c.street_address, c.postal_code, c.city, c.state_province from hr.employees a, hr.departments b, hr.locations c where a.department_id=b.department_id and b.location_id=c.location_id and a.employee_id = 174 order by a.last_name call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 1 0.00 0.00 0 0 0 0 Fetch 2 0.00 0.00 0 6 0 1 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 4 0.00 0.00 0 6 0 1 Misses in library cache during parse: 0 Optimizer goal: CHOOSE Parsing user id: 5 (SYSTEM) ********************************Access path taken during execution Rows Row Source Operation ------- --------------------------------------------------- 1 NESTED LOOPS 1 NESTED LOOPS 1 TABLE ACCESS BY INDEX ROWID EMPLOYEES 1 INDEX UNIQUE SCAN EMP_EMP_ID_PK (object id 29501) 1 TABLE ACCESS BY INDEX ROWID DEPARTMENTS 1 INDEX UNIQUE SCAN DEPT_ID_PK (object id 29495) 1 TABLE ACCESS BY INDEX ROWID LOCATIONS 1 INDEX UNIQUE SCAN LOC_ID_PK (object id 29492) ********************************Access path prediction Rows Execution Plan ------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: CHOOSE 1 NESTED LOOPS 1 NESTED LOOPS 1 TABLE ACCESS GOAL: ANALYZED (BY INDEX ROWID) OF 'EMPLOYEES' 1 INDEX (UNIQUE SCAN) OF 'EMP_EMP_ID_PK' (UNIQUE) 1 TABLE ACCESS GOAL: ANALYZED (BY INDEX ROWID) OF 'DEPARTMENTS' 1 INDEX (UNIQUE SCAN) OF 'DEPT_ID_PK' (UNIQUE) 1 TABLE ACCESS GOAL: ANALYZED (BY INDEX ROWID) OF 'LOCATIONS' 1 INDEX (UNIQUE SCAN) OF 'LOC_ID_PK' (UNIQUE) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ SQL*Net message to client 2 0.00 0.00 SQL*Net message from client 2 0.01 0.01 ******************************************************************************** OVERALL TOTALS FOR ALL NON-RECURSIVE STATEMENTS call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 2 0.00 0.00 0 0 0 0 Fetch 2 0.00 0.00 0 6 0 1 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 5 0.00 0.00 0 6 0 1 Misses in library cache during parse: 0 Misses in library cache during execute: 1 Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ SQL*Net message to client 5 0.00 0.00 SQL*Net message from client 5 48.77 58.59 OVERALL TOTALS FOR ALL RECURSIVE STATEMENTS call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 0 0.00 0.00 0 0 0 0 Execute 0 0.00 0.00 0 0 0 0 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 0 0.00 0.00 0 0 0 0 Misses in library cache during parse: 0 2 user SQL statements in session. 0 internal SQL statements in session. 2 SQL statements in session. 1 statement EXPLAINed in this session. ******************************************************************************** Trace file: orcl9i_ora_5656_test_event_tracing.trc Trace file compatibility: 9.02.00 Sort options: default 1 session in tracefile. 2 user SQL statements in trace file. 0 internal SQL statements in trace file. 2 SQL statements in trace file. 2 unique SQL statements in trace file. 1 SQL statements EXPLAINed using schema: SYSTEM.prof$plan_table Default table was used. Table was created. Table was dropped. 62 lines in trace file.