使用10046事件檢視資料庫OPEN時從root dba到bootstrap$的詳細引導過程

資料庫版本11.2.0.4

1.使用10046事件檢視資料庫OPEN時的TRACE檔案：

SYS@ bys3>startup mount;
ORACLE instance started.
Total System Global Area  150654976 bytes
Fixed Size                  1363216 bytes
Variable Size              96469744 bytes
Database Buffers           50331648 bytes
Redo Buffers                2490368 bytes
Database mounted.
SYS@ bys3>alter session set events='10046 trace name context forever,level 12';
Session altered.
SYS@ bys3>alter database open;
Database altered.
SYS@ bys3>select value from v$diag_info where name like 'Default%';    其它檢視TRACE檔案位置方法是：oradebug setmypid;   oradebug tracefile_name  或者直接在user_dump_dest目錄中使用 ls -alt 找最近生成的檔案
VALUE
-------------------------------------------------------------------------------------------------
/u01/app/oracle/product/11.2.0/dbhome_1/log/diag/rdbms/bys3/bys3/trace/bys3_ora_13020.trc
####################################

引導過程：     關於root dba及 bootstrap$,詳見：http://blog.csdn.net/q947817003/article/details/17025955

1.ORACLE首先讀取控制檔案獲取資料檔案，b file sequential read 單塊讀方式讀取每個資料檔案第一個block 資料檔案頭的資訊，
2.然後用db file sequential read方式讀取了file 1 block#520的內容（這一步應該有從file 1 blcok 1找到root dba來定位到file#1 block#520，不知道怎麼從TRACE檔案中看？？？）
WAIT #3064435524: nam='db file sequential read' ela= 264 file#=1 block#=520 blocks=1 obj#=-1 tim=1385703907158975
3.ORACLE找到了file#=1 block#=520後，讀取其內容，在記憶體中建立：
PARSING IN CURSOR #3064429824 len=188 dep=1 uid=0 oct=1 lid=0 tim=1385703907172599 hv=4006182593 ad='257ea26c' sqlid='32r4f1brckzq1'
create table bootstrap$ (
END OF STMT
4.此後ORACLE通過遞迴查詢，從bootstrap$中獲取建立物件的語句：
PARSING IN CURSOR #3064429824 len=55 dep=1 uid=0 oct=3 lid=0 tim=1385703907195679 hv=2111436465 ad='257e8e88' sqlid='6apq2rjyxmxpj'
select line#, sql_text from bootstrap$ where obj# != :1
END OF STMT
5.然後，根據查出語句，建立這些物件：如下：   這些建立物件語句在資料庫中也通過select * from bootstrap$ 可以查到
=====================
PARSING IN CURSOR #3064429824 len=129 dep=1 uid=0 oct=36 lid=0 tim=1385703907299714 hv=1119914026 ad='b6a51d5c' sqlid='864bmh11c121a'
CREATE ROLLBACK SEGMENT SYSTEM STORAGE (  INITIAL 112K NEXT 56K MINEXTENTS 1 MAXEXTENTS 32765 OBJNO 0 EXTENTS (FILE 1 BLOCK 128))
END OF STMT
PARSE #3064429824:c=999,e=31611,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=1385703907299710
EXEC #3064429824:c=1000,e=18763,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=0,tim=1385703907318789
CLOSE #3064429824:c=0,e=7,dep=1,type=0,tim=1385703907318912
=====================
6.再然後，建立完這些物件後，就正常開啟資料庫。
########################################

2.關於轉儲檔案中的幾個等待事件簡單說明：

Disk file operations I/O
This event is used to wait for disk file operations (for example, open, close, seek, and resize). It is also used for miscellaneous I/O operations such as block dumps and password file accesses.
control file sequential read
控制檔案連續讀
rdbms ipc reply
這個事件說明等待一個後臺程式的響應。
db file sequential read
等待時間是由於執行對索引，回滾（undo）段，和表（當藉助rowid來訪問），控制檔案和資料檔案頭的單塊讀操作SQL語句（使用者和遞迴）引起的。
control file parallel write-控制檔案並行寫
當server 程式更新所有控制檔案時，這個事件可能出現。如果等待很短，可以不用考慮。如果等待時間較長，檢查存放控制檔案的物理磁碟I/O 是否存在瓶頸
########################################

3.TRACE檔案內容---擷取部分

Trace file /u01/app/oracle/product/11.2.0/dbhome_1/log/diag/rdbms/bys3/bys3/trace/bys3_ora_13020.trc
Oracle Database 11g Enterprise Edition Release 11.2.0.4.0 - Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options
ORACLE_HOME = /u01/app/oracle/product/11.2.0/dbhome_1
System name:    Linux
Node name:    bys3.bys.com
Release:    2.6.32-200.13.1.el5uek
Version:    #1 SMP Wed Jul 27 20:21:26 EDT 2011
Machine:    i686
Instance name: bys3
Redo thread mounted by this instance: 1
Oracle process number: 17
Unix process pid: 13020, image: oracle@bys3.bys.com (TNS V1-V3)

*** 2013-11-29 13:44:59.723
*** SESSION ID:(1.5) 2013-11-29 13:44:59.723
*** CLIENT ID:() 2013-11-29 13:44:59.723
*** SERVICE NAME:() 2013-11-29 13:44:59.723
*** MODULE NAME:(sqlplus@bys3.bys.com (TNS V1-V3)) 2013-11-29 13:44:59.723
*** ACTION NAME:() 2013-11-29 13:44:59.723
 
WAIT #3064440660: nam='SQL*Net message to client' ela= 10 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1385703899721694

*** 2013-11-29 13:45:05.589
WAIT #3064440660: nam='SQL*Net message from client' ela= 5865444 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1385703905589082
CLOSE #3064440660:c=0,e=28,dep=0,type=1,tim=1385703905590081
XCTEND rlbk=0, rd_only=1, tim=1385703905595227
=====================
PARSING IN CURSOR #3064435524 len=19 dep=0 uid=0 oct=35 lid=0 tim=1385703905623330 hv=1907384048 ad='25eddd7c' sqlid='a01hp0psv0rrh'
alter database open
END OF STMT
PARSE #3064435524:c=12999,e=33127,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,plh=0,tim=1385703905623322
WAIT #3064435524: nam='Disk file operations I/O' ela= 550 FileOperation=2 fileno=0 filetype=1 obj#=-1 tim=1385703905629002
WAIT #3064435524: nam='Disk file operations I/O' ela= 33 FileOperation=2 fileno=1 filetype=1 obj#=-1 tim=1385703905629484
WAIT #3064435524: nam='control file sequential read' ela= 188 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905629831
WAIT #3064435524: nam='control file sequential read' ela= 47 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905630128            ---讀取資料檔案1的第1個塊
WAIT #3064435524: nam='control file sequential read' ela= 23 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703905630278
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703905630632
WAIT #3064435524: nam='control file sequential read' ela= 39 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905633356
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703905633910
WAIT #3064435524: nam='control file sequential read' ela= 38 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703905634005
WAIT #3064435524: nam='rdbms ipc reply' ela= 60 from_process=10 timeout=910 p3=0 obj#=-1 tim=1385703905637300
WAIT #3064435524: nam='rdbms ipc reply' ela= 127477 from_process=10 timeout=910 p3=0 obj#=-1 tim=1385703905764875
WAIT #3064435524: nam='control file sequential read' ela= 52 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905765507
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703905765632
WAIT #3064435524: nam='control file sequential read' ela= 42 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703905765734
WAIT #3064435524: nam='control file sequential read' ela= 68 file#=0 block#=291 blocks=1 obj#=-1 tim=1385703905765869
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905766297
WAIT #3064435524: nam='control file sequential read' ela= 47 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905766543
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703905766642
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703905766731
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=291 blocks=1 obj#=-1 tim=1385703905766860
WAIT #3064435524: nam='Disk file operations I/O' ela= 69 FileOperation=2 fileno=1 filetype=2 obj#=-1 tim=1385703905767279
WAIT #3064435524: nam='Disk file operations I/O' ela= 23 FileOperation=2 fileno=2 filetype=2 obj#=-1 tim=1385703905767380
WAIT #3064435524: nam='Disk file operations I/O' ela= 54 FileOperation=2 fileno=3 filetype=2 obj#=-1 tim=1385703905767824
WAIT #3064435524: nam='Disk file operations I/O' ela= 51 FileOperation=2 fileno=4 filetype=2 obj#=-1 tim=1385703905767914
WAIT #3064435524: nam='Disk file operations I/O' ela= 20 FileOperation=2 fileno=201 filetype=2 obj#=-1 tim=1385703905767972
WAIT #3064435524: nam='control file sequential read' ela= 44 file#=0 block#=16 blocks=1 obj#=-1 tim=1385703905768068
WAIT #3064435524: nam='db file sequential read' ela= 68 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905768369
WAIT #3064435524: nam='db file sequential read' ela= 26 file#=2 block#=1 blocks=1 obj#=-1 tim=1385703905768933
WAIT #3064435524: nam='db file sequential read' ela= 28 file#=3 block#=1 blocks=1 obj#=-1 tim=1385703905769117
WAIT #3064435524: nam='db file sequential read' ela= 27 file#=4 block#=1 blocks=1 obj#=-1 tim=1385703905769223
WAIT #3064435524: nam='control file parallel write' ela= 744 files=2 block#=9 requests=2 obj#=-1 tim=1385703905770129
WAIT #3064435524: nam='control file parallel write' ela= 1244 files=2 block#=7 requests=2 obj#=-1 tim=1385703905771569
WAIT #3064435524: nam='control file parallel write' ela= 534 files=2 block#=1 requests=2 obj#=-1 tim=1385703905772171
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905772336
WAIT #3064435524: nam='control file sequential read' ela= 65 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905772461
WAIT #3064435524: nam='control file sequential read' ela= 42 file#=0 block#=7 blocks=1 obj#=-1 tim=1385703905772672
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=9 blocks=1 obj#=-1 tim=1385703905772771
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=0 block#=291 blocks=1 obj#=-1 tim=1385703905772901
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905773134
WAIT #3064435524: nam='control file sequential read' ela= 44 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905773236
WAIT #3064435524: nam='control file sequential read' ela= 39 file#=0 block#=7 blocks=1 obj#=-1 tim=1385703905773317
WAIT #3064435524: nam='control file sequential read' ela= 44 file#=0 block#=9 blocks=1 obj#=-1 tim=1385703905773400
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=291 blocks=1 obj#=-1 tim=1385703905773549
WAIT #3064435524: nam='control file parallel write' ela= 607 files=2 block#=292 requests=2 obj#=-1 tim=1385703905774316
WAIT #3064435524: nam='control file parallel write' ela= 709 files=2 block#=10 requests=2 obj#=-1 tim=1385703905775097
WAIT #3064435524: nam='control file parallel write' ela= 610 files=2 block#=8 requests=2 obj#=-1 tim=1385703905775773
WAIT #3064435524: nam='control file parallel write' ela= 594 files=2 block#=1 requests=2 obj#=-1 tim=1385703905776514
WAIT #3064435524: nam='control file sequential read' ela= 42 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905779031
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905779254
WAIT #3064435524: nam='control file sequential read' ela= 48 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703905779358
WAIT #3064435524: nam='control file sequential read' ela= 64 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703905779464
WAIT #3064435524: nam='control file sequential read' ela= 48 file#=0 block#=331 blocks=1 obj#=-1 tim=1385703905779621
WAIT #3064435524: nam='control file parallel write' ela= 38493 files=2 block#=332 requests=2 obj#=-1 tim=1385703905818160
WAIT #3064435524: nam='control file parallel write' ela= 606 files=2 block#=9 requests=2 obj#=-1 tim=1385703905818848
WAIT #3064435524: nam='control file parallel write' ela= 934 files=2 block#=7 requests=2 obj#=-1 tim=1385703905819850
WAIT #3064435524: nam='control file parallel write' ela= 596 files=2 block#=1 requests=2 obj#=-1 tim=1385703905820754
WAIT #3064435524: nam='control file sequential read' ela= 42 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905821083
WAIT #3064435524: nam='control file sequential read' ela= 39 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905821240
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=7 blocks=1 obj#=-1 tim=1385703905821325
WAIT #3064435524: nam='control file sequential read' ela= 68 file#=0 block#=9 blocks=1 obj#=-1 tim=1385703905821441
WAIT #3064435524: nam='control file sequential read' ela= 47 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905821631
WAIT #3064435524: nam='control file sequential read' ela= 39 file#=1 block#=1 blocks=1 obj#=-1 tim=1385703905821729
WAIT #3064435524: nam='control file sequential read' ela= 44 file#=0 block#=7 blocks=1 obj#=-1 tim=1385703905821813
WAIT #3064435524: nam='control file sequential read' ela= 42 file#=0 block#=9 blocks=1 obj#=-1 tim=1385703905822220
WAIT #3064435524: nam='control file sequential read' ela= 45 file#=0 block#=11 blocks=1 obj#=-1 tim=1385703905822329
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703905822488
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=7 blocks=1 obj#=-1 tim=1385703905822575
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=9 blocks=1 obj#=-1 tim=1385703905822659
WAIT #3064435524: nam='rdbms ipc reply' ela= 23 from_process=11 timeout=1800 p3=0 obj#=-1 tim=1385703905826080

*** 2013-11-29 13:45:07.141
WAIT #3064435524: nam='rdbms ipc reply' ela= 1314812 from_process=11 timeout=1800 p3=0 obj#=-1 tim=1385703907140989
WAIT #3064435524: nam='control file sequential read' ela= 101 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703907142993
WAIT #3064435524: nam='control file sequential read' ela= 42 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703907143128
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703907143343
WAIT #3064435524: nam='control file sequential read' ela= 58 file#=0 block#=292 blocks=1 obj#=-1 tim=1385703907143462
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703907143570
WAIT #3064435524: nam='control file sequential read' ela= 39 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703907143651
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703907143739
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=292 blocks=1 obj#=-1 tim=1385703907143821
WAIT #3064435524: nam='control file sequential read' ela= 41 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703907143944
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703907144025
WAIT #3064435524: nam='control file sequential read' ela= 42 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703907144110
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=292 blocks=1 obj#=-1 tim=1385703907144192
WAIT #3064435524: nam='control file sequential read' ela= 44 file#=0 block#=1 blocks=1 obj#=-1 tim=1385703907148808
WAIT #3064435524: nam='control file sequential read' ela= 49 file#=0 block#=8 blocks=1 obj#=-1 tim=1385703907148929
WAIT #3064435524: nam='control file sequential read' ela= 40 file#=0 block#=10 blocks=1 obj#=-1 tim=1385703907149011
WAIT #3064435524: nam='control file sequential read' ela= 43 file#=0 block#=292 blocks=1 obj#=-1 tim=1385703907149094
WAIT #3064435524: nam='db file sequential read' ela= 264 file#=1 block#=520blocks=1 obj#=-1 tim=1385703907158975
=====================
PARSING IN CURSOR #3064429824 len=188 dep=1 uid=0 oct=1 lid=0 tim=1385703907172599 hv=4006182593 ad='257ea26c' sqlid='32r4f1brckzq1'
create table bootstrap$ (
END OF STMT
PARSE #3064429824:c=5999,e=11363,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=1385703907172594
EXEC #3064429824:c=2000,e=16397,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=0,tim=1385703907191131
CLOSE #3064429824:c=1000,e=36,dep=1,type=0,tim=1385703907191434
=====================
PARSING IN CURSOR #3064429824 len=55 dep=1 uid=0 oct=3 lid=0 tim=1385703907195679 hv=2111436465 ad='257e8e88' sqlid='6apq2rjyxmxpj'
select line#, sql_text from bootstrap$ where obj# != :1
END OF STMT
PARSE #3064429824:c=2999,e=4055,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=1385703907195675
BINDS #3064429824:
 Bind#0
  oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
  oacflg=08 fl2=0001 frm=00 csi=00 siz=24 off=0
  kxsbbbfp=b6a778d0  bln=22  avl=02  flg=05
  value=59
EXEC #3064429824:c=7999,e=47324,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=867914364,tim=1385703907244586
WAIT #3064429824: nam='db file sequential read' ela= 246 file#=1 block#=520 blocks=1 obj#=59 tim=1385703907245408
WAIT #3064429824: nam='db file scattered read' ela= 470 file#=1 block#=521 blocks=3 obj#=59 tim=1385703907248639
FETCH #3064429824:c=3999,e=7831,p=4,cr=3,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907252714
FETCH #3064429824:c=0,e=45,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907252920
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907252978
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253032
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253089
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253148
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253201
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253254
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253307
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253359
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253412
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907253486
FETCH #3064429824:c=0,e=11,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907254729
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907254803
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907254856
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907254908
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907254959
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255011
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255072
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255125
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255176
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255228
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255280
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255331
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255386
FETCH #3064429824:c=0,e=13,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255457
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255512
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255564
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255615
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907255666
FETCH #3064429824:c=0,e=12,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907256923
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907256977
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257028
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257080
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257131
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257232
FETCH #3064429824:c=0,e=13,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257453
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257508
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257560
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257611
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257661
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257716
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257773
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907257825
FETCH #3064429824:c=0,e=11,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259036
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259110
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259162
FETCH #3064429824:c=0,e=11,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259215
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259271
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259322
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259383
FETCH #3064429824:c=0,e=12,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259452
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259507
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259559
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259610
FETCH #3064429824:c=0,e=8,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259661
FETCH #3064429824:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259717
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259773
FETCH #3064429824:c=0,e=9,p=0,cr=1,cu=0,mis=0,r=1,dep=1,og=4,plh=867914364,tim=1385703907259824
FETCH #3064429824:c=0,e=8,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=867914364,tim=1385703907259875
STAT #3064429824 id=1 cnt=59 pid=0 pos=1 obj=59 op='TABLE ACCESS FULL BOOTSTRAP$ (cr=61 pr=4 pw=0 time=7811 us)'
CLOSE #3064429824:c=0,e=10,dep=1,type=0,tim=1385703907267895
=====================
PARSING IN CURSOR #3064429824 len=129 dep=1 uid=0 oct=36 lid=0 tim=1385703907299714 hv=1119914026 ad='b6a51d5c' sqlid='864bmh11c121a'
CREATE ROLLBACK SEGMENT SYSTEM STORAGE (  INITIAL 112K NEXT 56K MINEXTENTS 1 MAXEXTENTS 32765 OBJNO 0 EXTENTS (FILE 1 BLOCK 128))      ----這些建立物件語句在資料庫中通過select * from bootstrap$ 可以查到
END OF STMT
PARSE #3064429824:c=999,e=31611,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=1385703907299710
EXEC #3064429824:c=1000,e=18763,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=0,tim=1385703907318789
CLOSE #3064429824:c=0,e=7,dep=1,type=0,tim=1385703907318912
=====================
PARSING IN CURSOR #3064429824 len=209 dep=1 uid=0 oct=4 lid=0 tim=1385703907324096 hv=1323908363 ad='b6a51d0c' sqlid='7j058yj7fkg8b'
CREATE CLUSTER C_OBJ#("OBJ#" NUMBER) PCTFREE 5 PCTUSED 40 INITRANS 2 MAXTRANS 255 STORAGE (  INITIAL 136K NEXT 200K MINEXTENTS 1 MAXEXTENTS 2147483645 PCTINCREASE 0 OBJNO 2 EXTENTS (FILE 1 BLOCK 144)) SIZE 800
END OF STMT
PARSE #3064429824:c=0,e=5135,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=1385703907324091
EXEC #3064429824:c=2000,e=420,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=0,tim=1385703907324715
CLOSE #3064429824:c=0,e=7,dep=1,type=0,tim=1385703907324827
=====================
PARSING IN CURSOR #3064429824 len=191 dep=1 uid=0 oct=9 lid=0 tim=1385703907361450 hv=2739073813 ad='257d8b18' sqlid='0cmnx32jn5wsp'
CREATE INDEX I_OBJ# ON CLUSTER C_OBJ# PCTFREE 10 INITRANS 2 MAXTRANS 255 STORAGE (  INITIAL 64K NEXT 1024K MINEXTENTS 1 MAXEXTENTS 2147483645 PCTINCREASE 0 OBJNO 3 EXTENTS (FILE 1 BLOCK 168))
END OF STMT