• Oracle Database 12c R2
• 2017.03.02
• 渡部 亮太

オンプレミス版Oracle Database 12.2.0.1がリリースされたので、早速RACをインストールしてみました :-)

オンプレミス版Oracle Database 12.2.0.1がリリースされたので、早速RACをインストールしてみました 🙂

UNIFORM_LOG_TIMESTAMP_FORMATの導入にともない、タイムスタンプの出力形式が変わっているのが、まず目につきます。

また、何やら見慣れないメッセージがいくつか見られますが・・・・追って、チェックしてまいりましょうかね・・・。

2017-03-02T15:39:40.537062+09:00
Starting ORACLE instance (normal) (OS id: 1426)
2017-03-02T15:39:40.551188+09:00
CLI notifier numLatches:3 maxDescs:519
2017-03-02T15:39:40.569683+09:00
**********************************************************************
2017-03-02T15:39:40.569825+09:00
Dump of system resources acquired for SHARED GLOBAL AREA (SGA) 

2017-03-02T15:39:40.570043+09:00
 Per process system memlock (soft) limit = 128G
2017-03-02T15:39:40.570188+09:00
 Expected per process system memlock (soft) limit to lock
 SHARED GLOBAL AREA (SGA) into memory: 1504M
2017-03-02T15:39:40.570432+09:00
 Available system pagesizes:
  4K, 2048K 
2017-03-02T15:39:40.570692+09:00
 Supported system pagesize(s):
2017-03-02T15:39:40.570820+09:00
  PAGESIZE  AVAILABLE_PAGES  EXPECTED_PAGES  ALLOCATED_PAGES  ERROR(s)
2017-03-02T15:39:40.571161+09:00
        4K       Configured          385027          385027        NONE
2017-03-02T15:39:40.571295+09:00
 Reason for not supporting certain system pagesizes: 
2017-03-02T15:39:40.571440+09:00
  2048K - Dynamic allocate and free memory regions
2017-03-02T15:39:40.571616+09:00
**********************************************************************
2017-03-02T15:39:41.882763+09:00
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
2017-03-02T15:39:41.917995+09:00
Initial number of CPU is 2
Number of processor cores in the system is 1
Number of processor sockets in the system is 1
Exafusion(Msgq-Auto) enabled
Using LOG_ARCHIVE_DEST_1 parameter default value as USE_DB_RECOVERY_FILE_DEST
Autotune of undo retention is turned on. 
IMODE=BR
ILAT =51
LICENSE_MAX_USERS = 0
SYS auditing is enabled
NOTE: remote asm mode is remote (mode 0x2; from cluster type)
NOTE: Cluster configuration type = CLUSTER [4]
Oracle Database 12c Enterprise Edition Release 12.2.0.1.0 - 64bit Production.
ORACLE_HOME:    /u01/app/oracle/product/12.2.0/dbhome_1
System name:    Linux
Node name:  lc201rag1.domain
Release:    4.1.12-37.4.1.el6uek.x86_64
Version:    #2 SMP Tue May 17 07:23:38 PDT 2016
Machine:    x86_64
VM name:    Xen Version: 4.1 (HVM)
Using parameter settings in client-side pfile /u01/app/oracle/admin/c201/pfile/init.ora on machine lc201rag1.domain
System parameters with non-default values:
  processes                = 300
  nls_language             = "AMERICAN"
  nls_territory            = "AMERICA"
  memory_target            = 1504M
  control_files            = "+DATA/C201/CONTROLFILE/current.261.937582391"
  control_files            = "+FRA/C201/CONTROLFILE/current.256.937582391"
  db_block_size            = 8192
  compatible               = "12.2.0"
  log_archive_format       = "%t_%s_%r.dbf"
  db_create_file_dest      = "+DATA"
  db_recovery_file_dest    = "+FRA"
  db_recovery_file_dest_size= 16062M
  remote_login_passwordfile= "EXCLUSIVE"
  db_domain                = "domain"
  dispatchers              = "(PROTOCOL=TCP) (SERVICE=c201XDB)"
  local_listener           = "-oraagent-dummy-"
  audit_file_dest          = "/u01/app/oracle/admin/c201/adump"
  audit_trail              = "DB"
  db_name                  = "c201"
  open_cursors             = 300
  diagnostic_dest          = "/u01/app/oracle"
  enable_pluggable_database= TRUE
