How to deal with a Capture gagging on a Large Txn
I'm running into this "large transaction" problem on Streams that I would like some help on.
What happens is I start getting a message in the alert log every 10 mins from the CAPTURE process on the primary that looks like this:
Thu May 1 09:58:10 2008
C001: large txn detected (2404916 LCRs), xid: 0x0005.02a.0004870b
Once these start, it really slows down the streams flow to a crawl. Even though the transaction apparently was completed on Wednesday afternoon, the processing of it by streams is still going on and is holding at just under 4M Log Change Records:
Fri May 2 14:05:36 2008
C001: large txn detected (3988171 LCRs), xid: 0x0005.02a.0004870b
Latency has now backed up to 43 hours!!
I have only recently gained the smarts to be able to find the session history that prompted these. I discovered that several new DBA_HIST views were very helpful in this research. You will recognize my method for translating XID to raw which I mentioned in other posts on this forum. You can see my queries and the details for my problem transaction at the bottom of this post.
Note that all this thing is doing is updating some row(s) and deleting some row(s). From talking to the developers, this is not that many rows involved, maybe 10-20. However, BLOBs are present in both of the affected tables, and they could be sizable. Granted what I really need is for the app to break up this work into smaller units and commit more frequently. Given that this is a 3rd party app, I really can't do anything about that, though, without waiting on the vendor (which we are already doing for other things).
FYI, I do have COMMIT_SERIALIZATION = FULL on the APPLY side, as that was advised in some of the Metalink notes I read.
What I need is answers to one or more of the following questions:
***What would changing COMMIT_SERIALIZATION do to the integrity of my replica?
***Is there a way to flush this transaction that preserves all other transactions and doesn't corrupt the replica? (I'm thinking I could go back and reconstruct the transaction--carefully--directly on the replica...)
I can provide more info about my Streams config if needed...
--------------------------------------------------------------------------------
***
*** Dumping Transaction Information for XID: 0005002A0004870B
***
@find_large_tran &&1
select sql_id, min(sample_time), max(sample_time), count(*)
2 from dba_hist_active_sess_history h where rawtohex(h.xid) = '&1'
3 group by sql_id order by min(sample_time)
4 /
old 2: from dba_hist_active_sess_history h where rawtohex(h.xid) = '&1'
new 2: from dba_hist_active_sess_history h where rawtohex(h.xid) = '0005002A0004870B'
SQL_ID MIN(SAMPLE_TIME) MAX(SAMPLE_TIME) COUNT(*)
------------- ------------------------------ ------------------------------ ----------
ad6x3gpfxk2tx 30-APR-08 03.04.34.490 PM 30-APR-08 03.11.08.640 PM 40
1gfw6kug7ufnf 30-APR-08 03.11.18.750 PM 30-APR-08 03.15.21.310 PM 25
30-APR-08 03.15.31.410 PM 30-APR-08 03.35.23.980 PM 119
@get_sqlbyid &&1
select sql_id, sql_Text from dba_hist_sqltext where sql_id in
2 (select sql_id from dba_hist_active_sess_history h where rawtohex(h.xid) = '&1')
3 /
old 2: (select sql_id from dba_hist_active_sess_history h where rawtohex(h.xid) = '&1')
new 2: (select sql_id from dba_hist_active_sess_history h where rawtohex(h.xid) = '0005002A0004870B')
SQL_ID SQL_TEXT
------------- --------------------------------------------------------------------------------
ad6x3gpfxk2tx update log set message='Scheduler:sun04-v440nuke#1209585938783' where log.actionDateTime<='20071202T06:00:00.000'
1gfw6kug7ufnf delete from logattr where id in (select id from log where message='Scheduler:sun04-v440nuke#1209585938783' )
@lg_tran_detal &&1
select to_char(sample_time,'hhmiss')||':'||sql_id "time:sqlid", sql_opcode||':'||session_type||':'||session_state||'__'||
2 blocking_session||':'||event||':'||p1text||'='||p1||';'||p2text||'='||p2||';'||p3text||'='||p3
3 "op:typ:st__blkr:evt:p1;p2;p3", wait_class||'='||wait_time||':'||time_waited||':'||current_Obj#
4 "wait=time:waited:obj" from dba_hist_active_sess_history h where rawtohex(h.xid) = '&1'
5 order by sample_time
6 /
old 4: "wait=time:waited:obj" from dba_hist_active_sess_history h where rawtohex(h.xid) = '&1'
new 4: "wait=time:waited:obj" from dba_hist_active_sess_history h where rawtohex(h.xid) = '0005002A0004870B'
time:sqlid op:typ:st__blkr:evt:p1;p2;p3 wait=time:waited:obj
---------------------- ------------------------------------------------------------------------------------------ ------------------------------
030434:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=11;block#=521;blocks=1 =55:0:87718
030444:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=1259;blocks=16 =996:0:87718
030454:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=3085;blocks=16 =7988:0:87718
030504:ad6x3gpfxk2tx 6:FOREGROUND:WAITING__:db file scattered read:file#=11;block#=4781;blocks=16 User I/O=0:15578:87718
030514:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=14;block#=5309;blocks=16 =1443:0:87718
030525:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=12;block#=7373;blocks=16 =1028:0:87718
030535:ad6x3gpfxk2tx 6:FOREGROUND:WAITING__:db file scattered read:file#=10;block#=8109;blocks=16 User I/O=0:32389:87718
030545:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=10141;blocks=16 =772:0:87718
030555:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=12701;blocks=16 =1194:0:87718
030605:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=12;block#=13068;blocks=1 =109:0:87718
030615:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=14733;blocks=16 =790:0:87718
030625:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=15389;blocks=16 =1410:0:87718
030635:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=16189;blocks=16 =906:0:87718
030645:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=14;block#=17005;blocks=16 =1052:0:87718
030655:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=18061;blocks=16 =735:0:87718
030706:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=19565;blocks=16 =868:0:87718
030716:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=11;block#=20813;blocks=16 =1476:0:87718
030726:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=11;block#=21405;blocks=16 =2115:0:87718
030736:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=12;block#=23229;blocks=16 =1090:0:87718
030746:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=24717;blocks=16 =823:0:87718
030756:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=14;block#=25693;blocks=16 =1076:0:87718
030806:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=14;block#=27293;blocks=16 =1043:0:87718
030816:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=31121;blocks=16 =990:0:87718
030826:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=12;block#=33185;blocks=16 =22661:0:87718
030837:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=12;block#=39601;blocks=16 =995:0:87718
030847:ad6x3gpfxk2tx 6:FOREGROUND:WAITING__:db file scattered read:file#=11;block#=37633;blocks=16 User I/O=0:1085:87718
030857:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=10;block#=36721;blocks=16 =5054:0:87718
030907:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=10;block#=41921;blocks=16 =3898:0:87718
030917:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=14;block#=41105;blocks=16 =1138:0:87718
030927:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=77889;blocks=16 =923:0:87718
030937:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=82849;blocks=16 =1105:0:87718
030947:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::=0;=0;=0 =27968:0:87718
030957:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=12;block#=132657;blocks=16 =1487:0:87718
031008:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=11;block#=83905;blocks=16 =1664:0:87718
031018:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=11;block#=88081;blocks=16 =722:0:87718
031028:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=10;block#=94465;blocks=16 =917:0:87718
031038:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=10;block#=99681;blocks=16 =1089:0:87718
031048:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=14;block#=99425;blocks=16 =2261:0:87718
031058:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=13;block#=141121;blocks=16 =1322:0:87718
031108:ad6x3gpfxk2tx 6:FOREGROUND:ON CPU__::file#=11;block#=255361;blocks=16 =701:0:87718
031118:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=13;block#=4205;blocks=16 =859:0:87718
031128:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=14;block#=16045;blocks=16 =483:0:87718
031138:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=11;block#=37137;blocks=16 =469:0:87718
031149:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file scattered read:file#=10;block#=245233;blocks=16 User I/O=0:449:87718
031159:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=35;block#=35630;blocks=1 User I/O=0:4436:87873
031209:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=35;block#=12029;blocks=1 User I/O=0:10856:87873
031219:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=35;block#=15113;blocks=1 =382:0:-1
031229:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=35;block#=10524;blocks=1 =5005:0:87874
031239:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=42;block#=18540;blocks=1 =78:0:-1
031249:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=34;block#=14019;blocks=1 =1364:0:87873
031259:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file number=201;first dba=11580;block cnt=15 =81:0:-1
031309:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=30;block#=15243;blocks=1 User I/O=0:5101:87873
031320:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=31;block#=14103;blocks=1 User I/O=0:751:87873
031330:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=33;block#=36574;blocks=1 =1348:0:87874
031340:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=35;block#=18738;blocks=1 User I/O=0:63765:87874
031350:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=33;block#=18852;blocks=1 User I/O=0:63:87874
031400:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=33;block#=37673;blocks=1 User I/O=0:9908:87873
031410:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=42;block#=14542;blocks=1 =329:0:87873
031420:1gfw6kug7ufnf 7:FOREGROUND:WAITING__:db file sequential read:file#=30;block#=16293;blocks=1 User I/O=0:415:87873
031430:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=33;block#=19107;blocks=1 =74:0:87874
031440:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::file#=35;block#=17354;blocks=1 =4188:0:-1
031450:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::address=15996169136;number=131;tries=0 =71:0:-1
031501:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::address=15996169136;number=131;tries=0 =71:0:-1
031511:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::address=16012041424;number=131;tries=0 =63:0:-1
031521:1gfw6kug7ufnf 7:FOREGROUND:ON CPU__::address=15996169136;number=131;tries=0 =33:0:-1
031531: 0:FOREGROUND:ON CPU__::=0;=0;=0 =86394:0:-1
031541: 0:FOREGROUND:ON CPU__::=0;=0;=0 =86394:0:-1
031551: 0:FOREGROUND:ON CPU__::=0;=0;=0 =86394:0:-1
031601: 0:FOREGROUND:ON CPU__::=0;=0;=0 =86394:0:-1
031611: 0:FOREGROUND:ON CPU__::file#=11;block#=85609;blocks=1 =601:0:-1
031621: 0:FOREGROUND:ON CPU__::file#=2;block#=37811;blocks=1 =66:0:-1
031632: 0:FOREGROUND:ON CPU__::file#=2;block#=45942;blocks=1 =71:0:-1
031642: 0:FOREGROUND:ON CPU__::file#=2;block#=39130;blocks=1 =1398:0:-1
031652: 0:FOREGROUND:WAITING__:db file sequential read:file#=13;block#=84223;blocks=1 User I/O=0:3518:-1
031702: 0:FOREGROUND:WAITING__:db file sequential read:file#=13;block#=81916;blocks=1 User I/O=0:4465:-1
031712: 0:FOREGROUND:ON CPU__::file#=13;block#=80169;blocks=1 =57:0:-1
031722: 0:FOREGROUND:WAITING__:db file sequential read:file#=13;block#=78545;blocks=1 User I/O=0:3702:-1
031732: 0:FOREGROUND:WAITING__:db file sequential read:file#=14;block#=45117;blocks=1 User I/O=0:2845:-1
031742: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=22638;blocks=1 User I/O=0:6604:-1
031752: 0:FOREGROUND:ON CPU__::file#=14;block#=42900;blocks=1 =57:0:-1
031803: 0:FOREGROUND:ON CPU__::file#=14;block#=40243;blocks=1 =58:0:-1
031813: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=98026;blocks=1 User I/O=0:3387:-1
031823: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=25345;blocks=1 User I/O=0:22076:-1
031833: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=12271;blocks=1 User I/O=0:5663:-1
031843: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=40527;blocks=1 User I/O=0:8674:-1
031853: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=37389;blocks=1 User I/O=0:5332:-1
031903: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=6300;blocks=1 User I/O=0:5825:-1
031913: 0:FOREGROUND:ON CPU__::file#=11;block#=34983;blocks=1 =62:0:-1
031923: 0:FOREGROUND:ON CPU__::file#=46;block#=38869;blocks=1 =5580:0:-1
031933: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=39829;blocks=1 User I/O=0:16572:-1
031944: 0:FOREGROUND:ON CPU__::file#=12;block#=34576;blocks=1 =774:0:-1
031954: 0:FOREGROUND:ON CPU__::file#=2;block#=60572;blocks=1 =5307:0:-1
032004: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=2564;blocks=1 User I/O=0:5294:-1
032014: 0:FOREGROUND:WAITING__:db file sequential read:file#=13;block#=29967;blocks=1 User I/O=0:760:-1
032024: 0:FOREGROUND:ON CPU__::file#=12;block#=28495;blocks=1 =62:0:-1
032034: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=33357;blocks=1 User I/O=0:5189:-1
032044: 0:FOREGROUND:ON CPU__::file#=46;block#=7163;blocks=1 =7783:0:-1
032054: 0:FOREGROUND:ON CPU__::file#=13;block#=25883;blocks=1 =116:0:-1
032104: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=34047;blocks=1 User I/O=0:6123:-1
032115: 0:FOREGROUND:ON CPU__::file#=11;block#=24742;blocks=1 =780:0:-1
032125: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=14776;blocks=1 User I/O=0:6975:-1
032135: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=860;blocks=1 User I/O=0:5387:-1
032145: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=61740;blocks=1 User I/O=0:6621:-1
032155: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=7734;blocks=1 User I/O=0:2323:-1
032205: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=4122;blocks=1 User I/O=0:7549:-1
032215: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=21706;blocks=1 User I/O=0:30258:-1
032225: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=2880;blocks=1 User I/O=0:17963:-1
032235: 0:FOREGROUND:WAITING__:db file sequential read:file#=14;block#=20399;blocks=1 User I/O=0:75261:-1
032246: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=33775;blocks=1 User I/O=0:12084:-1
032256: 0:FOREGROUND:ON CPU__::file#=14;block#=19364;blocks=1 =130:0:-1
032306: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=88278;blocks=1 User I/O=0:66288:-1
032316: 0:FOREGROUND:ON CPU__::file#=10;block#=18509;blocks=1 =50:0:-1
032326: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=82822;blocks=1 User I/O=0:8339:-1
032336: 0:FOREGROUND:ON CPU__::file#=2;block#=100035;blocks=1 =3194:0:-1
032346: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=21127;blocks=1 User I/O=0:20634:-1
032356: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=63360;blocks=1 User I/O=0:7155:-1
032406: 0:FOREGROUND:ON CPU__::file#=10;block#=16344;blocks=1 =81:0:-1
032416: 0:FOREGROUND:ON CPU__::file#=2;block#=22176;blocks=1 =4853:0:-1
032427: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=17593;blocks=1 User I/O=0:2467:-1
032437: 0:FOREGROUND:ON CPU__::file#=2;block#=20460;blocks=1 =2784:0:-1
032447: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=29425;blocks=1 User I/O=0:3297:-1
032457: 0:FOREGROUND:ON CPU__::file#=14;block#=15142;blocks=1 =85:0:-1
032507: 0:FOREGROUND:ON CPU__::file#=10;block#=14447;blocks=1 =55:0:-1
032517: 0:FOREGROUND:ON CPU__::file#=12;block#=14529;blocks=1 =91:0:-1
032527: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=14624;blocks=1 User I/O=0:22545:-1
032537: 0:FOREGROUND:ON CPU__::file#=10;block#=13378;blocks=1 =77:0:-1
032547: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=31351;blocks=1 User I/O=0:12254:-1
032558: 0:FOREGROUND:ON CPU__::file#=13;block#=13458;blocks=1 =4509:0:-1
032608: 0:FOREGROUND:WAITING__:db file sequential read:file#=14;block#=12640;blocks=1 User I/O=0:20244:-1
032618: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=25676;blocks=1 User I/O=0:3266:-1
032628: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=31027;blocks=1 User I/O=0:6563:-1
032638: 0:FOREGROUND:WAITING__:db file sequential read:file#=13;block#=12531;blocks=1 User I/O=0:3831:-1
032648: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=30300;blocks=1 User I/O=0:4426:-1
032658: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=11017;blocks=1 User I/O=0:7876:-1
032708: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=19324;blocks=1 User I/O=0:7390:-1
032718: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=23538;blocks=1 User I/O=0:8761:-1
032728: 0:FOREGROUND:ON CPU__::file#=46;block#=27536;blocks=1 =6802:0:-1
032739: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=34788;blocks=1 User I/O=0:5926:-1
032749: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=8990;blocks=1 User I/O=0:79:-1
032759: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=100827;blocks=1 User I/O=0:12831:-1
032809: 0:FOREGROUND:WAITING__:db file sequential read:file#=13;block#=9545;blocks=1 User I/O=0:5069:-1
032819: 0:FOREGROUND:WAITING__:db file sequential read:file#=14;block#=8817;blocks=1 User I/O=0:4899:-1
032829: 0:FOREGROUND:ON CPU__::file#=10;block#=8593;blocks=1 =4587:0:-1
032839: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=8414;blocks=1 User I/O=0:5169:-1
032849: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=54871;blocks=1 User I/O=0:5123:-1
032859: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=53065;blocks=1 User I/O=0:58965:-1
032910: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=46950;blocks=1 User I/O=0:6362:-1
032920: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=7941;blocks=1 User I/O=0:10388:-1
032930: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=32092;blocks=1 User I/O=0:12542:-1
032940: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=27855;blocks=1 User I/O=0:6997:-1
032950: 0:FOREGROUND:WAITING__:db file sequential read:file#=14;block#=6858;blocks=1 User I/O=0:4449:-1
033000: 0:FOREGROUND:ON CPU__::file#=10;block#=6647;blocks=1 =4528:0:-1
033010: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=6556;blocks=1 User I/O=0:5874:-1
033020: 0:FOREGROUND:WAITING__:db file sequential read:file#=46;block#=3562;blocks=1 User I/O=0:7914:-1
033030: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=97606;blocks=1 User I/O=0:21775:-1
033041: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=94986;blocks=1 User I/O=0:4243:-1
033051: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=5242;blocks=1 User I/O=0:6397:-1
033101: 0:FOREGROUND:ON CPU__::file#=11;block#=5028;blocks=1 =4559:0:-1
033111: 0:FOREGROUND:WAITING__:db file sequential read:file#=12;block#=4950;blocks=1 User I/O=0:5189:-1
033121: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=76150;blocks=1 User I/O=0:1856:-1
033131: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=4844;blocks=1 User I/O=0:9911:-1
033141: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=4851;blocks=1 User I/O=0:6822:-1
033151: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=63031;blocks=1 User I/O=0:5187:-1
033201: 0:FOREGROUND:WAITING__:db file sequential read:file#=12;block#=4014;blocks=1 User I/O=0:4682:-1
033211: 0:FOREGROUND:ON CPU__::file#=13;block#=3842;blocks=1 =4191:0:-1
033222: 0:FOREGROUND:WAITING__:db file sequential read:file#=14;block#=3127;blocks=1 User I/O=0:4679:-1
033232: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=2875;blocks=1 User I/O=0:6710:-1
033242: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=2863;blocks=1 User I/O=0:74359:-1
033252: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=74558;blocks=1 User I/O=0:1501:-1
033302: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=71994;blocks=1 User I/O=0:44537:-1
033312: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=2567;blocks=1 User I/O=0:5230:-1
033322: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=2529;blocks=1 User I/O=0:3693:-1
033332: 0:FOREGROUND:WAITING__:db file sequential read:file#=12;block#=2517;blocks=1 User I/O=0:3987:-1
033342: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=91080;blocks=1 User I/O=0:8004:-1
033353: 0:FOREGROUND:WAITING__:db file sequential read:file#=11;block#=1769;blocks=1 User I/O=0:6714:-1
033403: 0:FOREGROUND:ON CPU__::file#=14;block#=1670;blocks=1 =1139:0:-1
033413: 0:FOREGROUND:WAITING__:db file sequential read:file#=14;block#=1509;blocks=1 User I/O=0:6842:-1
033423: 0:FOREGROUND:ON CPU__::file#=14;block#=1305;blocks=1 =4793:0:-1
033433: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=1074;blocks=1 User I/O=0:68179:-1
033443: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=54624;blocks=1 User I/O=0:4598:-1
033453: 0:FOREGROUND:ON CPU__::file#=2;block#=52930;blocks=1 =2290:0:-1
033503: 0:FOREGROUND:WAITING__:db file sequential read:file#=2;block#=51425;blocks=1 User I/O=0:45119:-1
033513: 0:FOREGROUND:WAITING__:db file sequential read:file#=10;block#=481;blocks=1 User I/O=0:3063:-1
033523: 0:FOREGROUND:ON CPU__::file#=12;block#=380;blocks=1 =4927:0:-1
184 rows selected.