Oracle FAQ | Your Portal to the Oracle Knowledge Grid |
Home -> Community -> Usenet -> c.d.o.misc -> tkprof output?
Hi All
Would someone be so kind and have a look at the following tkprof output and maybe give me a clue as to why I am have users reporting bad response times? I have set db block buffers to 1.3G and the shared pool is set to 1.6. I have 300 users running on a e4000 SUn Server with 5GB of memory.
thanks in advance for any help here
eddie lufker
TKPROF: Release 7.3.3.0.0 - Production on Tue Jul 28 13:39:58 1998
Copyright =A9 Oracle Corporation 1979, 1996. All rights reserved.
Trace file: ././sblp_ora_25945.trc
Sort options: default
************************************************************************=
count =3D number of times OCI procedure was executed cpu =3D cpu time in seconds executing=20 elapsed =3D elapsed time in seconds executing disk =3D number of physical reads of buffers from disk query =3D number of buffers gotten for consistent read current =3D number of buffers gotten in current mode (usually for = update) rows =3D number of rows processed by the fetch or execute call
************************************************************************=
SELECT
T1.ROW_ID, T1.MODIFICATION_NUM, T1.CREATED_BY, T1.LAST_UPD_BY, T1.CREATED, T1.LAST_UPD, T1.CONFLICT_ID, T1.NAME, T1.DESC_TEXT, T1.PRIV_FLG, T1.QUERY_STRING FROM=20 SIEBEL.S_APP_QUERY T1 WHERE (T1.CREATED_BY =3D :1 OR T1.PRIV_FLG =3D :2) AND (T1.NAME =3D :3) ORDER BY T1.NAME, T1.DESC_TEXT call count cpu elapsed disk query currentrows
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 0 SORT (ORDER BY) 0 TABLE ACCESS (BY ROWID) OF 'S_APP_QUERY' 0 INDEX (RANGE SCAN) OF 'S_APP_QUERY_U1' (UNIQUE)
************************************************************************=
SELECT
T6.ROW_ID, T6.MODIFICATION_NUM, T6.CREATED_BY, T6.LAST_UPD_BY, T6.CREATED, T6.LAST_UPD, T6.CONFLICT_ID, T6.REVN_GOAL_CURCY_CD, T6.PROG_END_DT, T6.REVN_GOAL_DT, T6.NAME, T6.OBJECTIVE, T6.PR_CS_PATH_ID, T6.PR_D_NOTE_ID, T6.PR_POSTN_ID, T6.PR_SCRIPT_NOTE_ID, T6.PR_TERR_ID, T6.PURPOSE, T6.REVN_GOAL, T6.PROG_START_DT, T1.NAME, T3.NOTE, T3.CREATED, T3.CREATED_BY, T4.PR_EMP_ID, T5.LOGIN, T2.SRC_NAME, T2.POSITION_ID, T1.ROW_ID, T3.ROW_ID, T4.ROW_ID, T2.ROW_ID, T5.ROW_ID FROM=20 SIEBEL.S_TERR T1, SIEBEL.S_SRC_POSTN T2, SIEBEL.S_NOTE T3, SIEBEL.S_POSTN T4, SIEBEL.S_EMPLOYEE T5, SIEBEL.S_SRC T6 WHERE T6.PR_TERR_ID =3D T1.ROW_ID (+) AND T6.PR_D_NOTE_ID =3D T3.ROW_ID (+) AND T4.ROW_ID =3D T2.POSITION_ID AND T2.POSITION_ID =3D :1 AND T6.ROW_ID =3D T2.SRC_ID AND T4.PR_EMP_ID =3D T5.ROW_ID (+) AND (T6.SUB_TYPE =3D :2) call count cpu elapsed disk query currentrows
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS 0 NESTED LOOPS 0 TABLE ACCESS (BY ROWID) OF 'S_SRC_POSTN' 0 INDEX (RANGE SCAN) OF 'S_SRC_POSTN_M1' (NON-UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_SRC' 0 INDEX (UNIQUE SCAN) OF 'S_SRC_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_POSTN' 0 INDEX (UNIQUE SCAN) OF 'S_POSTN_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_EMPLOYEE' 0 INDEX (UNIQUE SCAN) OF 'S_EMPLOYEE_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_NOTE' 0 INDEX (UNIQUE SCAN) OF 'S_NOTE_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_TERR' 0 INDEX (UNIQUE SCAN) OF 'S_TERR_P1' (UNIQUE)
************************************************************************=
SELECT
T8.ROW_ID, T8.MODIFICATION_NUM, T8.CREATED_BY, T8.LAST_UPD_BY, T8.CREATED, T8.LAST_UPD, T8.CONFLICT_ID, T7.NAME, T8.PR_DEPT_OU_ID, T7.LOC, T7.X_SITE, T8.CON_ASST_NAME, T8.CALL_FLG, T8.X_CAMPAIGN_ID, T8.COMMENTS, T8.X_CONTACTED_FLG, T8.X_DUPLICATE_REC_FLG, T8.EMAIL_ADDR, T8.FAX_PH_NUM, T8.FST_NAME, T8.HOME_PH_NUM, T8.JOB_TITLE, T7.ROW_ID, T8.LAST_NAME, T8.X_LAST_SALE_DATE, T8.X_LEAD_PRIORITY, T3.NAME, T8.X_LEAD_STATUS, T8.PER_TITLE, T8.OU_MAIL_STOP, T8.CON_MANAGER_NAME, T8.MID_NAME, T8.OWNER_LOGIN, T8.PR_OU_ADDR_ID, T8.PR_PER_ADDR_ID, T8.PR_POSTN_ID, T8.X_QUALIFIED_FLG, T8.SRC_ID, T8.WORK_PH_NUM, T2.ROW_ID, T2.MODIFICATION_NUM, T2.CREATED_BY, T2.LAST_UPD_BY, T2.CREATED, T2.LAST_UPD, T2.CONFLICT_ID, T2.SRC_ID, T2.CON_PER_ID, T2.CON_PER_ID, T2.SRC_ID, T2.CON_FST_NAME, T2.CON_LAST_NAME, T2.CON_MID_NAME, T5.CITY, T5.COUNTRY, T5.ZIPCODE, T5.STATE, T5.ADDR, T4.ASGN_TYPE, T1.PR_EMP_ID, T6.FST_NAME, T6.LAST_NAME, T1.NAME, T4.ROW_STATUS, T6.LOGIN, T4.CON_FST_NAME, T4.CON_LAST_NAME, T4.CON_MID_NAME, T4.POSTN_ID, T7.ROW_ID, T3.ROW_ID, T5.ROW_ID, T1.ROW_ID, T4.ROW_ID, T6.ROW_ID FROM SIEBEL.S_POSTN T1, SIEBEL.S_CAMP_CON T2, SIEBEL.S_SRC T3, SIEBEL.S_POSTN_CON T4, SIEBEL.S_ADDR_ORG T5, SIEBEL.S_EMPLOYEE T6, SIEBEL.S_ORG_EXT T7, SIEBEL.S_CONTACT T8 WHERE T8.PR_DEPT_OU_ID =3D T7.ROW_ID (+) AND T8.SRC_ID =3D T3.ROW_ID (+) AND
ORDER BY T7.NAME, T8.X_LEAD_PRIORITY call count cpu elapsed disk query currentrows
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 0 SORT (ORDER BY) 0 NESTED LOOPS 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS 0 TABLE ACCESS (BY ROWID) OF 'S_POSTN_CON' 0 INDEX (RANGE SCAN) OF 'S_POSTN_CON_M1'=20 (NON-UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_CONTACT' 0 INDEX (UNIQUE SCAN) OF 'S_CONTACT_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_ORG_EXT' 0 INDEX (UNIQUE SCAN) OF 'S_ORG_EXT_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_ADDR_ORG' 0 INDEX (UNIQUE SCAN) OF 'S_ADDR_ORG_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_SRC' 0 INDEX (UNIQUE SCAN) OF 'S_SRC_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_POSTN' 0 INDEX (UNIQUE SCAN) OF 'S_POSTN_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_EMPLOYEE' 0 INDEX (UNIQUE SCAN) OF 'S_EMPLOYEE_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_CAMP_CON' 0 INDEX (RANGE SCAN) OF 'S_CAMP_CON_U1' (UNIQUE)
************************************************************************=
select file#,block#,length=20
from
fet$ where length>=3D:1 and ts#=3D:2
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 1 TABLE ACCESS (CLUSTER) OF 'FET$' 1 INDEX (UNIQUE SCAN) OF 'I_TS#' (CLUSTER)
************************************************************************=
select length=20
from
fet$ where file#=3D:1 and block#=3D:2 and ts#=3D:3
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 1 TABLE ACCESS (CLUSTER) OF 'FET$' 1 INDEX (UNIQUE SCAN) OF 'I_TS#' (CLUSTER)
************************************************************************=
select ts#,file#,block#,length=20
from
uet$ where segfile#=3D:1 and segblock#=3D:2 and ext#=3D:3
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 74 TABLE ACCESS (CLUSTER) OF 'UET$' 1 INDEX (UNIQUE SCAN) OF 'I_FILE#_BLOCK#' (CLUSTER)
************************************************************************=
update ts$ set inc#=3D:2=20
where
ts#=3D:1
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
Rows Execution Plan
------- --------------------------------------------------- 0 UPDATE STATEMENT GOAL: RULE 1 TABLE ACCESS (CLUSTER) OF 'TS$' 1 INDEX (UNIQUE SCAN) OF 'I_TS#' (CLUSTER)
************************************************************************=
update seg$ set =
type=3D:3,ts#=3D:4,blocks=3D:5,extents=3D:6,minexts=3D:7,maxexts=3D:8,
extsize=3D:9,extpct=3D:10,user#=3D:11,iniexts=3D:12,lists=DEcode(:13, =
65535, NULL,=20
:13),groups=DEcode(:14, 65535, NULL, :14)=20
where
file#=3D:1 and block#=3D:2
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
Rows Execution Plan
------- --------------------------------------------------- 0 UPDATE STATEMENT GOAL: RULE 1 TABLE ACCESS (CLUSTER) OF 'SEG$' 1 INDEX (UNIQUE SCAN) OF 'I_FILE#_BLOCK#' (CLUSTER)
************************************************************************=
insert into uet$ (segfile#,segblock#,ext#,ts#,file#,block#,length)
values
(:1, :2, :3, :4, :5, :6, :7)
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
Rows Execution Plan
------- --------------------------------------------------- 0 INSERT STATEMENT GOAL: RULE
************************************************************************=
delete from fet$=20
where
file#=3D:1 and block#=3D:2 and ts#=3D:3
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
Rows Execution Plan
------- --------------------------------------------------- 0 DELETE STATEMENT GOAL: RULE 399 TABLE ACCESS (CLUSTER) OF 'FET$' 1 INDEX (UNIQUE SCAN) OF 'I_TS#' (CLUSTER)
************************************************************************=
SELECT
T8.ROW_ID, T8.MODIFICATION_NUM, T8.CREATED_BY, T8.LAST_UPD_BY, T8.CREATED, T8.LAST_UPD, T8.CONFLICT_ID, T7.NAME, T8.PR_DEPT_OU_ID, T7.LOC, T7.X_SITE, T8.X_AREA_INTERESTED, T8.CON_ASST_NAME, T8.CALL_FLG, T8.X_CAMPAIGN_ID, T8.COMMENTS, T8.X_CONTACTED_FLG, T8.X_DUPLICATE_REC_FLG, T8.EMAIL_ADDR, T8.FAX_PH_NUM, T8.FST_NAME, T8.HOME_PH_NUM, T8.X_CBT_INTEREST, T8.X_MCP_INTEREST, T8.JOB_TITLE, T7.ROW_ID, T8.LAST_NAME, T8.X_LAST_SALE_DATE, T8.X_LEAD_PRIORITY, T3.NAME, T8.X_LEAD_STATUS, T8.PER_TITLE, T8.OU_MAIL_STOP, T8.CON_MANAGER_NAME, T8.MID_NAME, T8.X_NETWORK_OS_1, T8.X_NETWORK_OS_2, T8.X_NUM_PC, T8.X_OS_USED_1, T8.X_OS_USED_2, T8.OWNER_LOGIN, T8.X_POSITION_1, T8.PR_OU_ADDR_ID, T8.PR_PER_ADDR_ID, T8.PR_POSTN_ID, T8.X_QUALIFIED_FLG, T8.SRC_ID, T8.WORK_PH_NUM, T2.ROW_ID, T2.MODIFICATION_NUM, T2.CREATED_BY, T2.LAST_UPD_BY, T2.CREATED, T2.LAST_UPD, T2.CONFLICT_ID, T2.SRC_ID, T2.CON_PER_ID, T2.CON_PER_ID, T2.SRC_ID, T2.CON_FST_NAME, T2.CON_LAST_NAME, T2.CON_MID_NAME, T5.CITY, T5.COUNTRY, T5.ZIPCODE, T5.STATE, T5.ADDR, T4.ASGN_TYPE, T1.PR_EMP_ID, T6.FST_NAME, T6.LAST_NAME, T1.NAME, T4.ROW_STATUS, T6.LOGIN, T4.CON_FST_NAME, T4.CON_LAST_NAME, T4.CON_MID_NAME, T4.POSTN_ID, T7.ROW_ID, T3.ROW_ID, T5.ROW_ID, T1.ROW_ID, T4.ROW_ID, T6.ROW_ID FROM=20 SIEBEL.S_POSTN T1, SIEBEL.S_CAMP_CON T2, SIEBEL.S_SRC T3, SIEBEL.S_POSTN_CON T4, SIEBEL.S_ADDR_ORG T5, SIEBEL.S_EMPLOYEE T6, SIEBEL.S_ORG_EXT T7, SIEBEL.S_CONTACT T8 WHERE T8.PR_DEPT_OU_ID =3D T7.ROW_ID (+) AND T8.SRC_ID =3D T3.ROW_ID (+) ANDT8.PR_OU_ADDR_ID =3D T5.ROW_ID (+) AND T1.ROW_ID =3D T4.POSTN_ID AND = T4.POSTN_ID
ORDER BY T7.NAME, T8.X_LEAD_PRIORITY call count cpu elapsed disk query currentrows
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 0 SORT (ORDER BY) 0 NESTED LOOPS 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS 0 TABLE ACCESS (BY ROWID) OF 'S_POSTN_CON' 0 INDEX (RANGE SCAN) OF 'S_POSTN_CON_M1'=20 (NON-UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_CONTACT' 0 INDEX (UNIQUE SCAN) OF 'S_CONTACT_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_ORG_EXT' 0 INDEX (UNIQUE SCAN) OF 'S_ORG_EXT_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_ADDR_ORG' 0 INDEX (UNIQUE SCAN) OF 'S_ADDR_ORG_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_SRC' 0 INDEX (UNIQUE SCAN) OF 'S_SRC_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_POSTN' 0 INDEX (UNIQUE SCAN) OF 'S_POSTN_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_EMPLOYEE' 0 INDEX (UNIQUE SCAN) OF 'S_EMPLOYEE_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_CAMP_CON' 0 INDEX (RANGE SCAN) OF 'S_CAMP_CON_U1' (UNIQUE)
************************************************************************=
SELECT
T1.ROW_ID, T1.MODIFICATION_NUM, T1.CREATED_BY, T1.LAST_UPD_BY, T1.CREATED, T1.LAST_UPD, T1.CONFLICT_ID, T1.X_CAMPAIGN_ID, T1.COMMENTS, T1.TARGET_PER_ID, T1.OWNER_PER_ID, T1.APPT_REPT_FLG, T1.X_SUBMITTED_FLG, T1.TODO_CD FROM=20 SIEBEL.S_EVT_ACT T1 WHERE (T1.APPT_REPT_FLG !=3D :1 OR T1.APPT_REPT_FLG IS NULL) AND (T1.TARGET_PER_ID =3D :2) ORDER BY T1.CREATED DESC call count cpu elapsed disk query currentrows
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 0 SORT (ORDER BY) 0 TABLE ACCESS (BY ROWID) OF 'S_EVT_ACT' 0 INDEX (RANGE SCAN) OF 'S_EVT_ACT_F8' (NON-UNIQUE)
************************************************************************=
SELECT
T3.ROW_ID, T3.MODIFICATION_NUM, T3.CREATED_BY, T3.LAST_UPD_BY, T3.CREATED, T3.LAST_UPD, T3.CONFLICT_ID, T3.TARGET_OU_ID, T2.NAME, T3.ALARM_FLAG, T3.X_CAMPAIGN_ID, T3.COMMENTS, T3.X_COMPLETED_FLG, T1.FST_NAME, T3.TARGET_PER_ID, T1.LAST_NAME, T3.CREATOR_LOGIN, T3.NAME, T3.OWNER_LOGIN, T3.OWNER_PER_ID, T3.APPT_REPT_FLG, T3.APPT_START_DT, T3.APPT_START_TM, T3.EVT_STAT_CD, T3.X_SUBMITTED_FLG, T3.TODO_CD, T1.ROW_ID, T2.ROW_ID FROM=20 SIEBEL.S_CONTACT T1, SIEBEL.S_ORG_EXT T2, SIEBEL.S_EVT_ACT T3 WHERE T3.TARGET_PER_ID =3D T1.ROW_ID (+) AND T3.TARGET_OU_ID =3D T2.ROW_ID =(+)
ORDER BY T3.TARGET_PER_ID, T3.CREATED DESC call count cpu elapsed disk query currentrows
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 0 SORT (ORDER BY) 0 NESTED LOOPS (OUTER) 0 NESTED LOOPS (OUTER) 0 TABLE ACCESS (BY ROWID) OF 'S_EVT_ACT' 0 INDEX (RANGE SCAN) OF 'S_EVT_ACT_F8' (NON-UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_ORG_EXT' 0 INDEX (UNIQUE SCAN) OF 'S_ORG_EXT_P1' (UNIQUE) 0 TABLE ACCESS (BY ROWID) OF 'S_CONTACT' 0 INDEX (UNIQUE SCAN) OF 'S_CONTACT_P1' (UNIQUE)
************************************************************************=
SELECT
T1.ROW_ID, T1.MODIFICATION_NUM, T1.CREATED_BY, T1.LAST_UPD_BY, T1.CREATED, T1.LAST_UPD, T1.CONFLICT_ID, T1.NAME, T1.PR_EMP_ID, T1.PR_TERR_ID, T1.X_SEC_LEVEL FROM=20 SIEBEL.S_POSTN T1 WHERE (T1.ROW_ID =3D :1) ORDER BY T1.NAME call count cpu elapsed disk query currentrows
Rows Execution Plan
------- --------------------------------------------------- 0 SELECT STATEMENT GOAL: RULE 0 TABLE ACCESS (BY ROWID) OF 'S_POSTN' 0 INDEX (UNIQUE SCAN) OF 'S_POSTN_P1' (UNIQUE)
************************************************************************=
OVERALL TOTALS FOR ALL NON-RECURSIVE STATEMENTS
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
OVERALL TOTALS FOR ALL RECURSIVE STATEMENTS
call count cpu elapsed disk query current
rows
------- ------ -------- ---------- ---------- ---------- ----------
7 user SQL statements in session. 28 internal SQL statements in session. 35 SQL statements in session. 14 statements EXPLAINed in this session.
************************************************************************=
1 session in tracefile. 7 user SQL statements in trace file. 28 internal SQL statements in trace file. 35 SQL statements in trace file. 14 unique SQL statements in trace file. 14 SQL statements EXPLAINed using schema: SYS.prof$plan_table Default table was used. Table was created. Table was dropped. 640 lines in trace file.Received on Tue Jul 28 1998 - 18:32:20 CDT