NOTE: remote asm mode is remote (mode 0x2; from cluster type)
2017-03-02T15:39:42.399481+09:00
Cluster Communication is configured to use IPs from: GPnP
KSIPC Loopback IP addresses(OSD): 
127.0.0.1   
KSIPC Available Transports: UDP:TCP
KSIPC: Client: KCL   Transport: UDP
KSIPC: Client: DLM   Transport: UDP
KSIPC CAPABILITIES :IPCLW:GRPAM:TOPO:DLL
KSXP: ksxpsg_ipclwtrans: 2 UDP
cluster interconnect IPC version: [IPCLW over UDP(mode 3) ]
IPC Vendor 1 proto 2
Oracle instance running with ODM: Oracle Direct NFS ODM Library Version 4.0 
============================================================
NOTE: PatchLevel of this instance 0
============================================================
Starting background process PMON
2017-03-02T15:39:42.731736+09:00
PMON started with pid=2, OS id=1448 
Starting background process CLMN
2017-03-02T15:39:42.776871+09:00
CLMN started with pid=3, OS id=1452 
Starting background process PSP0
2017-03-02T15:39:42.820876+09:00
PSP0 started with pid=4, OS id=1456 
Starting background process IPC0
2017-03-02T15:39:43.870946+09:00
Starting background process VKTM
2017-03-02T15:39:43.870978+09:00
IPC0 started with pid=5, OS id=1460 
2017-03-02T15:39:43.921254+09:00
VKTM started with pid=6, OS id=1464 at elevated (RT) priority
2017-03-02T15:39:43.923867+09:00
VKTM running at (1)millisec precision with DBRM quantum (100)ms
Starting background process GEN0
Starting background process MMAN
2017-03-02T15:39:43.977337+09:00
GEN0 started with pid=7, OS id=1470 
2017-03-02T15:39:44.015773+09:00
MMAN started with pid=8, OS id=1474 
Starting background process GEN1
2017-03-02T15:39:44.129609+09:00
GEN1 started with pid=10, OS id=1482_1486 
Starting background process DIAG
2017-03-02T15:39:44.169793+09:00
DIAG started with pid=12, OS id=1490 
Starting background process OFSD
Starting background process DBRM
2017-03-02T15:39:44.245690+09:00
OFSD started with pid=13, OS id=1494_1498 
2017-03-02T15:39:44.246919+09:00
Oracle running with ofslib:'Oracle File Server Library' version=2
2017-03-02T15:39:44.336670+09:00
DBRM started with pid=15, OS id=1503 
Starting background process VKRM
Starting background process PING
2017-03-02T15:39:44.397126+09:00
VKRM started with pid=9, OS id=1512 
Starting background process SVCB
2017-03-02T15:39:44.442720+09:00
PING started with pid=16, OS id=1517 
Starting background process ACMS
2017-03-02T15:39:44.490323+09:00
SVCB started with pid=17, OS id=1521 
Starting background process PMAN
2017-03-02T15:39:44.546901+09:00
ACMS started with pid=18, OS id=1525 
Starting background process DIA0
2017-03-02T15:39:44.595143+09:00
PMAN started with pid=19, OS id=1530 
Starting background process LMON
2017-03-02T15:39:44.636098+09:00
DIA0 started with pid=20, OS id=1534 
Starting background process LMD0
2017-03-02T15:39:44.683653+09:00
LMON started with pid=21, OS id=1538 
2017-03-02T15:39:44.735138+09:00
LMD0 started with pid=22, OS id=1543 
Starting background process RMS0
2017-03-02T15:39:44.737826+09:00
* Load Monitor used for high load check 
* New Low - High Load Threshold Range = [1920 - 2560] 
Starting background process LMHB
2017-03-02T15:39:44.773017+09:00
RMS0 started with pid=23, OS id=1549 
Starting background process DBW0
2017-03-02T15:39:44.812528+09:00
LMHB started with pid=24, OS id=1553 
2017-03-02T15:39:44.861102+09:00
DBW0 started with pid=25, OS id=1557 
Starting background process LGWR
2017-03-02T15:39:44.897627+09:00
LGWR started with pid=26, OS id=1561 
2017-03-02T15:39:44.897650+09:00
Starting background process CKPT
2017-03-02T15:39:44.938384+09:00
CKPT started with pid=27, OS id=1565 
2017-03-02T15:39:44.978587+09:00
LGWR slave LG00 created with pid=28, OS pid=1569
Starting background process SMON
2017-03-02T15:39:45.020857+09:00
SMON started with pid=29, OS id=1573 
LGWR slave LG01 created with pid=30, OS pid=1577
Starting background process SMCO
Starting background process RECO
2017-03-02T15:39:45.171631+09:00
SMCO started with pid=31, OS id=1581 
2017-03-02T15:39:45.210297+09:00
RECO started with pid=32, OS id=1585 
Starting background process LREG
2017-03-02T15:39:45.294314+09:00
LREG started with pid=34, OS id=1593 
Starting background process PXMN
2017-03-02T15:39:45.379810+09:00
PXMN started with pid=36, OS id=1603 
Starting background process RBAL
2017-03-02T15:39:45.419098+09:00
RBAL started with pid=37, OS id=1609 
Starting background process ASMB
2017-03-02T15:39:45.456572+09:00
ASMB started with pid=38, OS id=1614 
Starting background process FENC
2017-03-02T15:39:45.499488+09:00
FENC started with pid=39, OS id=1618 
Starting background process MMON
Starting background process MMNL
2017-03-02T15:39:45.539532+09:00
MMON started with pid=40, OS id=1622 
2017-03-02T15:39:45.576321+09:00
starting up 1 dispatcher(s) for network address '(ADDRESS=(PARTIAL=YES)(PROTOCOL=TCP))'...
2017-03-02T15:39:45.576329+09:00
MMNL started with pid=41, OS id=1631 
starting up 1 shared server(s) ...
2017-03-02T15:39:45.789640+09:00
lmon registered with NM - instance number 1 (internal mem no 0)
* allocate domain 0, valid ? 0 
Attached to domain 0 (addr: 0x6846a788)
2017-03-02T15:39:46.103576+09:00
Reconfiguration started (old inc 0, new inc 2)
List of instances (total 1) :
 1
