TKPROF: Release 10.2.0.1.0 - Production on Mon May 4 15:51:26 2009 Copyright (c) 1982, 2005, Oracle. All rights reserved. Trace file: valvesp_ora_25077_run_parsers.trc Sort options: prsdsk exedsk fchdsk ******************************************************************************** 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 ******************************************************************************** INSERT INTO CCI_ORDER_FLOW_TBL SELECT XACTID, DIVISION, ORDERTYPE, ORDERNBR, REPID, LINENBR, REPORDERID, REPLINENBR, QUOTEID, DIVORDERNBR, HOLDS, RELEASES, MONITOR_REF, MONITOR_TS, CREATION_DATE FROM ORDER_FLOW_MONITOR WHERE CREATION_DATE > ADD_MONTHS(TRUNC(SYSDATE),-2) MINUS SELECT XACTID, DIVISION, ORDERTYPE, ORDERNBR, REPID, LINENBR, REPORDERID, REPLINENBR, QUOTEID, DIVORDERNBR, HOLDS, RELEASES, MONITOR_REF, MONITOR_TS, CREATION_DATE FROM CCI_ORDER_FLOW_TBL WHERE CREATION_DATE > ADD_MONTHS(TRUNC(SYSDATE),-2) call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 2 49.74 730.93 122826 266684 27 0 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 49.74 730.93 122826 266684 27 0 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ db file sequential read 15380 0.41 75.01 direct path write temp 1080 0.00 0.00 direct path read temp 2926 0.00 0.00 db file scattered read 5422 3.32 113.26 log file switch completion 1 0.97 0.97 write complete waits 164 0.97 160.30 ******************************************************************************** SELECT A.MONITOR_TS, A.TRACE_ID, A.MONITOR_REF, A.DIVORDERNBR, A.HOLDS FROM CCI_ORDER_MONITOR_POINTS A WHERE 1=1 AND EXISTS(SELECT 'x' FROM CCI_ORDER_TRACE_MONITOR M WHERE ( A.MONITOR_TS <> NVL(DECODE(MONITOR_REF, 'JDE201', JDE_A_RECEIVED_DATE,'MFG202', VALVES_IBM_RECEIVED_DATE, 'OsMfg202Out', A.MONITOR_TS, 'SoMfg202In', VALVES_IBM_COMPLETION_DATE, 'JDE202', JDE_EDC_RECEIVED_DATE,'OsJde202Out',JDE_EDC_COMPLETION_DATE), SYSDATE+300) OR (MONITOR_REF = 'OsMfg202Out' AND VALVES_IBM_ORDER_NUMBER IS NULL) ) AND A.TRACE_ID = M.TRANSACTION_ID) AND MONITOR_REF IN('JDE201', 'MFG202','OsMfg202Out','SoMfg202In','JDE202','OsJde202Out') AND MONITOR_TS > TRUNC(SYSDATE-180) 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 389062 29.61 263.67 62325 3286341 0 389061 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 389064 29.61 263.67 62325 3286341 0 389061 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ db file sequential read 7986 1.09 119.92 db file scattered read 3442 0.99 116.20 ******************************************************************************** SELECT * FROM ( SELECT FILENAME, DECODE(FILE_MONREFNAME(FILENAME), 'OsMfg202Out',1, 'OsSyc002Out',2,'OsMfg204Out',3,4) STAT_TYPE, FILE_TS(FILENAME) TIMER FROM XML2LIST WHERE NOT EXISTS (SELECT 1 FND FROM XML2_XACTS WHERE XML2_XACTS.FILENAME = XML2LIST.FILENAME) ORDER BY 2,3) WHERE ROWNUM < 10000 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 1 0.01 0.02 0 154 0 0 Fetch 1 1.72 26.99 22069 22090 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 1.74 27.01 22069 22244 0 0 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ PX Deq: Join ACK 10 0.00 0.00 PX Deq: Parse Reply 11 0.00 0.00 PX Deq: Execute Reply 28 0.01 0.02 db file sequential read 2 0.00 0.00 db file scattered read 1388 0.86 23.54 PX Deq Credit: send blkd 147 1.94 1.97 PX Deq Credit: need buffer 272 0.16 0.18 PX Deq: Table Q qref 3 0.00 0.00 PX Deq: Signal ACK 10 0.00 0.01 enq: PS - contention 1 0.00 0.00 ******************************************************************************** INSERT INTO CCI_ORDER_MONITOR_POINTS SELECT * FROM ORDER_MONITOR_POINTS_ALL MP WHERE NOT EXISTS(SELECT 'x' FROM CCI_ORDER_MONITOR_POINTS OMP WHERE OMP.XACTID = MP.XACTID AND OMP.MONITOR_REF = MP.MONITOR_REF AND OMP.MONITOR_TS = MP.MONITOR_TS) AND MONITOR_TS > ADD_MONTHS(SYSDATE,-2) call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 1 13.05 542.47 9737 1152854 0 0 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 2 13.05 542.47 9737 1152854 0 0 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ db file sequential read 9737 1.13 241.39 log file switch (checkpoint incomplete) 295 0.97 285.36 latch: cache buffers chains 1 0.01 0.01 buffer busy waits 1 0.00 0.00 log buffer space 3 0.59 1.01 ******************************************************************************** SELECT FILENAME, DECODE(FILE_MONREFNAME(FILENAME),'SoMfg202In',1,2) STAT_TYPE FROM XML14LIST WHERE NOT EXISTS (SELECT 1 FND FROM XML14_XACTS WHERE XML14_XACTS.FILENAME = XML14LIST.FILENAME) AND ROWNUM < 500 ORDER BY 2 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.01 0 0 0 0 Execute 1 0.01 0.19 0 151 0 0 Fetch 1 0.64 10.21 7454 7471 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 0.66 10.43 7454 7622 0 0 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ PX Deq: Join ACK 7 0.00 0.00 PX Deq: Parse Reply 5 0.00 0.00 PX Deq: Execute Reply 13 0.03 0.03 db file sequential read 2 0.04 0.04 db file scattered read 474 0.48 9.61 PX Deq Credit: send blkd 137 0.00 0.01 PX Deq Credit: need buffer 227 0.00 0.00 PX Deq: Table Q Normal 3 0.00 0.00 latch: session allocation 1 0.00 0.00 ******************************************************************************** UPDATE CCI_ORDER_TRACE_MONITOR SET VALVES_IBM_ORDER_NUMBER = :B3 , VALVES_IBM_PROCESSING_STATUS = 'Complete' , VALVES_IBM_COMPLETION_DATE = :B2 WHERE TRANSACTION_ID = :B1 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 387694 83.20 332.16 1059 1163125 532352 387694 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 387695 83.20 332.16 1059 1163125 532352 387694 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ enq: TX - row lock contention 5 2.93 13.22 log file switch completion 29 0.97 16.07 log file switch (checkpoint incomplete) 225 0.97 212.59 log buffer space 17 0.79 3.63 db file sequential read 1059 0.52 1.73 latch: library cache 5 0.02 0.03 ******************************************************************************** BEGIN run_all_parsers; END; call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 1 39.09 40.67 6 519 0 1 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 2 39.09 40.67 6 519 0 1 Misses in library cache during parse: 1 Optimizer mode: ALL_ROWS Parsing user id: 38 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 ******************************************************************************** SELECT max(version) FROM JAVA$POLICY$SHARED$TABLE 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 1 0.00 0.42 2 3 0 1 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 0.00 0.42 2 3 0 1 Misses in library cache during parse: 0 Optimizer mode: CHOOSE Parsing user id: SYS (recursive depth: 2) Rows Row Source Operation ------- --------------------------------------------------- 1 SORT AGGREGATE (cr=3 pr=2 pw=0 time=425175 us) 2 TABLE ACCESS FULL JAVA$POLICY$SHARED$TABLE (cr=3 pr=2 pw=0 time=425118 us) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ db file sequential read 2 0.33 0.33 ******************************************************************************** UPDATE CCI_ORDER_TRACE_MONITOR SET VALVES_IBM_RECEIVED_DATE = :B2 WHERE TRANSACTION_ID = :B1 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.00 0 0 0 0 Execute 1367 0.22 0.23 2 4101 2060 1367 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 1368 0.22 0.23 2 4101 2060 1367 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ db file sequential read 2 0.00 0.00 ******************************************************************************** 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 Optimizer mode: ALL_ROWS Parsing user id: 38 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 1625.25 1625.25 ******************************************************************************** SELECT par_type, param_clob, param_blob from external_tab$ where obj#=:1 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 3 0.00 0.00 0 0 0 0 Execute 3 0.00 0.00 0 0 0 0 Fetch 3 0.00 0.00 0 6 0 3 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 9 0.00 0.00 0 6 0 3 Misses in library cache during parse: 0 Optimizer mode: CHOOSE Parsing user id: SYS (recursive depth: 2) Rows Row Source Operation ------- --------------------------------------------------- 1 TABLE ACCESS BY INDEX ROWID EXTERNAL_TAB$ (cr=2 pr=0 pw=0 time=91 us) 1 INDEX UNIQUE SCAN I_EXTERNAL_TAB1$ (cr=1 pr=0 pw=0 time=36 us)(object id 502) ******************************************************************************** SELECT PATH, READ, WRITE FROM SYS.LOADER_DIR_OBJS WHERE NAME = :1 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 9 0.00 0.00 0 0 0 0 Execute 9 0.00 0.00 0 0 0 0 Fetch 18 0.01 0.00 0 396 0 9 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 36 0.01 0.01 0 396 0 9 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 2) Rows Row Source Operation ------- --------------------------------------------------- 1 VIEW (cr=44 pr=0 pw=0 time=2205 us) 1 UNION-ALL (cr=44 pr=0 pw=0 time=2198 us) 0 NESTED LOOPS (cr=21 pr=0 pw=0 time=645 us) 8 TABLE ACCESS FULL DIR$ (cr=3 pr=0 pw=0 time=122 us) 0 TABLE ACCESS BY INDEX ROWID OBJ$ (cr=18 pr=0 pw=0 time=505 us) 8 INDEX UNIQUE SCAN I_OBJ1 (cr=10 pr=0 pw=0 time=179 us)(object id 36) 0 FIXED TABLE FULL X$KZSPR (cr=0 pr=0 pw=0 time=152 us) 1 SORT GROUP BY (cr=23 pr=0 pw=0 time=1511 us) 2 FILTER (cr=23 pr=0 pw=0 time=1289 us) 2 HASH JOIN SEMI (cr=23 pr=0 pw=0 time=1207 us) 2 NESTED LOOPS (cr=23 pr=0 pw=0 time=340 us) 1 NESTED LOOPS (cr=21 pr=0 pw=0 time=293 us) 8 TABLE ACCESS FULL DIR$ (cr=3 pr=0 pw=0 time=105 us) 1 TABLE ACCESS BY INDEX ROWID OBJ$ (cr=18 pr=0 pw=0 time=173 us) 8 INDEX UNIQUE SCAN I_OBJ1 (cr=10 pr=0 pw=0 time=85 us)(object id 36) 2 INDEX RANGE SCAN I_OBJAUTH1 (cr=2 pr=0 pw=0 time=65 us)(object id 102) 2 VIEW (cr=0 pr=0 pw=0 time=37 us) 2 FIXED TABLE FULL X$KZSRO (cr=0 pr=0 pw=0 time=31 us) 0 FIXED TABLE FULL X$KZSPR (cr=0 pr=0 pw=0 time=63 us) ******************************************************************************** select file# from file$ where ts#=:1 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 37 0.00 0.00 0 0 0 0 Execute 37 0.00 0.00 0 0 0 0 Fetch 74 0.00 0.00 0 148 0 37 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 148 0.00 0.00 0 148 0 37 Misses in library cache during parse: 0 Optimizer mode: CHOOSE Parsing user id: SYS (recursive depth: 2) Rows Row Source Operation ------- --------------------------------------------------- 1 TABLE ACCESS FULL FILE$ (cr=4 pr=0 pw=0 time=76 us) ******************************************************************************** SELECT SETMAXMEMORYSIZE(500000000) FROM DUAL 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 1 0.02 0.14 0 0 0 1 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 0.02 0.14 0 0 0 1 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) ******************************************************************************** select longname from javasnm$ where short = :1 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 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 1 0.00 0.00 0 0 0 0 Misses in library cache during parse: 0 Optimizer mode: CHOOSE Parsing user id: SYS (recursive depth: 2) ******************************************************************************** select longdbcs from javasnm$ where short = :1 call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 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 1 0.00 0.00 0 0 0 0 Misses in library cache during parse: 0 Optimizer mode: CHOOSE Parsing user id: SYS (recursive depth: 2) ******************************************************************************** SELECT * FROM (SELECT FILENAME, DECODE(FILE_MONREFNAME(FILENAME), 'SoSyc002Out',1,2) STAT_TYPE, FILE_TS(FILENAME) TIMER FROM XML1LIST WHERE NOT EXISTS (SELECT 1 FND FROM XML1_XACTS WHERE XML1_XACTS.FILENAME = XML1LIST.FILENAME) AND FILE_TS(FILENAME) > SYSDATE-30 ORDER BY 2,3) call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 1 0.00 0.02 0 0 0 0 Execute 1 0.06 3.22 0 162 0 0 Fetch 1 0.00 2.32 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 0.06 5.56 0 162 0 0 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 38 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ process startup 11 0.26 0.46 PX Deq: Join ACK 7 0.02 0.05 PX Deq: Parse Reply 5 1.87 1.94 PX Deq: Execute Reply 25 1.77 2.20 PX Deq: Table Q qref 3 0.00 0.00 PX Deq: Signal ACK 13 0.09 0.13 ******************************************************************************** select metadata from kopm$ where name='DB_FDO' 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 1 0.00 0.00 0 2 0 1 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 0.00 0.00 0 2 0 1 Misses in library cache during parse: 0 Optimizer mode: CHOOSE Parsing user id: SYS (recursive depth: 2) Rows Row Source Operation ------- --------------------------------------------------- 1 TABLE ACCESS BY INDEX ROWID KOPM$ (cr=2 pr=0 pw=0 time=56 us) 1 INDEX UNIQUE SCAN I_KOPM1 (cr=1 pr=0 pw=0 time=28 us)(object id 350) ******************************************************************************** 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 39.09 40.67 6 519 0 1 Fetch 0 0.00 0.00 0 0 0 0 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 3 39.09 40.67 6 519 0 1 Misses in library cache during parse: 1 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 1 1625.25 1625.25 OVERALL TOTALS FOR ALL RECURSIVE STATEMENTS call count cpu elapsed disk query current rows ------- ------ -------- ---------- ---------- ---------- ---------- ---------- Parse 62 0.00 0.04 0 0 0 0 Execute 389120 146.32 1609.25 133624 2587231 534439 389061 Fetch 389163 32.03 303.79 91850 3316457 0 389113 ------- ------ -------- ---------- ---------- ---------- ---------- ---------- total 778345 178.37 1913.09 225474 5903688 534439 778174 Misses in library cache during parse: 0 Elapsed times include waiting on following events: Event waited on Times Max. Wait Total Waited ---------------------------------------- Waited ---------- ------------ db file sequential read 34170 1.13 438.46 process startup 11 0.26 0.46 PX Deq: Join ACK 24 0.02 0.05 PX Deq: Parse Reply 21 1.87 1.95 PX Deq: Execute Reply 66 1.77 2.27 PX Deq: Table Q qref 6 0.00 0.00 PX Deq: Signal ACK 23 0.09 0.15 db file scattered read 10726 3.32 262.63 PX Deq Credit: send blkd 284 1.94 1.98 PX Deq Credit: need buffer 499 0.16 0.19 PX Deq: Table Q Normal 3 0.00 0.00 latch: session allocation 1 0.00 0.00 enq: PS - contention 1 0.00 0.00 direct path write temp 1080 0.00 0.00 direct path read temp 2926 0.00 0.00 log file switch completion 30 0.97 17.05 write complete waits 164 0.97 160.30 log file switch (checkpoint incomplete) 520 0.97 497.95 latch: cache buffers chains 1 0.01 0.01 buffer busy waits 1 0.00 0.00 log buffer space 20 0.79 4.65 enq: TX - row lock contention 5 2.93 13.22 latch: library cache 5 0.02 0.03 20 user SQL statements in session. 44 internal SQL statements in session. 64 SQL statements in session. ******************************************************************************** Trace file: valvesp_ora_25077_run_parsers.trc Trace file compatibility: 10.01.00 Sort options: prsdsk exedsk fchdsk 1 session in tracefile. 20 user SQL statements in trace file. 44 internal SQL statements in trace file. 64 SQL statements in trace file. 18 unique SQL statements in trace file. 3550315 lines in trace file. 3579 elapsed seconds in trace file.