My inst 1 (I'm a new instance) 
 Global Resource Directory frozen
 Communication channels reestablished
 Master broadcasted resource hash value bitmaps
 Non-local Process blocks cleaned out
 Set master node info 
 Submitted all remote-enqueue requests
 Dwn-cvts replayed, VALBLKs dubious
 All grantable enqueues granted
2017-03-02T15:39:46.130698+09:00
 Post SMON to start 1st pass IR
 Submitted all GCS remote-cache requests
2017-03-02T15:39:46.133011+09:00
Reconfiguration complete (total time 0.0 secs) 
2017-03-02T15:39:46.134778+09:00
Starting background process TMON
2017-03-02T15:39:46.227138+09:00
TMON started with pid=46, OS id=1652 
ORACLE_BASE from environment = /u01/app/oracle
2017-03-02T15:39:46.286398+09:00
NOTE: ASMB0 registering with ASM instance as Flex client 0xffffffffffffffff (reg:2784201951) (new connection)
2017-03-02T15:39:46.305379+09:00
Errors in file /u01/app/oracle/diag/rdbms/c201/c201_1/trace/c201_1_rbal_1609.trc:
ORA-15183: ASMLIB initialization error [driver/agent not installed]
2017-03-02T15:39:46.305576+09:00
WARNING: FAILED to load library: /opt/oracle/extapi/64/asm/orcl/1/libasm.so 
2017-03-02T15:39:46.524137+09:00
NOTE: ASMB0 (1614) connected to ASM instance +ASM1, osid: 1654 (Flex mode; client id 0x1000a)
NOTE: initiating MARK startup 
Starting background process MARK
2017-03-02T15:39:46.570306+09:00
MARK started with pid=48, OS id=1663 
2017-03-02T15:39:46.685073+09:00
ALTER DATABASE   MOUNT
2017-03-02T15:39:46.733073+09:00
NOTE: MARK has subscribed
2017-03-02T15:39:47.340155+09:00
Using default pga_aggregate_limit of 2048 MB
2017-03-02T15:39:48.955422+09:00
NOTE: ASMB mounting group 3 (DATA)
NOTE: ASM background process initiating disk discovery for grp 3 (reqid:0)
NOTE: Assigning number (3,2) to disk (/dev/asmdisk_data03)
NOTE: Assigning number (3,0) to disk (/dev/asmdisk_data01)
NOTE: Assigning number (3,1) to disk (/dev/asmdisk_data02)
SUCCESS: mounted group 3 (DATA)
NOTE: grp 3 disk 2: DATA_0002 path:/dev/asmdisk_data03
NOTE: grp 3 disk 0: DATA_0000 path:/dev/asmdisk_data01
NOTE: grp 3 disk 1: DATA_0001 path:/dev/asmdisk_data02
2017-03-02T15:39:49.160641+09:00
NOTE: dependency between database c201 and diskgroup resource ora.DATA.dg is established
2017-03-02T15:39:49.163643+09:00
NOTE: ASMB mounting group 4 (FRA)
NOTE: ASM background process initiating disk discovery for grp 4 (reqid:0)
NOTE: Assigning number (4,2) to disk (/dev/asmdisk_fra03)
NOTE: Assigning number (4,1) to disk (/dev/asmdisk_fra02)
NOTE: Assigning number (4,0) to disk (/dev/asmdisk_fra01)
SUCCESS: mounted group 4 (FRA)
NOTE: grp 4 disk 2: FRA_0002 path:/dev/asmdisk_fra03
NOTE: grp 4 disk 1: FRA_0001 path:/dev/asmdisk_fra02
NOTE: grp 4 disk 0: FRA_0000 path:/dev/asmdisk_fra01
2017-03-02T15:39:49.315873+09:00
NOTE: dependency between database c201 and diskgroup resource ora.FRA.dg is established
2017-03-02T15:39:53.319144+09:00
Network throttle feature is disabled as mount time

2017-03-02T15:39:53.405486+09:00
Successful mount of redo thread 1, with mount id 3497482882
2017-03-02T15:39:53.410512+09:00
Database mounted in Exclusive Mode
Lost write protection disabled
Using STANDBY_ARCHIVE_DEST parameter default value as USE_DB_RECOVERY_FILE_DEST
Completed: ALTER DATABASE   MOUNT
2017-03-02T15:39:53.847826+09:00
alter database archivelog
Completed: alter database archivelog
alter database open
Ping without log force is disabled:
  instance mounted in exclusive mode.
Endian type of dictionary set to little
2017-03-02T15:39:54.066011+09:00
LGWR: STARTING ARCH PROCESSES
2017-03-02T15:39:54.103685+09:00
TT00: Gap Manager starting (PID:1806)
Starting background process ARC0
2017-03-02T15:39:54.146515+09:00
ARC0 started with pid=52, OS id=1810 
2017-03-02T15:39:54.154553+09:00
ARC0: Archival started
LGWR: STARTING ARCH PROCESSES COMPLETE
2017-03-02T15:39:54.155219+09:00
ARC0: Becoming a 'no FAL' ARCH
ARC0: Becoming the 'no SRL' ARCH
2017-03-02T15:39:54.188917+09:00
ARCH: STARTING ARCH PROCESSES
Starting background process ARC1
Starting background process ARC2
2017-03-02T15:39:54.231119+09:00
ARC1 started with pid=54, OS id=1818 
2017-03-02T15:39:54.277948+09:00
ARC2 started with pid=55, OS id=1822 
Starting background process ARC3
ARC1: Archival started
2017-03-02T15:39:54.318433+09:00
Thread 1 opened at log sequence 2
2017-03-02T15:39:54.318473+09:00
ARC2: Archival started
2017-03-02T15:39:54.318927+09:00
  Current log# 2 seq# 2 mem# 0: +DATA/C201/ONLINELOG/group_2.263.937582395
2017-03-02T15:39:54.318946+09:00
ARC3 started with pid=56, OS id=1826 
  Current log# 2 seq# 2 mem# 1: +FRA/C201/ONLINELOG/group_2.258.937582407
Successful open of redo thread 1
2017-03-02T15:39:54.319515+09:00
MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
2017-03-02T15:39:54.328962+09:00
ARC3: Archival started
ARCH: STARTING ARCH PROCESSES COMPLETE
2017-03-02T15:39:54.922886+09:00
[1754] Successfully onlined Undo Tablespace 2.
Undo initialization finished serial:0 start:9626706 end:9626992 diff:286 ms (0.3 seconds)
Verifying minimum file header compatibility for tablespace encryption for pdb 1..
Verifying file header compatibility for tablespace encryption completed for pdb 1
Database Characterset is AL32UTF8
No Resource Manager plan active
replication_dependency_tracking turned off (no async multimaster replication found)
2017-03-02T15:39:56.173059+09:00
Starting background process AQPC
2017-03-02T15:39:56.214070+09:00
AQPC started with pid=60, OS id=1870 
PDB$SEED(2):Autotune of undo retention is turned on. 
2017-03-02T15:39:57.348494+09:00
PDB$SEED(2):Endian type of dictionary set to little
PDB$SEED(2):Undo initialization finished serial:0 start:9629729 end:9629729 diff:0 ms (0.0 seconds)
PDB$SEED(2):Database Characterset for PDB$SEED is AL32UTF8
2017-03-02T15:39:58.937912+09:00
PDB$SEED(2):Opatch validation is skipped for PDB PDB$SEED (con_id=0)
PDB$SEED(2):Opening pdb with no Resource Manager plan active
2017-03-02T15:40:00.575371+09:00
Starting background process CJQ0
Completed: alter database open
2017-03-02T15:40:00.620715+09:00
CJQ0 started with pid=62, OS id=1980 
2017-03-02T15:40:01.772993+09:00
The spfile location is updated.
2017-03-02T15:40:04.060024+09:00
Shared IO Pool defaulting to 48MB. Trying to get it from Buffer Cache for process 1622.
===========================================================
Dumping current patch information
===========================================================
No patches have been applied
===========================================================
2017-03-02T15:40:07.770353+09:00
db_recovery_file_dest_size of 16062 MB is 3.88% used. This is a
user-specified limit on the amount of space that will be used by this
database for recovery-related files, and does not reflect the amount of
space available in the underlying filesystem or ASM diskgroup.