Skip to content

Instantly share code, notes, and snippets.

@drewwells drewwells/trace.log
Created Mar 18, 2016

Embed
What would you like to do?
[main] [ 2016-03-18 13:40:47.170 UTC ] [Host.<init>:1090] Begin tracing..
[main] [ 2016-03-18 13:40:47.224 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:47.224 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:47.225 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:47.242 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:47.242 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:47.243 UTC ] [SilentHost.initialize:187] SilentHost-> initialize: m_tempLogFile = /u01/app/oracle/cfgtoollogs/dbca/silent.log_2016-03-18_01-40-47-PM
[main] [ 2016-03-18 13:40:47.248 UTC ] [Host.checkIfBigClusterAndHubNode:1710] Not a cluster environment: exiting BigCluster Check
[main] [ 2016-03-18 13:40:47.248 UTC ] [InventoryUtil.getOracleBase:149] Getting ORACLE_BASE from inventory.
[main] [ 2016-03-18 13:40:47.249 UTC ] [InventoryUtil.getOUIInvSession:349] setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-03-18 13:40:47.249 UTC ] [InventoryUtil.getOracleBase:164] Inventory Home Size = 1
[main] [ 2016-03-18 13:40:47.249 UTC ] [InventoryUtil.getOracleBase:189] ORACLE_BASE returned from inventory:= /u01/app/oracle
[main] [ 2016-03-18 13:40:47.250 UTC ] [Host.displayOracleBaseWarning:1750] oracleBaseFromEnv null
[main] [ 2016-03-18 13:40:47.250 UTC ] [Host.displayOracleBaseWarning:1756] oracle_base from OUI /u01/app/oracle oracle_base from envnull
[main] [ 2016-03-18 13:40:47.251 UTC ] [InventoryUtil.getOUIInvSession:349] setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-03-18 13:40:47.251 UTC ] [InventoryUtil.isCRSHome:386] Homeinfo /u01/app/oracle/product/12.1.0/xe,1
[main] [ 2016-03-18 13:40:47.341 UTC ] [Host.validateGridHome:3878] Validation false
[main] [ 2016-03-18 13:40:47.341 UTC ] [Host.startOperation:2395] Source db null
[main] [ 2016-03-18 13:40:47.342 UTC ] [Host.startOperation:2396] GDB Name xe.oracle.docker
[main] [ 2016-03-18 13:40:47.352 UTC ] [Host.startOperation:2397] MgmtDB sid -MGMTDB
[main] [ 2016-03-18 13:40:47.352 UTC ] [Host.startOperation:2398] MgmtDB name _mgmtdb
[main] [ 2016-03-18 13:40:47.352 UTC ] [HADatabaseUtils.isGridMgmtDB:2842] Mgmt DBName from srvm _mgmtdb
[main] [ 2016-03-18 13:40:47.356 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: null
[main] [ 2016-03-18 13:40:47.357 UTC ] [InventoryUtil.getOUIInvSession:349] setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-03-18 13:40:47.357 UTC ] [OracleHome.getVersion:1010] Homeinfo /u01/app/oracle/product/12.1.0/xe,1
[main] [ 2016-03-18 13:40:47.419 UTC ] [OracleHome.getVersion:1038] OracleHome.server.getVersion Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.419 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.419 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.420 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.420 UTC ] [CommonUtils.createPasswordFile:1243] calling new orapwd for 11.1 or higher
[main] [ 2016-03-18 13:40:47.420 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.420 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.420 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.420 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.420 UTC ] [CommonUtils.getPasswordFileCreateCmd:1182] for new orapwd for 11.1 or higher
[main] [ 2016-03-18 13:40:47.422 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.422 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.422 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.423 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.423 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] /u01/app/oracle/product/12.1.0/xe/bin/orapwd
[main] [ 2016-03-18 13:40:47.423 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] file=/u01/app/oracle/product/12.1.0/xe/dbs/orapwDBUA4047353
[main] [ 2016-03-18 13:40:47.423 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] force=y
[main] [ 2016-03-18 13:40:47.423 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] format=12
[main] [ 2016-03-18 13:40:47.423 UTC ] [OsUtilsBase.execProg:2123] beginning execProg with input array.
[main] [ 2016-03-18 13:40:47.453 UTC ] [OsUtilsBase.execProg:2160] finished execProg with input array. Status:0
[main] [ 2016-03-18 13:40:47.453 UTC ] [OracleHome.initOptionsStopOnError:1356] Initializing Database Options with for dummy sid=DBUA4047353 using initfile=/u01/app/oracle/product/12.1.0/xe/dbs/initDBUA4047353.ora using pwdfile=/u01/app/oracle/product/12.1.0/xe/dbs/orapwDBUA4047353
[main] [ 2016-03-18 13:40:47.456 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.457 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.457 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.457 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.457 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.457 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.458 UTC ] [SQLPlusEngine.getCmmdParams:225] m_home 12.1.0.2.0
[main] [ 2016-03-18 13:40:47.458 UTC ] [SQLPlusEngine.getCmmdParams:226] version > 112 true
[main] [ 2016-03-18 13:40:47.458 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:47.458 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:47.458 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:47.464 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:47.464 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:47.464 UTC ] [SQLEngine.getEnvParams:602] Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-03-18 13:40:47.464 UTC ] [SQLEngine.getEnvParams:612] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-03-18 13:40:47.466 UTC ] [SQLEngine.initialize:358] Execing SQLPLUS/SVRMGR process...
[main] [ 2016-03-18 13:40:47.468 UTC ] [SQLEngine.initialize:395] m_bReaderStarted: false
[main] [ 2016-03-18 13:40:47.468 UTC ] [SQLEngine.initialize:399] Starting Reader Thread...
[main] [ 2016-03-18 13:40:49.075 UTC ] [OracleHome.initOptionsStopOnError:1370] executing: startup nomount pfile='/u01/app/oracle/product/12.1.0/xe/dbs/initDBUA4047353.ora'
[main] [ 2016-03-18 13:40:52.108 UTC ] [OracleHome.initOptionsStopOnError:1372] DB Options instance startup successful
[main] [ 2016-03-18 13:40:52.108 UTC ] [OracleHome.initOptionsStopOnError:1383] executing: select parameter from v$option where value='TRUE'
[main] [ 2016-03-18 13:40:52.116 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Objects is ON
[main] [ 2016-03-18 13:40:52.117 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Connection multiplexing is ON
[main] [ 2016-03-18 13:40:52.117 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Connection pooling is ON
[main] [ 2016-03-18 13:40:52.117 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Database queuing is ON
[main] [ 2016-03-18 13:40:52.117 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Incremental backup and recovery is ON
[main] [ 2016-03-18 13:40:52.117 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Instead-of triggers is ON
[main] [ 2016-03-18 13:40:52.117 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Parallel load is ON
[main] [ 2016-03-18 13:40:52.117 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Proxy authentication/authorization is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Plan Stability is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Coalesce Index is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Transparent Application Failover is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Sample Scan is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Java is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option OLAP Window Functions is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option Flashback Data Archive is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option DICOM is ON
[main] [ 2016-03-18 13:40:52.118 UTC ] [OracleHome.initOptionsStopOnError:1389] Database Option XStream is ON
[main] [ 2016-03-18 13:40:52.119 UTC ] [OracleHome.initOptionsStopOnError:1393] executing: select version from v$timezone_file
[main] [ 2016-03-18 13:40:52.125 UTC ] [OracleHome.initOptionsStopOnError:1399] Timezone file version is 18
[main] [ 2016-03-18 13:40:52.125 UTC ] [OracleHome.initOptionsStopOnError:1419] shutting down dummy instance
[main] [ 2016-03-18 13:40:56.188 UTC ] [SQLEngine.done:2278] Done called
[main] [ 2016-03-18 13:40:56.188 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/product/12.1.0/xe/dbs/initDBUA4047353.ora
[main] [ 2016-03-18 13:40:56.189 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/product/12.1.0/xe/dbs/orapwDBUA4047353
[main] [ 2016-03-18 13:40:56.189 UTC ] [OracleHome.initOptionsStopOnError:1443] Diag cleanup /u01/app/oracle/product/12.1.0/xe/log/diag/rdbms/dbua4047353
[main] [ 2016-03-18 13:40:56.199 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.199 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.199 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.207 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.207 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.207 UTC ] [OracleHome.initOptionsStopOnError:1449] Admin cleanup /u01/app/oracle/admin/DBUA4047353
[main] [ 2016-03-18 13:40:56.207 UTC ] [OracleHome.initOptionsStopOnError:1458] Database Options queried: 17
[main] [ 2016-03-18 13:40:56.208 UTC ] [Host.checkOPS:2082] Inside checkOPS
[main] [ 2016-03-18 13:40:56.208 UTC ] [Host.checkOPS:2094] Trying to check cluster existence
[main] [ 2016-03-18 13:40:56.208 UTC ] [Host.checkOPS:2099] cluster existence:false
[main] [ 2016-03-18 13:40:56.208 UTC ] [Host.checkOPS:2108] Cluster Installed false
[main] [ 2016-03-18 13:40:56.208 UTC ] [Host.checkOPS:2124] Cluster installed=false
[main] [ 2016-03-18 13:40:56.357 UTC ] [TemplateManager.validateTemplate:636] Validation of template is succussful
[main] [ 2016-03-18 13:40:56.360 UTC ] [HADatabaseUtils.isGridMgmtDB:2842] Mgmt DBName from srvm _mgmtdb
[main] [ 2016-03-18 13:40:56.360 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.360 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.361 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.361 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.361 UTC ] [StepContext.setListenersToUpdate:946] needLocalListener = false
[main] [ 2016-03-18 13:40:56.366 UTC ] [OracleHome.hasEELicense:247] Running script to determine licensing: /u01/app/oracle/product/12.1.0/xe/bin/bndlchk
[main] [ 2016-03-18 13:40:56.380 UTC ] [OracleHome.hasEELicense:258] home=/u01/app/oracle/product/12.1.0/xe has EE licensing
[main] [ 2016-03-18 13:40:56.406 UTC ] [OracleHome.hasEELicense:247] Running script to determine licensing: /u01/app/oracle/product/12.1.0/xe/bin/bndlchk
[main] [ 2016-03-18 13:40:56.411 UTC ] [OracleHome.hasEELicense:258] home=/u01/app/oracle/product/12.1.0/xe has EE licensing
[main] [ 2016-03-18 13:40:56.412 UTC ] [InitParamHandler.endElement:547] CustomSGA flag: false
[main] [ 2016-03-18 13:40:56.413 UTC ] [InitParamHandler.endElement:548] Database Type: MULTIPURPOSE
[main] [ 2016-03-18 13:40:56.413 UTC ] [InitParamHandler.endElement:549] Mem Percentage: 40
[main] [ 2016-03-18 13:40:56.422 UTC ] [OsUtilsBase.getTotalPhysicalMemory:411] Total Physical Memory in MB: 3952
[main] [ 2016-03-18 13:40:56.422 UTC ] [InitParamHandler.endElement:582] Total memory MB: 512
[main] [ 2016-03-18 13:40:56.422 UTC ] [InitParamHandler.endElement:583] Physical memory MB: 3952
[main] [ 2016-03-18 13:40:56.423 UTC ] [OsUtilsBase.is64Bit:395] architecture is 64 bit: true
[main] [ 2016-03-18 13:40:56.423 UTC ] [MemoryCalculator.<clinit>:103] setting memory minimums for 64 bit
[main] [ 2016-03-18 13:40:56.427 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /
[main] [ 2016-03-18 13:40:56.427 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /
[main] [ 2016-03-18 13:40:56.427 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /proc
[main] [ 2016-03-18 13:40:56.427 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /dev
[main] [ 2016-03-18 13:40:56.427 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /dev/pts
[main] [ 2016-03-18 13:40:56.427 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /dev/mqueue
[main] [ 2016-03-18 13:40:56.427 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/cpuset
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/cpu
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/cpuacct
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/memory
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/devices
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/freezer
[main] [ 2016-03-18 13:40:56.428 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/blkio
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/perf_event
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/hugetlb
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /sys/fs/cgroup/systemd
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /u01/app/oracle
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /etc/resolv.conf
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /etc/hostname
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /etc/hosts
[main] [ 2016-03-18 13:40:56.429 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381] Volume: /dev/shm
[main] [ 2016-03-18 13:40:56.430 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:388] /dev/shm Feasible: true
[main] [ 2016-03-18 13:40:56.430 UTC ] [InitParamHandler.endElement:600] /dev/shm size in MB: 1024
[main] [ 2016-03-18 13:40:56.430 UTC ] [InitParamHandler.endElement:608] bUseUnified true
[main] [ 2016-03-18 13:40:56.430 UTC ] [DatafileCloneHandler.startElement:209] atts name cdb
[main] [ 2016-03-18 13:40:56.430 UTC ] [DatafileCloneHandler.startElement:211] atts lenght true
[main] [ 2016-03-18 13:40:56.436 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:331] This thread doesnot exist in existing thread to group map
[main] [ 2016-03-18 13:40:56.436 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:269] Current threadID=1
[main] [ 2016-03-18 13:40:56.436 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:270] newThread threadID=1
[main] [ 2016-03-18 13:40:56.437 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:303] redoGroups.length 1
[main] [ 2016-03-18 13:40:56.437 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:305] new redoGroup id 2
[main] [ 2016-03-18 13:40:56.437 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:310] redoGroups 1
[main] [ 2016-03-18 13:40:56.437 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:321] arRedoGroups:[1, 2] adding to thread id 1
[main] [ 2016-03-18 13:40:56.438 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:269] Current threadID=1
[main] [ 2016-03-18 13:40:56.438 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:270] newThread threadID=1
[main] [ 2016-03-18 13:40:56.438 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:303] redoGroups.length 2
[main] [ 2016-03-18 13:40:56.438 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:305] new redoGroup id 3
[main] [ 2016-03-18 13:40:56.438 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:310] redoGroups 1
[main] [ 2016-03-18 13:40:56.438 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:310] redoGroups 2
[main] [ 2016-03-18 13:40:56.439 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:321] arRedoGroups:[1, 2, 3] adding to thread id 1
[main] [ 2016-03-18 13:40:56.439 UTC ] [TemplateManager.getParsedAttributes:1710] is Template a CDB false
[main] [ 2016-03-18 13:40:56.440 UTC ] [Verifier.getDatabaseSize:2543] Clone Datafile: sysaux01.dbf size: 560.0
[main] [ 2016-03-18 13:40:56.440 UTC ] [Verifier.getDatabaseSize:2543] Clone Datafile: system01.dbf size: 780.0
[main] [ 2016-03-18 13:40:56.441 UTC ] [Verifier.getDatabaseSize:2543] Clone Datafile: undotbs01.dbf size: 25.0
[main] [ 2016-03-18 13:40:56.441 UTC ] [Verifier.getDatabaseSize:2543] Clone Datafile: users01.dbf size: 5.0
[main] [ 2016-03-18 13:40:56.441 UTC ] [Verifier.getControlfFileSizes:2868] No. of Control files:=2
[main] [ 2016-03-18 13:40:56.441 UTC ] [Verifier.getDatabaseSize:2595] dbSize: 1402880 redoLogSize: 153600 ctrlFileSize: 40
[main] [ 2016-03-18 13:40:56.441 UTC ] [TemplateManager.getParsedAttributes:1772] Setting DB_RECOVERY_FILE_SIZE to default size: 4560
[main] [ 2016-03-18 13:40:56.441 UTC ] [TemplateManager.getParsedAttributes:1778] Dynamic FRA true
[main] [ 2016-03-18 13:40:56.442 UTC ] [TemplateManager.parseCloneTemplate:1353] See for any transportable datafiles in TemplateManager.....
[main] [ 2016-03-18 13:40:56.443 UTC ] [Host.setupOIDCommandlineParameters:9418] setupOIDCommandlineParameters:
[main] [ 2016-03-18 13:40:56.443 UTC ] [Host.setupOIDCommandlineParameters:9419] m_regWithdirService: false
[main] [ 2016-03-18 13:40:56.443 UTC ] [Host.setupOIDCommandlineParameters:9420] m_unregWithdirService: false
[main] [ 2016-03-18 13:40:56.443 UTC ] [Host.setupOIDCommandlineParameters:9421] m_updateDirService: false
[main] [ 2016-03-18 13:40:56.444 UTC ] [Host.startOperation:2789] setDataBaseType :0
[main] [ 2016-03-18 13:40:56.444 UTC ] [Host.startOperation:2872] is Template CDB false
[main] [ 2016-03-18 13:40:56.444 UTC ] [TemplateManager.isInstallTemplate:2348] Selected Template by user:=General Purpose
[main] [ 2016-03-18 13:40:56.444 UTC ] [TemplateManager.isInstallTemplate:2355] The Message Id to be searched:=GENERAL_PURPOSE
[main] [ 2016-03-18 13:40:56.444 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.444 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.445 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.445 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.445 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.445 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.445 UTC ] [HADatabaseUtils.isGridMgmtDB:2842] Mgmt DBName from srvm _mgmtdb
[main] [ 2016-03-18 13:40:56.445 UTC ] [Verifier.processRawConfigFile:2991] StorageType == 0
[main] [ 2016-03-18 13:40:56.459 UTC ] [SilentHost.performOperation:458] processRawConfigFile=true
[main] [ 2016-03-18 13:40:56.461 UTC ] [Host.initializeValidationContext:1255] sid, uniquenname, domain set in validationcontextxe xe, xe, oracle.docker
[main] [ 2016-03-18 13:40:56.462 UTC ] [Verifier.getStorageDataForValidation:4935] Entering getStorageDataForValidation
[main] [ 2016-03-18 13:40:56.469 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.470 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.473 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.474 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.474 UTC ] [ASMUtils.initialize:247] ASMHome: null
[main] [ 2016-03-18 13:40:56.475 UTC ] [KfodUtil.kfodOutput:361] null/rdbms/bin/kfod
[main] [ 2016-03-18 13:40:56.475 UTC ] [KfodUtil.kfodOutput:361] nohdr=TRUE
[main] [ 2016-03-18 13:40:56.476 UTC ] [KfodUtil.kfodOutput:361] OP=GROUPS
[main] [ 2016-03-18 13:40:56.476 UTC ] [KfodUtil.kfodOutput:361] status=true
[main] [ 2016-03-18 13:40:56.476 UTC ] [KfodUtil.kfodOutput:361] asmcompatibility=true dbcompatibility=true
[main] [ 2016-03-18 13:40:56.481 UTC ] [KfodUtil.kfodOutput:368] Cannot run program "null/rdbms/bin/kfod": java.io.IOException: error=2, No such file or directory
[main] [ 2016-03-18 13:40:56.481 UTC ] [KfodUtil.kfodOutput:369] Cannot run program "null/rdbms/bin/kfod": java.io.IOException: error=2, No such file or directory
java.lang.ProcessBuilder.processException(ProcessBuilder.java:478)
java.lang.ProcessBuilder.start(ProcessBuilder.java:457)
java.lang.Runtime.exec(Runtime.java:593)
java.lang.Runtime.exec(Runtime.java:466)
oracle.sysman.assistants.util.OsUtilsBase.execProg(OsUtilsBase.java:2300)
oracle.sysman.assistants.util.OsUtilsBase.execProgNReturnOutput(OsUtilsBase.java:2448)
oracle.sysman.assistants.util.KfodUtil.kfodOutput(KfodUtil.java:364)
oracle.sysman.assistants.util.KfodUtil.kfodOutput(KfodUtil.java:254)
oracle.sysman.assistants.util.KfodUtil.getDiskgroupsWithCompatibilityValues(KfodUtil.java:569)
oracle.sysman.assistants.util.asm.ASMUtils.loadDiskGroups(ASMUtils.java:1040)
oracle.sysman.assistants.util.asm.ASMUtils.initialize(ASMUtils.java:277)
oracle.sysman.assistants.util.asm.ASMUtils.getASMUtils(ASMUtils.java:192)
oracle.sysman.assistants.dbca.backend.Verifier.initASMDGSpace(Verifier.java:3572)
oracle.sysman.assistants.dbca.backend.Verifier.initialiseStorageValidationData(Verifier.java:3560)
oracle.sysman.assistants.dbca.backend.Verifier.getStorageDataForValidation(Verifier.java:4936)
oracle.sysman.assistants.dbca.backend.Host.initializeValidationContext(Host.java:1402)
oracle.sysman.assistants.dbca.backend.SilentHost.performOperation(SilentHost.java:460)
oracle.sysman.assistants.dbca.backend.Host.startOperation(Host.java:3804)
oracle.sysman.assistants.dbca.Dbca.execute(Dbca.java:153)
oracle.sysman.assistants.dbca.Dbca.main(Dbca.java:226)
java.lang.ProcessBuilder.processException(ProcessBuilder.java:478)
java.lang.ProcessBuilder.start(ProcessBuilder.java:457)
java.lang.Runtime.exec(Runtime.java:593)
java.lang.Runtime.exec(Runtime.java:466)
oracle.sysman.assistants.util.OsUtilsBase.execProg(OsUtilsBase.java:2300)
oracle.sysman.assistants.util.OsUtilsBase.execProgNReturnOutput(OsUtilsBase.java:2448)
oracle.sysman.assistants.util.KfodUtil.kfodOutput(KfodUtil.java:364)
oracle.sysman.assistants.util.KfodUtil.kfodOutput(KfodUtil.java:254)
oracle.sysman.assistants.util.KfodUtil.getDiskgroupsWithCompatibilityValues(KfodUtil.java:569)
oracle.sysman.assistants.util.asm.ASMUtils.loadDiskGroups(ASMUtils.java:1040)
oracle.sysman.assistants.util.asm.ASMUtils.initialize(ASMUtils.java:277)
oracle.sysman.assistants.util.asm.ASMUtils.getASMUtils(ASMUtils.java:192)
oracle.sysman.assistants.dbca.backend.Verifier.initASMDGSpace(Verifier.java:3572)
oracle.sysman.assistants.dbca.backend.Verifier.initialiseStorageValidationData(Verifier.java:3560)
oracle.sysman.assistants.dbca.backend.Verifier.getStorageDataForValidation(Verifier.java:4936)
oracle.sysman.assistants.dbca.backend.Host.initializeValidationContext(Host.java:1402)
oracle.sysman.assistants.dbca.backend.SilentHost.performOperation(SilentHost.java:460)
oracle.sysman.assistants.dbca.backend.Host.startOperation(Host.java:3804)
oracle.sysman.assistants.dbca.Dbca.execute(Dbca.java:153)
oracle.sysman.assistants.dbca.Dbca.main(Dbca.java:226)
[main] [ 2016-03-18 13:40:56.482 UTC ] [KfodUtil.kfodOutput:372] Kfod result null
[main] [ 2016-03-18 13:40:56.482 UTC ] [Verifier.initASMDGSpace:3578] omfLocation=null
[main] [ 2016-03-18 13:40:56.482 UTC ] [Verifier.initASMDGSpace:3591] ControlFile Name {ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-03-18 13:40:56.482 UTC ] [Verifier.initASMDGSpace:3591] ControlFile Name {ORACLE_BASE}/fast_recovery_area/{DB_UNIQUE_NAME}/
[main] [ 2016-03-18 13:40:56.482 UTC ] [Verifier.initASMDGSpace:3612] datafile name {ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-03-18 13:40:56.486 UTC ] [Verifier.initASMDGSpace:3612] datafile name {ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-03-18 13:40:56.487 UTC ] [Verifier.initASMDGSpace:3612] datafile name {ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-03-18 13:40:56.487 UTC ] [Verifier.initASMDGSpace:3612] datafile name {ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-03-18 13:40:56.487 UTC ] [Verifier.initASMDGSpace:3622] Adding an extra 80 space for undo tbs
[main] [ 2016-03-18 13:40:56.487 UTC ] [Verifier.initASMDGSpace:3781] Skipping this redolog as its not on ASM
[main] [ 2016-03-18 13:40:56.487 UTC ] [Verifier.initASMDGSpace:3781] Skipping this redolog as its not on ASM
[main] [ 2016-03-18 13:40:56.487 UTC ] [Verifier.initASMDGSpace:3781] Skipping this redolog as its not on ASM
[main] [ 2016-03-18 13:40:56.488 UTC ] [HADatabaseUtils.isGridMgmtDB:2842] Mgmt DBName from srvm _mgmtdb
[main] [ 2016-03-18 13:40:56.488 UTC ] [Verifier.initASMDGSpace:3823] Skipping passwowd file compatible check for MGMTDB/sidb
[main] [ 2016-03-18 13:40:56.488 UTC ] [Verifier.initASMDGSpace:3878] dbRecAreaSize0 vertrue{ORACLE_BASE}/fast_recovery_area
[main] [ 2016-03-18 13:40:56.488 UTC ] [Verifier.initASMDGSpace:3884] dbRecAreaSizever4560
[main] [ 2016-03-18 13:40:56.488 UTC ] [Verifier.initASMDGSpace:3938] Exiting getStorageDataForValidation
[main] [ 2016-03-18 13:40:56.488 UTC ] [Verifier.initFSSpace:4421] omfLocation=null
[main] [ 2016-03-18 13:40:56.488 UTC ] [Verifier.initFSSpace:4442] Entering newCalculateCloneDatafilePathsAndSizes
[main] [ 2016-03-18 13:40:56.489 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.489 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.489 UTC ] [Verifier.initFSSpace:4483] clone /u01/app/oracle/oradata/xe/sysaux01.dbf 573440
[main] [ 2016-03-18 13:40:56.489 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.489 UTC ] [Verifier.updateSizeMap:4918] Adding required size for /u01/app/oracle/oradata/ to 560
[main] [ 2016-03-18 13:40:56.489 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.490 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.490 UTC ] [Verifier.initFSSpace:4483] clone /u01/app/oracle/oradata/xe/system01.dbf 798720
[main] [ 2016-03-18 13:40:56.490 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.490 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1340
[main] [ 2016-03-18 13:40:56.494 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.494 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.494 UTC ] [Verifier.initFSSpace:4483] clone /u01/app/oracle/oradata/xe/undotbs01.dbf 25600
[main] [ 2016-03-18 13:40:56.494 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.494 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1365
[main] [ 2016-03-18 13:40:56.494 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.495 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.495 UTC ] [Verifier.initFSSpace:4483] clone /u01/app/oracle/oradata/xe/users01.dbf 5120
[main] [ 2016-03-18 13:40:56.495 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.495 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1370
[main] [ 2016-03-18 13:40:56.495 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.495 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.495 UTC ] [Verifier.initFSSpace:4483] clone /u01/app/oracle/oradata/xe/temp01.dbf 61440
[main] [ 2016-03-18 13:40:56.496 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.496 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1430
[main] [ 2016-03-18 13:40:56.496 UTC ] [Verifier.initFSSpace:4488] Adding an extra 80 space for undo tbs
[main] [ 2016-03-18 13:40:56.496 UTC ] [Verifier.updateSizeMap:4923] Invalid filepath = {ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-03-18 13:40:56.496 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.496 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.496 UTC ] [Verifier.initFSSpace:4659] redo /u01/app/oracle/oradata/xe/ 51200
[main] [ 2016-03-18 13:40:56.497 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.497 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1480
[main] [ 2016-03-18 13:40:56.497 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.497 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.497 UTC ] [Verifier.initFSSpace:4659] redo /u01/app/oracle/oradata/xe/ 51200
[main] [ 2016-03-18 13:40:56.497 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.497 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1530
[main] [ 2016-03-18 13:40:56.498 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.498 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.498 UTC ] [Verifier.initFSSpace:4659] redo /u01/app/oracle/oradata/xe/ 51200
[main] [ 2016-03-18 13:40:56.498 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.499 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1580
[main] [ 2016-03-18 13:40:56.499 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.499 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.499 UTC ] [Verifier.initFSSpace:4691] contrl and buffer /u01/app/oracle/oradata/xe/ 120
[main] [ 2016-03-18 13:40:56.500 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.500 UTC ] [Verifier.updateSizeMap:4911] Updating required size for /u01/app/oracle/oradata/ to 1580
[main] [ 2016-03-18 13:40:56.500 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.500 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.501 UTC ] [Verifier.initFSSpace:4691] contrl and buffer /u01/app/oracle/fast_recovery_area/xe/ 120
[main] [ 2016-03-18 13:40:56.501 UTC ] [Verifier.updateSizeMap:4905] canonicalPath=/u01/app/oracle/
[main] [ 2016-03-18 13:40:56.501 UTC ] [Verifier.updateSizeMap:4918] Adding required size for /u01/app/oracle/ to 0
[main] [ 2016-03-18 13:40:56.501 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.502 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.502 UTC ] [Verifier.initFSSpace:4707] Default datafile path for CDB=/u01/app/oracle/oradata/xe
[main] [ 2016-03-18 13:40:56.511 UTC ] [TarUtil.loadMetaDataInfo:452] attributeList.size() 3
[main] [ 2016-03-18 13:40:56.511 UTC ] [Verifier.initFSSpace:4726] pdb datafile: /oradata/seeddata/pdbseed/system01.dbf has size: 250
[main] [ 2016-03-18 13:40:56.512 UTC ] [Verifier.initFSSpace:4726] pdb datafile: /oradata/seeddata/pdbseed/sysaux01.dbf has size: 490
[main] [ 2016-03-18 13:40:56.512 UTC ] [Verifier.initFSSpace:4726] pdb datafile: /oradata/seeddata/pdbseed/temp01.dbf has size: 20
[main] [ 2016-03-18 13:40:56.512 UTC ] [Verifier.initFSSpace:4736] Total size of all datafiles computed from the pdbseed xml is: 760
[main] [ 2016-03-18 13:40:56.512 UTC ] [Verifier.initFSSpace:4753] Updating the total PDB size required to: 760
[main] [ 2016-03-18 13:40:56.512 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.512 UTC ] [Verifier.initFSSpace:4828] canonicalPathForFra=/u01/app/oracle/
[main] [ 2016-03-18 13:40:56.513 UTC ] [Verifier.initFSSpace:4859] Adding File path: /u01/app/oracle/oradata/xe/sysaux01.dbf for file existence check
[main] [ 2016-03-18 13:40:56.513 UTC ] [Verifier.initFSSpace:4859] Adding File path: /u01/app/oracle/oradata/xe/system01.dbf for file existence check
[main] [ 2016-03-18 13:40:56.513 UTC ] [Verifier.initFSSpace:4859] Adding File path: /u01/app/oracle/oradata/xe/undotbs01.dbf for file existence check
[main] [ 2016-03-18 13:40:56.513 UTC ] [Verifier.initFSSpace:4859] Adding File path: /u01/app/oracle/oradata/xe/users01.dbf for file existence check
[main] [ 2016-03-18 13:40:56.513 UTC ] [Verifier.initFSSpace:4859] Adding File path: /u01/app/oracle/oradata/xe/temp01.dbf for file existence check
[main] [ 2016-03-18 13:40:56.513 UTC ] [Verifier.initFSSpace:4862] Exiting getStorageDataForValidation
[main] [ 2016-03-18 13:40:56.513 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.514 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.514 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.522 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.522 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.522 UTC ] [SilentHost.performOperation:461] after initialize validation context for silent mode
[main] [ 2016-03-18 13:40:56.599 UTC ] [ClusterVerification.getInstance:396] Method Entry
[main] [ 2016-03-18 13:40:56.605 UTC ] [ParamManager.<init>:668] m_paramInstantiated set to TRUE
[main] [ 2016-03-18 13:40:56.605 UTC ] [VerificationUtil.getLocalHost:1312] Hostname retrieved: 48748797223a, returned: 48748797223a
[main] [ 2016-03-18 13:40:56.608 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.608 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.608 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.608 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.608 UTC ] [VerificationUtil.isCRSConfigured:4209] crsConfigured=false
[main] [ 2016-03-18 13:40:56.609 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.609 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.609 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.609 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.609 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 11
[main] [ 2016-03-18 13:40:56.609 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.609 UTC ] [Version.isPre:642] version to be checked 12.1.0.2.0 major version to check against 11 minor version to check against 2
[main] [ 2016-03-18 13:40:56.610 UTC ] [Version.isPre:651] isPre: Returning FALSE for major version check
[main] [ 2016-03-18 13:40:56.610 UTC ] [UnixSystem.isHAConfigured:2788] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.610 UTC ] [VerificationUtil.isHAConfigured:4181] haConfigured=false
[main] [ 2016-03-18 13:40:56.632 UTC ] [Verifier.validateMemory:1219] Verification Exception: The default location for framework home is not available. It must be specified
[main] [ 2016-03-18 13:40:56.632 UTC ] [ClusterVerification.getInstance:396] Method Entry
[main] [ 2016-03-18 13:40:56.632 UTC ] [VerificationUtil.getLocalHost:1312] Hostname retrieved: 48748797223a, returned: 48748797223a
[main] [ 2016-03-18 13:40:56.632 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.633 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.633 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.633 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.633 UTC ] [VerificationUtil.isCRSConfigured:4209] crsConfigured=false
[main] [ 2016-03-18 13:40:56.633 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.633 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.633 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-03-18 13:40:56.633 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.634 UTC ] [Version.isPre:610] version to be checked 12.1.0.2.0 major version to check against 11
[main] [ 2016-03-18 13:40:56.634 UTC ] [Version.isPre:621] isPre.java: Returning FALSE
[main] [ 2016-03-18 13:40:56.634 UTC ] [Version.isPre:642] version to be checked 12.1.0.2.0 major version to check against 11 minor version to check against 2
[main] [ 2016-03-18 13:40:56.634 UTC ] [Version.isPre:651] isPre: Returning FALSE for major version check
[main] [ 2016-03-18 13:40:56.634 UTC ] [UnixSystem.isHAConfigured:2788] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.634 UTC ] [VerificationUtil.isHAConfigured:4181] haConfigured=false
[main] [ 2016-03-18 13:40:56.634 UTC ] [Verifier.validateSGA:1248] Verification Exception: The default location for framework home is not available. It must be specified
[main] [ 2016-03-18 13:40:56.635 UTC ] [Verifier.validateSharedPoolSize:1273] checkUnified true
[main] [ 2016-03-18 13:40:56.635 UTC ] [Verifier.validateSharedPoolSize:1274] checkUnified 536870912
[main] [ 2016-03-18 13:40:56.635 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.635 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.635 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.635 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.635 UTC ] [Host.validate:5345] calling validatebackend
[main] [ 2016-03-18 13:40:56.636 UTC ] [Verifier.validateBackend:1026] starting validateBackend
[main] [ 2016-03-18 13:40:56.639 UTC ] [DBCAValidator.loadCreateDBValidationGroups:400] run si db validations
[main] [ 2016-03-18 13:40:56.640 UTC ] [Host.noEntryinOratab:7194] Check made for oratab arg passed............
[main] [ 2016-03-18 13:40:56.640 UTC ] [DBCAValidator.loadCreateDBValidationGroups:403] skip dbid validatoin for SI
[main] [ 2016-03-18 13:40:56.640 UTC ] [DBCAValidator.loadCreateDBValidationGroups:415] isCluster false
[main] [ 2016-03-18 13:40:56.640 UTC ] [DBCAValidator.loadCreateDBValidationGroups:416] isHARegnReq false
[main] [ 2016-03-18 13:40:56.645 UTC ] [DBIdentificationHelper.getDbNameError:224] gdbname isxe.oracle.docker
[main] [ 2016-03-18 13:40:56.656 UTC ] [Verifier.validateBackend:1033] Validation Result:
Unique database identifier check passed.
[main] [ 2016-03-18 13:40:56.657 UTC ] [Verifier.validateBackend:1058] write result to trace true
[main] [ 2016-03-18 13:40:56.658 UTC ] [StorageSpaceCheck.validate:97] Getting volumes from space maps 3, 1
[main] [ 2016-03-18 13:40:56.658 UTC ] [StorageSpaceCheck.getConsolidatedMap:312] locationString processed: /u01/app/oracle original String: /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.658 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.658 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.659 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.659 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.659 UTC ] [HAUtils.isPathOnACFS:3995] isPathOnACFS: false
[main] [ 2016-03-18 13:40:56.705 UTC ] [OsUtilsBase.getMountPoint:2822] mount point to be verified /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.706 UTC ] [StorageSpaceCheck.getConsolidatedMap:341] Mount point of /u01/app/oracle/ is /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.706 UTC ] [StorageSpaceCheck.getConsolidatedMap:312] locationString processed: /u01/app/oracle/oradata original String: /u01/app/oracle/oradata/
[main] [ 2016-03-18 13:40:56.706 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.706 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.706 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.706 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.707 UTC ] [HAUtils.isPathOnACFS:3995] isPathOnACFS: false
[main] [ 2016-03-18 13:40:56.711 UTC ] [OsUtilsBase.getMountPoint:2822] mount point to be verified /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.711 UTC ] [StorageSpaceCheck.getConsolidatedMap:341] Mount point of /u01/app/oracle/oradata/ is /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.711 UTC ] [StorageSpaceCheck.getConsolidatedMap:312] locationString processed: /u01/app/oracle/oradata/xe original String: /u01/app/oracle/oradata/xe
[main] [ 2016-03-18 13:40:56.711 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.711 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.711 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.711 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.712 UTC ] [HAUtils.isPathOnACFS:3995] isPathOnACFS: false
[main] [ 2016-03-18 13:40:56.716 UTC ] [OsUtilsBase.getMountPoint:2822] mount point to be verified /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.716 UTC ] [StorageSpaceCheck.getConsolidatedMap:341] Mount point of /u01/app/oracle/oradata/xe is /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.716 UTC ] [StorageSpaceCheck.getConsolidatedMap:312] locationString processed: /u01/app/oracle/fast_recovery_area original String: /u01/app/oracle/fast_recovery_area
[main] [ 2016-03-18 13:40:56.716 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.717 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.717 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.717 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.717 UTC ] [HAUtils.isPathOnACFS:3995] isPathOnACFS: false
[main] [ 2016-03-18 13:40:56.721 UTC ] [OsUtilsBase.getMountPoint:2822] mount point to be verified /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.721 UTC ] [StorageSpaceCheck.getConsolidatedMap:341] Mount point of /u01/app/oracle/fast_recovery_area is /u01/app/oracle/
[main] [ 2016-03-18 13:40:56.721 UTC ] [StorageSpaceCheck.validate:136] Checking storage space in /u01/app/oracle
[main] [ 2016-03-18 13:40:56.721 UTC ] [HAUtils.getCurrentOracleHome:593] Oracle home from system property: /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.721 UTC ] [HAUtils.getConfiguredGridHome:1343] ----- Getting CRS HOME ----
[main] [ 2016-03-18 13:40:56.721 UTC ] [UnixSystem.getCRSHome:2878] olrFileName = /etc/oracle/olr.loc
[main] [ 2016-03-18 13:40:56.721 UTC ] [HAUtils.getHASHome:1500] Failed to get HAS home.
PRCI-1144 : Failed to retrieve Oracle Grid Infrastructure home path
PRKC-1144 : File "/etc/oracle/olr.loc" not found.
[main] [ 2016-03-18 13:40:56.722 UTC ] [HAUtils.isPathOnACFS:3995] isPathOnACFS: false
[main] [ 2016-03-18 13:40:56.722 UTC ] [StorageSpaceCheck.validate:165] /u01/app/oracle isACFSMountPoint: false
[main] [ 2016-03-18 13:40:56.722 UTC ] [StorageSpaceCheck.parseSpaceResult:385] actual: 25266 location: /u01/app/oracle/ mandatorySpace: 2340 optionalSpace: 4560
[main] [ 2016-03-18 13:40:56.723 UTC ] [Verifier.validateBackend:1033] Validation Result:
/u01/app/oracle/ has enough space. Required space is 6900 MB , available space is 25266 MB.
File Validations Successful.
[main] [ 2016-03-18 13:40:56.723 UTC ] [Verifier.validateBackend:1058] write result to trace true
[main] [ 2016-03-18 13:40:56.724 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.724 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.724 UTC ] [Host.checkCloneJarExists:5160] Backup piece /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb exists
[main] [ 2016-03-18 13:40:56.724 UTC ] [Host.executeSteps:5975] Executing steps....
[main] [ 2016-03-18 13:40:56.724 UTC ] [HADatabaseUtils.isGridMgmtDB:2842] Mgmt DBName from srvm _mgmtdb
[main] [ 2016-03-18 13:40:56.724 UTC ] [Host.setUpForOperation:4003] setUpForOperation: Mode = 128
[main] [ 2016-03-18 13:40:56.724 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.725 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.725 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.732 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.733 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.733 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.733 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.733 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.736 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.736 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.737 UTC ] [OsUtilsBase.copyFile:1505] OsUtilsBase.copyFile:
[main] [ 2016-03-18 13:40:56.738 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.738 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.738 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.746 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.746 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.746 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.746 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.746 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.747 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.747 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.747 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.747 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.757 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.757 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.757 UTC ] [Host.getAuditNextGen:9054] NextGen Audit /u01/app/oracle/audit
[main] [ 2016-03-18 13:40:56.758 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.758 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.758 UTC ] [Host.getFolders:10740] adding path for DB_RECOVERY_FILE_DETS/u01/app/oracle/fast_recovery_area
[main] [ 2016-03-18 13:40:56.758 UTC ] [Host.getFolders:10750] RedoLogDestName db_create_online_log_dest_1
[main] [ 2016-03-18 13:40:56.758 UTC ] [Host.getFolders:10750] RedoLogDestName db_create_online_log_dest_2
[main] [ 2016-03-18 13:40:56.759 UTC ] [Host.getFolders:10750] RedoLogDestName db_create_online_log_dest_3
[main] [ 2016-03-18 13:40:56.759 UTC ] [Host.getFolders:10750] RedoLogDestName db_create_online_log_dest_4
[main] [ 2016-03-18 13:40:56.759 UTC ] [Host.getFolders:10750] RedoLogDestName db_create_online_log_dest_5
[main] [ 2016-03-18 13:40:56.759 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.759 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.759 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.759 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.759 UTC ] [Host.getFolders:10786] adding path for /u01/app/oracle/product/12.1.0/xe/dbs
[main] [ 2016-03-18 13:40:56.760 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.760 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.760 UTC ] [Host.getFolders:10838] adding path /u01/app/oracle/oradata/xe
[main] [ 2016-03-18 13:40:56.760 UTC ] [Host.getFolders:10841] m_enableDBConsolidation: true
[main] [ 2016-03-18 13:40:56.760 UTC ] [InitParamAttributes.needPdbSeedConvert:5220] OMF Mode: false usePdbFileNameConvert: false
[main] [ 2016-03-18 13:40:56.760 UTC ] [Host.getFolders:10849] adding path /u01/app/oracle/oradata/xe/pdbseed
[main] [ 2016-03-18 13:40:56.760 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.761 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.761 UTC ] [Host.getFolders:10841] m_enableDBConsolidation: true
[main] [ 2016-03-18 13:40:56.761 UTC ] [InitParamAttributes.needPdbSeedConvert:5220] OMF Mode: false usePdbFileNameConvert: false
[main] [ 2016-03-18 13:40:56.761 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.761 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.761 UTC ] [Host.getFolders:10841] m_enableDBConsolidation: true
[main] [ 2016-03-18 13:40:56.761 UTC ] [InitParamAttributes.needPdbSeedConvert:5220] OMF Mode: false usePdbFileNameConvert: false
[main] [ 2016-03-18 13:40:56.761 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.762 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.762 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.762 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.762 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.762 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.762 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.762 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.763 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.763 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.763 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.763 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.763 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[main] [ 2016-03-18 13:40:56.763 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[main] [ 2016-03-18 13:40:56.765 UTC ] [Host.setUpForOperation:4253] Folder[0]=/u01/app/oracle
[main] [ 2016-03-18 13:40:56.766 UTC ] [Host.setUpForOperation:4253] Folder[1]=/u01/app/oracle/admin/xe/adump
[main] [ 2016-03-18 13:40:56.766 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/admin/xe/adump
[main] [ 2016-03-18 13:40:56.766 UTC ] [Host.setUpForOperation:4253] Folder[2]=/u01/app/oracle/admin/xe/dpdump
[main] [ 2016-03-18 13:40:56.766 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/admin/xe/dpdump
[main] [ 2016-03-18 13:40:56.766 UTC ] [Host.setUpForOperation:4253] Folder[3]=/u01/app/oracle/admin/xe/pfile
[main] [ 2016-03-18 13:40:56.766 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/admin/xe/pfile
[main] [ 2016-03-18 13:40:56.767 UTC ] [Host.setUpForOperation:4253] Folder[4]=/u01/app/oracle/audit
[main] [ 2016-03-18 13:40:56.767 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/audit
[main] [ 2016-03-18 13:40:56.767 UTC ] [Host.setUpForOperation:4253] Folder[5]=/u01/app/oracle/cfgtoollogs/dbca/xe
[main] [ 2016-03-18 13:40:56.767 UTC ] [Host.setUpForOperation:4253] Folder[6]=/u01/app/oracle/fast_recovery_area
[main] [ 2016-03-18 13:40:56.767 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/fast_recovery_area
[main] [ 2016-03-18 13:40:56.767 UTC ] [Host.setUpForOperation:4253] Folder[7]=/u01/app/oracle/fast_recovery_area/xe
[main] [ 2016-03-18 13:40:56.768 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/fast_recovery_area/xe
[main] [ 2016-03-18 13:40:56.768 UTC ] [Host.setUpForOperation:4253] Folder[8]=/u01/app/oracle/oradata/xe
[main] [ 2016-03-18 13:40:56.768 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/oradata/xe
[main] [ 2016-03-18 13:40:56.768 UTC ] [Host.setUpForOperation:4253] Folder[9]=/u01/app/oracle/oradata/xe/pdbseed
[main] [ 2016-03-18 13:40:56.768 UTC ] [Host.setUpForOperation:4278] Creating directory: /u01/app/oracle/oradata/xe/pdbseed
[main] [ 2016-03-18 13:40:56.769 UTC ] [Host.setUpForOperation:4253] Folder[10]=/u01/app/oracle/product/12.1.0/xe/dbs
[main] [ 2016-03-18 13:40:56.769 UTC ] [Host.executeSteps:6091] setupForOperation returned: true
[main] [ 2016-03-18 13:40:56.769 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.769 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.769 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.777 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.778 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.778 UTC ] [Host.createStepSQLInterface:8605] sid =xe
[main] [ 2016-03-18 13:40:56.778 UTC ] [Host.createStepSQLInterface:8606] sidOrConnectString xe
[main] [ 2016-03-18 13:40:56.778 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: null
[main] [ 2016-03-18 13:40:56.779 UTC ] [InventoryUtil.getOUIInvSession:349] setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-03-18 13:40:56.780 UTC ] [OracleHome.getVersion:1010] Homeinfo /u01/app/oracle/product/12.1.0/xe,1
[main] [ 2016-03-18 13:40:56.859 UTC ] [OracleHome.getVersion:1038] OracleHome.server.getVersion Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:56.859 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:56.860 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:56.860 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:56.860 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[main] [ 2016-03-18 13:40:56.860 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-03-18 13:40:56.861 UTC ] [SQLPlusEngine.getCmmdParams:225] m_home 12.1.0.2.0
[main] [ 2016-03-18 13:40:56.861 UTC ] [SQLPlusEngine.getCmmdParams:226] version > 112 true
[main] [ 2016-03-18 13:40:56.861 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.861 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:40:56.861 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:40:56.870 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:40:56.870 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:40:56.870 UTC ] [SQLEngine.getEnvParams:602] Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-03-18 13:40:56.870 UTC ] [SQLEngine.getEnvParams:612] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-03-18 13:40:56.871 UTC ] [SQLEngine.initialize:358] Execing SQLPLUS/SVRMGR process...
[main] [ 2016-03-18 13:40:56.874 UTC ] [SQLEngine.initialize:395] m_bReaderStarted: false
[main] [ 2016-03-18 13:40:56.874 UTC ] [SQLEngine.initialize:399] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:40:56.885 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.886 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.887 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:40:56.890 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.890 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.892 UTC ] [InventoryUtil.getOUIInvSession:349] setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[Thread-48] [ 2016-03-18 13:40:56.892 UTC ] [InventoryUtil.getHomeName:113] homeName = OraDB12Home1
[Thread-48] [ 2016-03-18 13:40:56.894 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.894 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.894 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:40:56.903 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.903 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.903 UTC ] [StepManager.getSteps:275] logging folder set: /u01/app/oracle/cfgtoollogs/dbca/xe
[Thread-48] [ 2016-03-18 13:40:56.903 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.904 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.904 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:40:56.912 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.912 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.912 UTC ] [StepManager.getSteps:295] ckFileName /u01/app/oracle/cfgtoollogs/dbca/xe/OraDB12Home1_xe_creation_checkpoint.xml ckContext oracle.sysman.assistants.util.CheckpointContext@58ee21f5
[Thread-48] [ 2016-03-18 13:40:56.915 UTC ] [CheckpointContext.isCleanupNeeded:140] failedChkFiles null
[Thread-48] [ 2016-03-18 13:40:56.916 UTC ] [CheckpointContext.isCleanupNeeded:149] Failed check files iterator null
[Thread-48] [ 2016-03-18 13:40:56.918 UTC ] [CommandLineArguments.getCmdLineWithoutPasswords:7686] remove password for logging-PDBADMINPASSWORD
[Thread-48] [ 2016-03-18 13:40:56.918 UTC ] [CommandLineArguments.getCmdLineWithoutPasswords:7686] remove password for logging-SYSPASSWORD
[Thread-48] [ 2016-03-18 13:40:56.918 UTC ] [CommandLineArguments.getCmdLineWithoutPasswords:7686] remove password for logging-SYSTEMPASSWORD
[Thread-48] [ 2016-03-18 13:40:56.919 UTC ] [StepManager.getSteps:326] checkpoint from file oracle.sysman.oic.oics.OicsCheckPoint@5796de29
[Thread-48] [ 2016-03-18 13:40:56.919 UTC ] [StepManager.getSteps:332] adding checkpoint to session
[Thread-48] [ 2016-03-18 13:40:56.919 UTC ] [StepManager.getSteps:334] saved new checkpoint session for DB creation
[Thread-48] [ 2016-03-18 13:40:56.919 UTC ] [StepManager.getSteps:430] StepManager isHARegistrationRequired : false
[Thread-48] [ 2016-03-18 13:40:56.932 UTC ] [StepContext$ModeRunner.run:2873] ---- Progress Needed:=true
[Thread-48] [ 2016-03-18 13:40:56.932 UTC ] [BasicStep.execute:250] Executing Step : CLONE_DB_CREATION_RMAN_RESTORE
[Thread-48] [ 2016-03-18 13:40:56.932 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01092
[Thread-48] [ 2016-03-18 13:40:56.932 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01034
[Thread-48] [ 2016-03-18 13:40:56.932 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-03114
[Thread-48] [ 2016-03-18 13:40:56.933 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-12560
[Thread-48] [ 2016-03-18 13:40:56.933 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-01109
[Thread-48] [ 2016-03-18 13:40:56.933 UTC ] [BasicStep.configureSettings:353] messageHandler being set=null
[Thread-48] [ 2016-03-18 13:40:56.935 UTC ] [Host.getRMANConnectionInfo:1171] Connect to Instance xe
[Thread-48] [ 2016-03-18 13:40:56.937 UTC ] [BasicStep.execute:250] Executing Step : INSTANCE_CREATION
[Thread-48] [ 2016-03-18 13:40:56.937 UTC ] [BasicStep.configureSettings:353] messageHandler being set=null
[Thread-48] [ 2016-03-18 13:40:56.937 UTC ] [InitParamAttributes.setPluggableDatabase:5115] setPluggableDatabase: bInclude=true
[Thread-48] [ 2016-03-18 13:40:56.940 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.940 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:40:56.942 UTC ] [InitParamAttributes.sortParams:3916] m_sortOn:-1 sortOn:4
[Thread-48] [ 2016-03-18 13:40:56.949 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param db_block_size
[Thread-48] [ 2016-03-18 13:40:56.949 UTC ] [InitParamAttributes.createInitFile:1816] The value is 8192
[Thread-48] [ 2016-03-18 13:40:56.949 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param open_cursors
[Thread-48] [ 2016-03-18 13:40:56.949 UTC ] [InitParamAttributes.createInitFile:1816] The value is 300
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param db_domain
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [InitParamAttributes.createInitFile:1816] The value is oracle.docker
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param db_name
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [InitParamAttributes.createInitFile:1816] The value is "xe"
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param control_files
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:40:56.950 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [InitParamAttributes.createInitFile:1816] The value is ("/u01/app/oracle/oradata/xe/control01.ctl", "/u01/app/oracle/fast_recovery_area/xe/control02.ctl")
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param db_recovery_file_dest
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [InitParamAttributes.createInitFile:1816] The value is "/u01/app/oracle/fast_recovery_area"
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param db_recovery_file_dest_size
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [InitParamAttributes.createInitFile:1816] The value is 4560m
[Thread-48] [ 2016-03-18 13:40:56.951 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param compatible
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [InitParamAttributes.createInitFile:1816] The value is 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param diagnostic_dest
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [InitParamAttributes.createInitFile:1816] The value is /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param enable_pluggable_database
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [InitParamAttributes.createInitFile:1816] The value is true
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param memory_target
[Thread-48] [ 2016-03-18 13:40:56.952 UTC ] [InitParamAttributes.createInitFile:1816] The value is 512m
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param processes
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [InitParamAttributes.createInitFile:1816] The value is 300
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param audit_file_dest
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [InitParamAttributes.createInitFile:1816] The value is "/u01/app/oracle/admin/xe/adump"
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param audit_trail
[Thread-48] [ 2016-03-18 13:40:56.953 UTC ] [InitParamAttributes.createInitFile:1816] The value is db
[Thread-48] [ 2016-03-18 13:40:56.954 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param remote_login_passwordfile
[Thread-48] [ 2016-03-18 13:40:56.954 UTC ] [InitParamAttributes.createInitFile:1816] The value is EXCLUSIVE
[Thread-48] [ 2016-03-18 13:40:56.954 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param dispatchers
[Thread-48] [ 2016-03-18 13:40:56.954 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:40:56.954 UTC ] [InitParamAttributes.createInitFile:1816] The value is "(PROTOCOL=TCP) (SERVICE=xeXDB)"
[Thread-48] [ 2016-03-18 13:40:56.954 UTC ] [InitParamAttributes.createInitFile:1811] Processing init param undo_tablespace
[Thread-48] [ 2016-03-18 13:40:56.954 UTC ] [InitParamAttributes.createInitFile:1816] The value is UNDOTBS1
[Thread-48] [ 2016-03-18 13:40:56.957 UTC ] [HADatabaseUtils.isGridMgmtDB:2842] Mgmt DBName from srvm _mgmtdb
[Thread-48] [ 2016-03-18 13:40:56.958 UTC ] [InstanceStep.executeImpl:280] Checking Register with HA Listener
[Thread-48] [ 2016-03-18 13:40:56.958 UTC ] [Host.noEntryinOratab:7194] Check made for oratab arg passed............
[Thread-48] [ 2016-03-18 13:40:56.958 UTC ] [Oratab.getTempFile:805] Temp oratab: /tmp/oratab
[Thread-48] [ 2016-03-18 13:40:56.960 UTC ] [InstanceStep.executeImpl:331] Password file location prior to parsing /u01/app/oracle/product/12.1.0/xe/dbs/orapwxe
[Thread-48] [ 2016-03-18 13:40:56.960 UTC ] [InstanceStep.executeImpl:333] Password file location prior to parsing /u01/app/oracle/product/12.1.0/xe/dbs/orapwxe
[Thread-48] [ 2016-03-18 13:40:56.960 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.961 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.961 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.961 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.961 UTC ] [CommonUtils.createPasswordFile:1243] calling new orapwd for 11.1 or higher
[Thread-48] [ 2016-03-18 13:40:56.961 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.961 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.962 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.962 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.962 UTC ] [CommonUtils.getPasswordFileCreateCmd:1182] for new orapwd for 11.1 or higher
[Thread-48] [ 2016-03-18 13:40:56.963 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.964 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.964 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.964 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.964 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] /u01/app/oracle/product/12.1.0/xe/bin/orapwd
[Thread-48] [ 2016-03-18 13:40:56.964 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] file=/u01/app/oracle/product/12.1.0/xe/dbs/orapwxe
[Thread-48] [ 2016-03-18 13:40:56.964 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] force=y
[Thread-48] [ 2016-03-18 13:40:56.964 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213] format=12
[Thread-48] [ 2016-03-18 13:40:56.965 UTC ] [OsUtilsBase.execProg:2123] beginning execProg with input array.
[Thread-48] [ 2016-03-18 13:40:56.979 UTC ] [OsUtilsBase.execProg:2160] finished execProg with input array. Status:0
[Thread-48] [ 2016-03-18 13:40:56.980 UTC ] [InstanceStep.setASMAdminGID:373] isWindows: false Storage Type: 0
[Thread-48] [ 2016-03-18 13:40:56.981 UTC ] [BasicStep.configureSettings:353] messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@40974600
[Thread-48] [ 2016-03-18 13:40:56.981 UTC ] [CloneRmanRestoreStep.executeImpl:270] Instance Creation went fine..........
[Thread-48] [ 2016-03-18 13:40:56.981 UTC ] [CloneRmanRestoreStep.executeImpl:277] db_recovery_file_dest="/u01/app/oracle/fast_recovery_area"
[Thread-48] [ 2016-03-18 13:40:56.981 UTC ] [CloneRmanRestoreStep.executeImpl:280] db_recovery_file_dest_size=4560m
[Thread-48] [ 2016-03-18 13:40:56.982 UTC ] [InitParameterFile.getLongValue:687] May have unit. Stripping unit and trying with 4560
[Thread-48] [ 2016-03-18 13:40:56.982 UTC ] [CloneRmanRestoreStep.executeImpl:297] Setting NLS_LANG to AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:40:56.982 UTC ] [SQLEngine.done:2278] Done called
[Thread-48] [ 2016-03-18 13:40:56.984 UTC ] [SQLEngine.reInitialize:785] Reinitializing SQLEngine...
[Thread-48] [ 2016-03-18 13:40:56.985 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.985 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.985 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.985 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.985 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.985 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.985 UTC ] [SQLPlusEngine.getCmmdParams:225] m_home 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:40:56.986 UTC ] [SQLPlusEngine.getCmmdParams:226] version > 112 true
[Thread-48] [ 2016-03-18 13:40:56.986 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.986 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:40:56.986 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:40:56.990 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.991 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:40:56.991 UTC ] [SQLEngine.getEnvParams:612] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:40:56.991 UTC ] [SQLEngine.initialize:358] Execing SQLPLUS/SVRMGR process...
[Thread-48] [ 2016-03-18 13:40:57.002 UTC ] [SQLEngine.initialize:395] m_bReaderStarted: false
[Thread-48] [ 2016-03-18 13:40:57.002 UTC ] [SQLEngine.initialize:399] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:40:57.002 UTC ] [SQLEngine.initialize:448] Waiting for m_bReaderStarted to be true
[Thread-48] [ 2016-03-18 13:40:58.042 UTC ] [SQLEngine.setSpool:2056] old Spool = null
[Thread-48] [ 2016-03-18 13:40:58.042 UTC ] [SQLEngine.setSpool:2057] Setting Spool = /u01/app/oracle/cfgtoollogs/dbca/xe/CloneRmanRestore.log
[Thread-48] [ 2016-03-18 13:40:58.043 UTC ] [SQLEngine.setSpool:2058] Is spool appendable? --> true
[Thread-48] [ 2016-03-18 13:40:58.043 UTC ] [CloneRmanRestoreStep.executeImpl:323] _diag_hm_rc_enabled to false
[Thread-48] [ 2016-03-18 13:40:58.043 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:40:58.044 UTC ] [CloneRmanRestoreStep.executeImpl:358] Using control file: /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.ctl; sourceDBName: seeddata
[Thread-48] [ 2016-03-18 13:40:58.044 UTC ] [OsUtilsBase.copyFile:1505] OsUtilsBase.copyFile:
[Thread-48] [ 2016-03-18 13:40:58.106 UTC ] [OsUtilsBase.copyFile:1553] **write of file at destination complete...
[Thread-48] [ 2016-03-18 13:40:58.106 UTC ] [OsUtilsBase.copyFile:1588] **file copy status:= true
[Thread-48] [ 2016-03-18 13:41:04.939 UTC ] [CloneRmanRestoreStep.executeImpl:399] update db alert log File
[Thread-48] [ 2016-03-18 13:41:04.939 UTC ] [CloneRmanRestoreStep.executeImpl:402] Clearing v$datafile_copy entries
[Thread-48] [ 2016-03-18 13:41:04.981 UTC ] [CloneRmanRestoreStep.executeImpl:404] Clearing all backup entries
[Thread-48] [ 2016-03-18 13:41:04.986 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:41:04.987 UTC ] [CloneRmanRestoreStep.executeImpl:457] Getting the map of data file ids and names for restore
[Thread-61] [ 2016-03-18 13:41:04.987 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: 31975
[Thread-48] [ 2016-03-18 13:41:04.987 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:41:04.988 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:41:04.988 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591] newname for datafile /u01/app/oracle/oradata/xe/sysaux01.dbf
[Thread-48] [ 2016-03-18 13:41:04.988 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:41:04.989 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:41:04.989 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591] newname for datafile /u01/app/oracle/oradata/xe/system01.dbf
[Thread-48] [ 2016-03-18 13:41:04.989 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:41:04.990 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:41:04.990 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591] newname for datafile /u01/app/oracle/oradata/xe/undotbs01.dbf
[Thread-48] [ 2016-03-18 13:41:04.990 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:41:04.990 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:41:04.990 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591] newname for datafile /u01/app/oracle/oradata/xe/users01.dbf
[Thread-48] [ 2016-03-18 13:41:04.993 UTC ] [RMANUtil.getRMANUtil:147] creating new util instance
[Thread-48] [ 2016-03-18 13:41:04.993 UTC ] [RMANUtil.getRMANUtil:148] target sid xe
[Thread-48] [ 2016-03-18 13:41:04.993 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:41:04.995 UTC ] [RMANEngine.getCmmdParams:343] using rman from /u01/app/oracle/product/12.1.0/xe/bin/rman
[Thread-48] [ 2016-03-18 13:41:04.995 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:41:04.995 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:41:04.996 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:41:05.005 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:41:05.005 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:41:05.006 UTC ] [RMANEngine.getEnvParams:436] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:41:05.006 UTC ] [RMANEngine.initialize:195] Execing RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:41:05.008 UTC ] [RMANEngine.initialize:221] Created RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:41:05.008 UTC ] [RMANEngine.initialize:235] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:41:05.009 UTC ] [RMANEngine.initialize:239] Started reader thread for RMAN process in initialize...
[Thread-64] [ 2016-03-18 13:41:05.009 UTC ] [RMANEngine.run:803] Started reader thread for RMAN process from initialize...
[Thread-64] [ 2016-03-18 13:41:05.009 UTC ] [RMANEngine.run:807] Set m_bReaderStarted to true in initialize...
[Thread-48] [ 2016-03-18 13:41:05.010 UTC ] [RMANEngine.initialize:247] Started error thread for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:41:05.010 UTC ] [RMANEngine.initialize:252] Created output writer for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:41:05.010 UTC ] [RMANEngine.initialize:290] Created Log writer for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:41:05.010 UTC ] [RMANEngine.initialize:295] Value of m_bReaderStarted in initialize=true
[Thread-48] [ 2016-03-18 13:41:05.010 UTC ] [RMANEngine.initialize:310] Made sure reader has started for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:41:05.010 UTC ] [RMANEngine.connect:775] NOT using OS auth to connect
[Thread-48] [ 2016-03-18 13:41:05.011 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:41:05.011 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=connect target SYS/******
[Thread-48] [ 2016-03-18 13:41:05.011 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-64] [ 2016-03-18 13:41:05.045 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:05.046 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Recovery Manager: Release 12.1.0.2.0 - Production on Fri Mar 18 13:41:05 2016
[Thread-64] [ 2016-03-18 13:41:05.046 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:05.046 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Copyright (c) 1982, 2015, Oracle and/or its affiliates. All rights reserved.
[Thread-64] [ 2016-03-18 13:41:05.127 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:05.128 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-64] [ 2016-03-18 13:41:05.688 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=connected to target database: SEEDDATA (DBID=4213022738, not open)
[Thread-64] [ 2016-03-18 13:41:05.691 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:05.697 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-64] [ 2016-03-18 13:41:05.697 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set on
[Thread-64] [ 2016-03-18 13:41:05.705 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=set echo off;
[Thread-64] [ 2016-03-18 13:41:05.705 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false
[Thread-48] [ 2016-03-18 13:41:05.705 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:41:05.706 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=connect target SYS/******
[Thread-48] [ 2016-03-18 13:41:05.706 UTC ] [RMANUtil.getRMANEngine:197] RMANUtil spooling to=/u01/app/oracle/cfgtoollogs/dbca/xe/rmanUtil
[Thread-48] [ 2016-03-18 13:41:05.707 UTC ] [RMANUtil.catalogBackup:471] catalog command CATALOG START WITH '/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates//Seed_Database.dfb' NOPROMPT
[Thread-48] [ 2016-03-18 13:41:05.707 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:41:05.707 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=CATALOG START WITH '/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates//Seed_Database.dfb' NOPROMPT;
[Thread-48] [ 2016-03-18 13:41:05.707 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-64] [ 2016-03-18 13:41:05.707 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=set echo off;
[Thread-64] [ 2016-03-18 13:41:05.707 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set off
[Thread-64] [ 2016-03-18 13:41:05.708 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:05.710 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-64] [ 2016-03-18 13:41:05.718 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Starting implicit crosscheck backup at 18-MAR-16
[Thread-64] [ 2016-03-18 13:41:05.723 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using target database control file instead of recovery catalog
[Thread-64] [ 2016-03-18 13:41:05.977 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=allocated channel: ORA_DISK_1
[Thread-64] [ 2016-03-18 13:41:05.981 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: SID=248 device type=DISK
[Thread-64] [ 2016-03-18 13:41:06.250 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Finished implicit crosscheck backup at 18-MAR-16
[Thread-64] [ 2016-03-18 13:41:06.250 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.250 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Starting implicit crosscheck copy at 18-MAR-16
[Thread-64] [ 2016-03-18 13:41:06.252 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using channel ORA_DISK_1
[Thread-64] [ 2016-03-18 13:41:06.267 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Finished implicit crosscheck copy at 18-MAR-16
[Thread-64] [ 2016-03-18 13:41:06.270 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.271 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=searching for all files in the recovery area
[Thread-64] [ 2016-03-18 13:41:06.275 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=cataloging files...
[Thread-64] [ 2016-03-18 13:41:06.435 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=no files cataloged
[Thread-64] [ 2016-03-18 13:41:06.435 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.634 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=searching for all files that match the pattern /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates//Seed_Database.dfb
[Thread-64] [ 2016-03-18 13:41:06.638 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.638 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=List of Files Unknown to the Database
[Thread-64] [ 2016-03-18 13:41:06.638 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output======================================
[Thread-64] [ 2016-03-18 13:41:06.638 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=File Name: /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb
[Thread-64] [ 2016-03-18 13:41:06.638 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=cataloging files...
[Thread-64] [ 2016-03-18 13:41:06.676 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=cataloging done
[Thread-64] [ 2016-03-18 13:41:06.677 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.677 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=List of Cataloged Files
[Thread-64] [ 2016-03-18 13:41:06.677 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output========================
[Thread-64] [ 2016-03-18 13:41:06.678 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=File Name: /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb
[Thread-64] [ 2016-03-18 13:41:06.713 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.718 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-64] [ 2016-03-18 13:41:06.720 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set on
[Thread-64] [ 2016-03-18 13:41:06.728 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=set echo off;
[Thread-64] [ 2016-03-18 13:41:06.729 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false
[Thread-48] [ 2016-03-18 13:41:06.729 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:41:06.729 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=CATALOG START WITH '/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates//Seed_Database.dfb' NOPROMPT;
[Thread-48] [ 2016-03-18 13:41:06.729 UTC ] [RMANUtil.catalogBackup:474] done catalog
[Thread-48] [ 2016-03-18 13:41:06.729 UTC ] [RMANUtil.getRestoreCommandForDataFiles:296] generate set new name/restore command per datafile basis
[Thread-48] [ 2016-03-18 13:41:06.730 UTC ] [RMANUtil.restoreDataFilesFromBackup:343] set new name command RUN {
set newname for datafile 3 to '/u01/app/oracle/oradata/xe/sysaux01.dbf' ;
set newname for datafile 1 to '/u01/app/oracle/oradata/xe/system01.dbf' ;
set newname for datafile 6 to '/u01/app/oracle/oradata/xe/users01.dbf' ;
set newname for datafile 4 to '/u01/app/oracle/oradata/xe/undotbs01.dbf' ;
restore datafile 3;
restore datafile 1;
restore datafile 6;
restore datafile 4; }
[Thread-48] [ 2016-03-18 13:41:06.730 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:41:06.730 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=RUN {
set newname for datafile 3 to '/u01/app/oracle/oradata/xe/sysaux01.dbf' ;
set newname for datafile 1 to '/u01/app/oracle/oradata/xe/system01.dbf' ;
set newname for datafile 6 to '/u01/app/oracle/oradata/xe/users01.dbf' ;
set newname for datafile 4 to '/u01/app/oracle/oradata/xe/undotbs01.dbf' ;
restore datafile 3;
restore datafile 1;
restore datafile 6;
restore datafile 4; }
[Thread-48] [ 2016-03-18 13:41:06.730 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-64] [ 2016-03-18 13:41:06.730 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=set echo off;
[Thread-64] [ 2016-03-18 13:41:06.731 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set off
[Thread-64] [ 2016-03-18 13:41:06.731 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.734 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17>
[Thread-64] [ 2016-03-18 13:41:06.738 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=executing command: SET NEWNAME
[Thread-64] [ 2016-03-18 13:41:06.761 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.763 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=executing command: SET NEWNAME
[Thread-64] [ 2016-03-18 13:41:06.781 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.782 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=executing command: SET NEWNAME
[Thread-64] [ 2016-03-18 13:41:06.801 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.802 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=executing command: SET NEWNAME
[Thread-64] [ 2016-03-18 13:41:06.822 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:06.826 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Starting restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:41:06.831 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using channel ORA_DISK_1
[Thread-64] [ 2016-03-18 13:41:06.863 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:07.001 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore
[Thread-64] [ 2016-03-18 13:41:07.003 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set
[Thread-64] [ 2016-03-18 13:41:07.005 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring datafile 00003 to /u01/app/oracle/oradata/xe/sysaux01.dbf
[Thread-64] [ 2016-03-18 13:41:07.006 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb
[Thread-61] [ 2016-03-18 13:41:14.988 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: -1
[Thread-61] [ 2016-03-18 13:41:24.989 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: -1
[Thread-61] [ 2016-03-18 13:41:34.990 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: -1
[Thread-64] [ 2016-03-18 13:41:42.018 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb tag=TAG20150706T124921
[Thread-64] [ 2016-03-18 13:41:42.019 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restored backup piece 1
[Thread-64] [ 2016-03-18 13:41:42.022 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:36
[Thread-64] [ 2016-03-18 13:41:42.038 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Finished restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:41:42.077 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:42.080 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Starting restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:41:42.084 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using channel ORA_DISK_1
[Thread-64] [ 2016-03-18 13:41:42.098 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:41:42.228 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore
[Thread-64] [ 2016-03-18 13:41:42.228 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set
[Thread-64] [ 2016-03-18 13:41:42.229 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring datafile 00001 to /u01/app/oracle/oradata/xe/system01.dbf
[Thread-64] [ 2016-03-18 13:41:42.230 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb
[Thread-61] [ 2016-03-18 13:41:44.991 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: 809
[Thread-61] [ 2016-03-18 13:41:54.993 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: -1
[Thread-61] [ 2016-03-18 13:42:04.994 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: -1
[Thread-61] [ 2016-03-18 13:42:14.994 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: -1
[Thread-61] [ 2016-03-18 13:42:24.996 UTC ] [CloneRmanRestoreStep$ProgressTracker.run:860] numBytesRead: 1062
[Thread-64] [ 2016-03-18 13:42:27.239 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb tag=TAG20150706T124921
[Thread-64] [ 2016-03-18 13:42:27.240 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restored backup piece 1
[Thread-64] [ 2016-03-18 13:42:27.241 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:45
[Thread-64] [ 2016-03-18 13:42:27.246 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Finished restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:42:27.273 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:42:27.275 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Starting restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:42:27.280 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using channel ORA_DISK_1
[Thread-64] [ 2016-03-18 13:42:27.291 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:42:27.418 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore
[Thread-64] [ 2016-03-18 13:42:27.420 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set
[Thread-64] [ 2016-03-18 13:42:27.420 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring datafile 00006 to /u01/app/oracle/oradata/xe/users01.dbf
[Thread-64] [ 2016-03-18 13:42:27.421 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb
[Thread-64] [ 2016-03-18 13:42:28.424 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb tag=TAG20150706T124921
[Thread-64] [ 2016-03-18 13:42:28.424 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restored backup piece 1
[Thread-64] [ 2016-03-18 13:42:28.426 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:01
[Thread-64] [ 2016-03-18 13:42:28.430 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Finished restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:42:28.454 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:42:28.457 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Starting restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:42:28.463 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using channel ORA_DISK_1
[Thread-64] [ 2016-03-18 13:42:28.475 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:42:28.618 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore
[Thread-64] [ 2016-03-18 13:42:28.619 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set
[Thread-64] [ 2016-03-18 13:42:28.619 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring datafile 00004 to /u01/app/oracle/oradata/xe/undotbs01.dbf
[Thread-64] [ 2016-03-18 13:42:28.619 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb
[Thread-64] [ 2016-03-18 13:42:29.629 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/Seed_Database.dfb tag=TAG20150706T124921
[Thread-64] [ 2016-03-18 13:42:29.630 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restored backup piece 1
[Thread-64] [ 2016-03-18 13:42:29.631 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:01
[Thread-64] [ 2016-03-18 13:42:29.636 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Finished restore at 18-MAR-16
[Thread-64] [ 2016-03-18 13:42:29.674 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-64] [ 2016-03-18 13:42:29.678 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-64] [ 2016-03-18 13:42:29.679 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set on
[Thread-64] [ 2016-03-18 13:42:29.688 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=set echo off;
[Thread-64] [ 2016-03-18 13:42:29.688 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false
[Thread-64] [ 2016-03-18 13:42:29.688 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=set echo off;
[Thread-48] [ 2016-03-18 13:42:29.688 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:42:29.688 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=RUN {
set newname for datafile 3 to '/u01/app/oracle/oradata/xe/sysaux01.dbf' ;
set newname for datafile 1 to '/u01/app/oracle/oradata/xe/system01.dbf' ;
set newname for datafile 6 to '/u01/app/oracle/oradata/xe/users01.dbf' ;
set newname for datafile 4 to '/u01/app/oracle/oradata/xe/undotbs01.dbf' ;
restore datafile 3;
restore datafile 1;
restore datafile 6;
restore datafile 4; }
[Thread-48] [ 2016-03-18 13:42:29.688 UTC ] [RMANUtil.restoreDataFilesFromBackup:346] done with set new name
[Thread-48] [ 2016-03-18 13:42:29.693 UTC ] [CloneRmanRestoreStep.setDataFileNames:638] Updated datafile name:/u01/app/oracle/oradata/xe/sysaux01.dbf
[Thread-48] [ 2016-03-18 13:42:29.694 UTC ] [CloneRmanRestoreStep.setDataFileNames:638] Updated datafile name:/u01/app/oracle/oradata/xe/system01.dbf
[Thread-48] [ 2016-03-18 13:42:29.695 UTC ] [CloneRmanRestoreStep.setDataFileNames:638] Updated datafile name:/u01/app/oracle/oradata/xe/undotbs01.dbf
[Thread-48] [ 2016-03-18 13:42:29.696 UTC ] [CloneRmanRestoreStep.setDataFileNames:638] Updated datafile name:/u01/app/oracle/oradata/xe/users01.dbf
[Thread-48] [ 2016-03-18 13:42:29.696 UTC ] [CloneRmanRestoreStep.executeImpl:508] Restored datafiles
[Thread-48] [ 2016-03-18 13:42:29.697 UTC ] [SQLEngine.spoolOff:2124] Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/xe/CloneRmanRestore.log
[Thread-48] [ 2016-03-18 13:42:29.697 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/cfgtoollogs/dbca/xe/initxeTempOMF.ora
[Thread-48] [ 2016-03-18 13:42:29.698 UTC ] [BasicStep.configureSettings:353] messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@40974600
[Thread-48] [ 2016-03-18 13:42:29.698 UTC ] [StepContext$ModeRunner.run:2873] ---- Progress Needed:=true
[Thread-48] [ 2016-03-18 13:42:29.699 UTC ] [BasicStep.execute:250] Executing Step : CLONE_DB_CREATION
[Thread-48] [ 2016-03-18 13:42:29.699 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01503
[Thread-48] [ 2016-03-18 13:42:29.699 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01034
[Thread-48] [ 2016-03-18 13:42:29.699 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01092
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-03113
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-03114
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-19502
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01219
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-01109
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-12712
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-00942
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-04043
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-44304
[Thread-48] [ 2016-03-18 13:42:29.700 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-44305
[Thread-48] [ 2016-03-18 13:42:29.702 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-06512
[Thread-48] [ 2016-03-18 13:42:29.702 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-06510
[Thread-48] [ 2016-03-18 13:42:29.702 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-01403
[Thread-48] [ 2016-03-18 13:42:29.702 UTC ] [BasicStep.configureSettings:353] messageHandler being set=null
[Thread-48] [ 2016-03-18 13:42:29.727 UTC ] [SQLEngine.setSpool:2056] old Spool = null
[Thread-48] [ 2016-03-18 13:42:29.727 UTC ] [SQLEngine.setSpool:2057] Setting Spool = /u01/app/oracle/cfgtoollogs/dbca/xe/cloneDBCreation.log
[Thread-48] [ 2016-03-18 13:42:29.727 UTC ] [SQLEngine.setSpool:2058] Is spool appendable? --> true
[Thread-48] [ 2016-03-18 13:42:29.727 UTC ] [CloneDBCreationStep.executeImpl:356] size of datfiles in create db 4
[Thread-48] [ 2016-03-18 13:42:29.727 UTC ] [CloneDBCreationStep.executeImpl:382] a.createCtlFileSql = Create controlfile reuse set database "xe"
MAXINSTANCES 8
MAXLOGHISTORY 1
MAXLOGFILES 16
MAXLOGMEMBERS 3
MAXDATAFILES 1024
Datafile
'/u01/app/oracle/oradata/xe/sysaux01.dbf',
'/u01/app/oracle/oradata/xe/system01.dbf',
[Thread-48] [ 2016-03-18 13:42:29.728 UTC ] [CloneDBCreationStep.executeImpl:386] b.createCtlFileSql = Create controlfile reuse set database "xe"
MAXINSTANCES 8
MAXLOGHISTORY 1
MAXLOGFILES 16
MAXLOGMEMBERS 3
MAXDATAFILES 1024
Datafile
'/u01/app/oracle/oradata/xe/sysaux01.dbf',
'/u01/app/oracle/oradata/xe/system01.dbf',
'/u01/app/oracle/oradata/xe/undotbs01.dbf',
[Thread-48] [ 2016-03-18 13:42:33.847 UTC ] [CloneDBCreationStep.executeImpl:400] pfile for startup=/u01/app/oracle/admin/xe/pfile/init.ora
[Thread-48] [ 2016-03-18 13:42:35.616 UTC ] [CloneDBCreationStep.executeImpl:405] createCtl=Create controlfile reuse set database "xe"
MAXINSTANCES 8
MAXLOGHISTORY 1
MAXLOGFILES 16
MAXLOGMEMBERS 3
MAXDATAFILES 1024
Datafile
'/u01/app/oracle/oradata/xe/sysaux01.dbf',
'/u01/app/oracle/oradata/xe/system01.dbf',
'/u01/app/oracle/oradata/xe/undotbs01.dbf',
'/u01/app/oracle/oradata/xe/users01.dbf',
[Thread-48] [ 2016-03-18 13:42:35.616 UTC ] [CloneDBCreationStep.executeImpl:412] crtCtl=Create controlfile reuse set database "xe"
MAXINSTANCES 8
MAXLOGHISTORY 1
MAXLOGFILES 16
MAXLOGMEMBERS 3
MAXDATAFILES 1024
Datafile
'/u01/app/oracle/oradata/xe/sysaux01.dbf',
'/u01/app/oracle/oradata/xe/system01.dbf',
'/u01/app/oracle/oradata/xe/undotbs01.dbf',
'/u01/app/oracle/oradata/xe/users01.dbf'
[Thread-48] [ 2016-03-18 13:42:35.616 UTC ] [CloneDBCreationStep.executeImpl:428] Length of OriginalRedoLogsGrNames=3
[Thread-48] [ 2016-03-18 13:42:35.617 UTC ] [RedoLogGroupSQLGenerator.filespecsClause:68] LOG FILE size in MB = 50
[Thread-48] [ 2016-03-18 13:42:35.617 UTC ] [RedoLogGroupSQLGenerator.filespecsClause:74] RedoFile space clause ('{ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/redo01.log') SIZE 50M
[Thread-48] [ 2016-03-18 13:42:35.617 UTC ] [CloneDBCreationStep.executeImpl:437] 0th redoLogText = GROUP 1 ('{ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/redo01.log') SIZE 50M
[Thread-48] [ 2016-03-18 13:42:35.617 UTC ] [RedoLogGroupSQLGenerator.filespecsClause:68] LOG FILE size in MB = 50
[Thread-48] [ 2016-03-18 13:42:35.617 UTC ] [RedoLogGroupSQLGenerator.filespecsClause:74] RedoFile space clause ('{ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/redo02.log') SIZE 50M
[Thread-48] [ 2016-03-18 13:42:35.617 UTC ] [CloneDBCreationStep.executeImpl:437] 1th redoLogText = GROUP 2 ('{ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/redo02.log') SIZE 50M
[Thread-48] [ 2016-03-18 13:42:35.617 UTC ] [RedoLogGroupSQLGenerator.filespecsClause:68] LOG FILE size in MB = 50
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [RedoLogGroupSQLGenerator.filespecsClause:74] RedoFile space clause ('{ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/redo03.log') SIZE 50M
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CloneDBCreationStep.executeImpl:437] 2th redoLogText = GROUP 3 ('{ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/redo03.log') SIZE 50M
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:42:35.618 UTC ] [CloneDBCreationStep.executeImpl:458] createCTLSql=Create controlfile reuse set database "xe"
MAXINSTANCES 8
MAXLOGHISTORY 1
MAXLOGFILES 16
MAXLOGMEMBERS 3
MAXDATAFILES 1024
Datafile
'/u01/app/oracle/oradata/xe/sysaux01.dbf',
'/u01/app/oracle/oradata/xe/system01.dbf',
'/u01/app/oracle/oradata/xe/undotbs01.dbf',
'/u01/app/oracle/oradata/xe/users01.dbf'
LOGFILE GROUP 1 ('/u01/app/oracle/oradata/xe/redo01.log') SIZE 50M,
GROUP 2 ('/u01/app/oracle/oradata/xe/redo02.log') SIZE 50M,
GROUP 3 ('/u01/app/oracle/oradata/xe/redo03.log') SIZE 50M RESETLOGS;
[Thread-48] [ 2016-03-18 13:42:35.702 UTC ] [CloneDBCreationStep.executeImpl:471] calling zerodbid
[Thread-48] [ 2016-03-18 13:42:51.263 UTC ] [CloneDBCreationStep.executeImpl:481] Shutdown database
[Thread-48] [ 2016-03-18 13:42:51.265 UTC ] [CloneDBCreationStep.executeImpl:513] Startup ......nomount......
[Thread-48] [ 2016-03-18 13:42:52.993 UTC ] [CloneDBCreationStep.executeImpl:532] Enabling restricted session.
[Thread-48] [ 2016-03-18 13:42:55.024 UTC ] [CloneDBCreationStep.executeImpl:534] alter database "xe" open resetlogs;
[Thread-48] [ 2016-03-18 13:42:57.339 UTC ] [CloneDBCreationStep.executeImpl:542] Removing existing services from sourcedb seeddata
[Thread-48] [ 2016-03-18 13:42:57.407 UTC ] [CloneDBCreationStep.executeImpl:555] Renaming global_name
[Thread-48] [ 2016-03-18 13:42:57.419 UTC ] [CloneDBCreationStep.executeImpl:633] Temp file to be added:={ORACLE_BASE}/oradata/{DB_UNIQUE_NAME}/temp01.dbf
[Thread-48] [ 2016-03-18 13:42:57.419 UTC ] [CloneDBCreationStep.executeImpl:634] Temp file size in KB:=61440
[Thread-48] [ 2016-03-18 13:42:57.419 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:42:57.419 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:42:57.483 UTC ] [CloneDBCreationStep.executeImpl:664] Establish USERS as the default permanent tablespace of the database
[Thread-48] [ 2016-03-18 13:42:57.486 UTC ] [CloneDBCreationStep.executeImpl:671] Resetting SYS and SYSTEM passwords
[Thread-48] [ 2016-03-18 13:42:57.514 UTC ] [CloneDBCreationStep.executeImpl:676] SYS reset done
[Thread-48] [ 2016-03-18 13:42:57.652 UTC ] [CloneDBCreationStep.executeImpl:685] SYSTEM reset done
[Thread-48] [ 2016-03-18 13:42:57.652 UTC ] [CloneDBCreationStep.executeImpl:865] if /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates//pdbseed.dfb file exist : true
[Thread-48] [ 2016-03-18 13:42:57.652 UTC ] [CloneDBCreationStep.executeImpl:867] pdbseed backup exist
[Thread-48] [ 2016-03-18 13:42:57.652 UTC ] [CloneDBCreationStep.executeImpl:922] Loading metadata file /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates//pdbseed.xml
[Thread-48] [ 2016-03-18 13:42:57.653 UTC ] [TarUtil.loadMetaDataInfo:452] attributeList.size() 3
[Thread-48] [ 2016-03-18 13:42:57.654 UTC ] [CloneDBCreationStep.executeImpl:939] Creating PDB$SEED
[Thread-48] [ 2016-03-18 13:42:57.655 UTC ] [BasicStep.execute:250] Executing Step : PLUG_DATABASE
[Thread-48] [ 2016-03-18 13:42:57.655 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-65107
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-15173
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-19505
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-65104
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01119
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-65011
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-65012
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-65027
[Thread-48] [ 2016-03-18 13:42:57.656 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-00604
[Thread-48] [ 2016-03-18 13:42:57.657 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-65026
[Thread-48] [ 2016-03-18 13:42:57.657 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-65005
[Thread-48] [ 2016-03-18 13:42:57.657 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-01034
[Thread-48] [ 2016-03-18 13:42:57.657 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-03114
[Thread-48] [ 2016-03-18 13:42:57.657 UTC ] [StepErrorHandler.setFatalErrors:323] setting Fatal Error: ORA-12560
[Thread-48] [ 2016-03-18 13:42:57.657 UTC ] [BasicStep.configureSettings:353] messageHandler being set=null
[Thread-48] [ 2016-03-18 13:42:57.746 UTC ] [SQLEngine.spoolOff:2124] Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/xe/cloneDBCreation.log
[Thread-48] [ 2016-03-18 13:42:57.746 UTC ] [SQLEngine.setSpool:2056] old Spool = null
[Thread-48] [ 2016-03-18 13:42:57.746 UTC ] [SQLEngine.setSpool:2057] Setting Spool = /u01/app/oracle/cfgtoollogs/dbca/xe/plugDatabase.log
[Thread-48] [ 2016-03-18 13:42:57.747 UTC ] [SQLEngine.setSpool:2058] Is spool appendable? --> true
[Thread-48] [ 2016-03-18 13:42:57.752 UTC ] [SQLEngine.spoolOff:2124] Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/xe/plugDatabase.log
[Thread-48] [ 2016-03-18 13:42:57.752 UTC ] [SQLEngine.setSpool:2056] old Spool = null
[Thread-48] [ 2016-03-18 13:42:57.752 UTC ] [SQLEngine.setSpool:2057] Setting Spool = /u01/app/oracle/cfgtoollogs/dbca/xe/plugDatabase.log
[Thread-48] [ 2016-03-18 13:42:57.752 UTC ] [SQLEngine.setSpool:2058] Is spool appendable? --> true
[Thread-48] [ 2016-03-18 13:42:57.752 UTC ] [PlugDatabaseStep.intializeSQLEngineAndStartCDB:276] update db alert log File
[Thread-48] [ 2016-03-18 13:42:57.754 UTC ] [RestorationFactory.getRestorer:51] Performing Rman restore
[Thread-48] [ 2016-03-18 13:42:57.755 UTC ] [Host.getRMANConnectionInfo:1171] Connect to Instance xe
[Thread-48] [ 2016-03-18 13:42:57.755 UTC ] [RmanFileRestorer.restoreFiles:110] destFolder /u01/app/oracle/oradata/xe/pdbseed
[Thread-48] [ 2016-03-18 13:42:57.755 UTC ] [RmanFileRestorer.restoreFiles:112] destFolder /u01/app/oracle/oradata/xe/pdbseed
[Thread-48] [ 2016-03-18 13:42:57.756 UTC ] [RmanFileRestorer.restoreFiles:157] Going to create common directory=/u01/app/oracle/oradata/xe/pdbseed
[Thread-48] [ 2016-03-18 13:42:57.756 UTC ] [RmanFileRestorer.restoreFiles:165] compressedFileName /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/pdbseed.dfb
[Thread-48] [ 2016-03-18 13:42:57.756 UTC ] [RMANUtil.getRMANUtil:157] using old util instance
[Thread-48] [ 2016-03-18 13:42:57.756 UTC ] [RMANUtil.getRMANUtil:158] target sid old insxe
[Thread-48] [ 2016-03-18 13:42:57.756 UTC ] [RmanFileRestorer.restoreFiles:171] setting _catalog_foreign_restore
[Thread-48] [ 2016-03-18 13:42:57.761 UTC ] [RMANEngine.getCmmdParams:343] using rman from /u01/app/oracle/product/12.1.0/xe/bin/rman
[Thread-48] [ 2016-03-18 13:42:57.762 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:42:57.762 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:42:57.762 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:42:57.766 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:42:57.766 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:42:57.766 UTC ] [RMANEngine.getEnvParams:436] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:42:57.766 UTC ] [RMANEngine.initialize:195] Execing RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:42:57.770 UTC ] [RMANEngine.initialize:221] Created RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:42:57.770 UTC ] [RMANEngine.initialize:235] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:42:57.770 UTC ] [RMANEngine.initialize:239] Started reader thread for RMAN process in initialize...
[Thread-68] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.run:803] Started reader thread for RMAN process from initialize...
[Thread-68] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.run:807] Set m_bReaderStarted to true in initialize...
[Thread-48] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.initialize:247] Started error thread for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.initialize:252] Created output writer for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.initialize:290] Created Log writer for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.initialize:295] Value of m_bReaderStarted in initialize=true
[Thread-48] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.initialize:310] Made sure reader has started for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:42:57.772 UTC ] [RMANEngine.connect:775] NOT using OS auth to connect
[Thread-48] [ 2016-03-18 13:42:57.773 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:42:57.773 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=connect target SYS/******
[Thread-48] [ 2016-03-18 13:42:57.773 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-68] [ 2016-03-18 13:42:57.773 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:42:57.774 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Recovery Manager: Release 12.1.0.2.0 - Production on Fri Mar 18 13:42:57 2016
[Thread-68] [ 2016-03-18 13:42:57.774 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:42:57.775 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Copyright (c) 1982, 2015, Oracle and/or its affiliates. All rights reserved.
[Thread-68] [ 2016-03-18 13:42:57.839 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:42:57.839 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-68] [ 2016-03-18 13:42:58.377 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=connected to target database: XE (DBID=2787938077)
[Thread-68] [ 2016-03-18 13:42:58.380 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:42:58.393 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-68] [ 2016-03-18 13:42:58.394 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set on
[Thread-68] [ 2016-03-18 13:42:58.403 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=set echo off;
[Thread-68] [ 2016-03-18 13:42:58.403 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false
[Thread-68] [ 2016-03-18 13:42:58.403 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=set echo off;
[Thread-48] [ 2016-03-18 13:42:58.403 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:42:58.403 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=connect target SYS/******
[Thread-48] [ 2016-03-18 13:42:58.403 UTC ] [RMANUtil.getRMANEngine:197] RMANUtil spooling to=/u01/app/oracle/cfgtoollogs/dbca/xe/rmanUtil
[Thread-48] [ 2016-03-18 13:42:58.404 UTC ] [RMANUtil.getPDBDFilesToRestore:1170] id of the datafile name:=2
[Thread-48] [ 2016-03-18 13:42:58.404 UTC ] [RMANUtil.getPDBDFilesToRestore:1183] datafile name:=/oradata/seeddata/pdbseed/system01.dbf
[Thread-48] [ 2016-03-18 13:42:58.404 UTC ] [RMANUtil.getPDBDFilesToRestore:1188] After replacing vars, datafile name:=/oradata/seeddata/pdbseed/system01.dbf
[Thread-48] [ 2016-03-18 13:42:58.404 UTC ] [RMANUtil.getPDBDFilesToRestore:1170] id of the datafile name:=4
[Thread-48] [ 2016-03-18 13:42:58.404 UTC ] [RMANUtil.getPDBDFilesToRestore:1183] datafile name:=/oradata/seeddata/pdbseed/sysaux01.dbf
[Thread-48] [ 2016-03-18 13:42:58.404 UTC ] [RMANUtil.getPDBDFilesToRestore:1188] After replacing vars, datafile name:=/oradata/seeddata/pdbseed/sysaux01.dbf
[Thread-48] [ 2016-03-18 13:42:58.405 UTC ] [RMANUtil.restorePDBBackUpCommand:1292] datafile List : 2 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf',4 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf'
[Thread-48] [ 2016-03-18 13:42:58.405 UTC ] [RMANUtil.restorePDBBackUpCommand:1298] RMAN command run
{
set command id to 'PDB$SEED';
RESTORE FOREIGN DATAFILE 2 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf',4 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf' FROM BACKUPSET '/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/pdbseed.dfb' ;
}
[Thread-48] [ 2016-03-18 13:42:58.405 UTC ] [RMANUtil.restorePDBBackUp:1321] RMAN command run
{
set command id to 'PDB$SEED';
RESTORE FOREIGN DATAFILE 2 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf',4 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf' FROM BACKUPSET '/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/pdbseed.dfb' ;
}
[Thread-48] [ 2016-03-18 13:42:58.405 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:42:58.405 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=run
{
set command id to 'PDB$SEED';
RESTORE FOREIGN DATAFILE 2 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf',4 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf' FROM BACKUPSET '/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/pdbseed.dfb' ;
}
[Thread-48] [ 2016-03-18 13:42:58.405 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-68] [ 2016-03-18 13:42:58.406 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set off
[Thread-68] [ 2016-03-18 13:42:58.406 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:42:58.411 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN> 2> 3> 4> 5>
[Thread-68] [ 2016-03-18 13:42:58.411 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=executing command: SET COMMAND ID
[Thread-68] [ 2016-03-18 13:42:58.599 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:42:58.604 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Starting restore at 18-MAR-16
[Thread-68] [ 2016-03-18 13:42:58.607 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using target database control file instead of recovery catalog
[Thread-68] [ 2016-03-18 13:42:58.960 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=allocated channel: ORA_DISK_1
[Thread-68] [ 2016-03-18 13:42:58.963 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: SID=253 device type=DISK
[Thread-68] [ 2016-03-18 13:42:59.098 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:42:59.132 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore
[Thread-68] [ 2016-03-18 13:42:59.135 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set
[Thread-68] [ 2016-03-18 13:42:59.135 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring foreign file 00002
[Thread-68] [ 2016-03-18 13:42:59.135 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring foreign file 00004
[Thread-68] [ 2016-03-18 13:42:59.135 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/pdbseed.dfb
[Thread-68] [ 2016-03-18 13:43:00.137 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring foreign file 2 to /u01/app/oracle/oradata/xe/pdbseed/system01.dbf
[Thread-68] [ 2016-03-18 13:43:00.138 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restoring foreign file 4 to /u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf
[Thread-68] [ 2016-03-18 13:43:25.153 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: foreign piece handle=/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/pdbseed.dfb
[Thread-68] [ 2016-03-18 13:43:25.154 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restored backup piece 1
[Thread-68] [ 2016-03-18 13:43:25.156 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:26
[Thread-68] [ 2016-03-18 13:43:25.158 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Finished restore at 18-MAR-16
[Thread-68] [ 2016-03-18 13:43:25.458 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:43:25.478 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-68] [ 2016-03-18 13:43:25.478 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set on
[Thread-68] [ 2016-03-18 13:43:25.491 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=set echo off;
[Thread-68] [ 2016-03-18 13:43:25.491 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false
[Thread-68] [ 2016-03-18 13:43:25.492 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=set echo off;
[Thread-48] [ 2016-03-18 13:43:25.492 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:43:25.492 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=run
{
set command id to 'PDB$SEED';
RESTORE FOREIGN DATAFILE 2 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf',4 FORMAT '/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf' FROM BACKUPSET '/u01/app/oracle/product/12.1.0/xe/assistants/dbca/templates/pdbseed.dfb' ;
}
[Thread-48] [ 2016-03-18 13:43:25.492 UTC ] [RMANUtil.restorePDBBackUp:1324] done with PDB datafile restore
[Thread-48] [ 2016-03-18 13:43:25.492 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:43:25.493 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=exit;
[Thread-48] [ 2016-03-18 13:43:25.493 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-68] [ 2016-03-18 13:43:25.493 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set off
[Thread-68] [ 2016-03-18 13:43:25.493 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:43:25.497 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-68] [ 2016-03-18 13:43:25.498 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-68] [ 2016-03-18 13:43:25.498 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Recovery Manager complete.
[Thread-68] [ 2016-03-18 13:43:25.514 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=falsem_bReaderDone=truem_bFatalErrorOccured=false
[Thread-68] [ 2016-03-18 13:43:25.515 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=null
[Thread-48] [ 2016-03-18 13:43:25.515 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:43:25.516 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=exit;
[Thread-48] [ 2016-03-18 13:43:25.516 UTC ] [RMANEngine.done:1554] Done called
[Thread-48] [ 2016-03-18 13:43:25.516 UTC ] [RMANEngine.spoolOff:1440] Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/xe/rmanUtil
[Thread-48] [ 2016-03-18 13:43:25.537 UTC ] [RmanFileRestorer.getDatafileCopyList:346] adding 4 : /u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf
[Thread-48] [ 2016-03-18 13:43:25.537 UTC ] [RmanFileRestorer.getDatafileCopyList:346] adding 2 : /u01/app/oracle/oradata/xe/pdbseed/system01.dbf
[Thread-48] [ 2016-03-18 13:43:25.538 UTC ] [Host.getRMANConnectionInfo:1171] Connect to Instance xe
[Thread-48] [ 2016-03-18 13:43:25.538 UTC ] [RmanFileRestorer.cleanupDatafileCopies:249] filename to cleanup: '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf','/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf'
[Thread-48] [ 2016-03-18 13:43:25.538 UTC ] [RMANUtil.getRMANUtil:147] creating new util instance
[Thread-48] [ 2016-03-18 13:43:25.538 UTC ] [RMANUtil.getRMANUtil:148] target sid xe
[Thread-48] [ 2016-03-18 13:43:25.538 UTC ] [RMANEngine.getCmmdParams:343] using rman from /u01/app/oracle/product/12.1.0/xe/bin/rman
[Thread-48] [ 2016-03-18 13:43:25.539 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:43:25.539 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:43:25.539 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:43:25.552 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:43:25.553 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:43:25.553 UTC ] [RMANEngine.getEnvParams:436] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:43:25.553 UTC ] [RMANEngine.initialize:195] Execing RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:43:25.555 UTC ] [RMANEngine.initialize:221] Created RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:43:25.555 UTC ] [RMANEngine.initialize:235] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:43:25.557 UTC ] [RMANEngine.initialize:239] Started reader thread for RMAN process in initialize...
[Thread-48] [ 2016-03-18 13:43:25.560 UTC ] [RMANEngine.initialize:247] Started error thread for RMAN process from initialize...
[Thread-72] [ 2016-03-18 13:43:25.560 UTC ] [RMANEngine.run:803] Started reader thread for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:43:25.560 UTC ] [RMANEngine.initialize:252] Created output writer for RMAN process from initialize...
[Thread-72] [ 2016-03-18 13:43:25.560 UTC ] [RMANEngine.run:807] Set m_bReaderStarted to true in initialize...
[Thread-48] [ 2016-03-18 13:43:25.560 UTC ] [RMANEngine.initialize:290] Created Log writer for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:43:25.560 UTC ] [RMANEngine.initialize:295] Value of m_bReaderStarted in initialize=true
[Thread-48] [ 2016-03-18 13:43:25.560 UTC ] [RMANEngine.initialize:310] Made sure reader has started for RMAN process from initialize...
[Thread-48] [ 2016-03-18 13:43:25.561 UTC ] [RMANEngine.connect:775] NOT using OS auth to connect
[Thread-48] [ 2016-03-18 13:43:25.561 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:43:25.561 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=connect target SYS/******
[Thread-48] [ 2016-03-18 13:43:25.561 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-72] [ 2016-03-18 13:43:25.583 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:25.583 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Recovery Manager: Release 12.1.0.2.0 - Production on Fri Mar 18 13:43:25 2016
[Thread-72] [ 2016-03-18 13:43:25.583 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:25.583 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Copyright (c) 1982, 2015, Oracle and/or its affiliates. All rights reserved.
[Thread-72] [ 2016-03-18 13:43:25.659 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:25.659 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-72] [ 2016-03-18 13:43:26.252 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=connected to target database: XE (DBID=2787938077)
[Thread-72] [ 2016-03-18 13:43:26.253 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:26.257 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-72] [ 2016-03-18 13:43:26.258 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set on
[Thread-72] [ 2016-03-18 13:43:26.264 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=set echo off;
[Thread-72] [ 2016-03-18 13:43:26.265 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false
[Thread-72] [ 2016-03-18 13:43:26.265 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=set echo off;
[Thread-48] [ 2016-03-18 13:43:26.265 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:43:26.265 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=connect target SYS/******
[Thread-48] [ 2016-03-18 13:43:26.265 UTC ] [RMANUtil.getRMANEngine:197] RMANUtil spooling to=/u01/app/oracle/cfgtoollogs/dbca/xe/rmanUtil
[Thread-48] [ 2016-03-18 13:43:26.265 UTC ] [RMANUtil.cleanUpDataFieCopy:1352] RMAN command change datafilecopy '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf','/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf' uncatalog
[Thread-48] [ 2016-03-18 13:43:26.265 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:43:26.266 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=change datafilecopy '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf','/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf' uncatalog;
[Thread-48] [ 2016-03-18 13:43:26.266 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-72] [ 2016-03-18 13:43:26.266 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set off
[Thread-72] [ 2016-03-18 13:43:26.266 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:26.270 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-72] [ 2016-03-18 13:43:26.440 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=using target database control file instead of recovery catalog
[Thread-72] [ 2016-03-18 13:43:27.061 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=uncataloged datafile copy
[Thread-72] [ 2016-03-18 13:43:27.064 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=datafile copy file name=/u01/app/oracle/oradata/xe/pdbseed/system01.dbf RECID=1 STAMP=906817393
[Thread-72] [ 2016-03-18 13:43:27.069 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=uncataloged datafile copy
[Thread-72] [ 2016-03-18 13:43:27.069 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=datafile copy file name=/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf RECID=2 STAMP=906817398
[Thread-72] [ 2016-03-18 13:43:27.071 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Uncataloged 2 objects
[Thread-72] [ 2016-03-18 13:43:27.071 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:27.297 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:27.304 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-72] [ 2016-03-18 13:43:27.305 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set on
[Thread-72] [ 2016-03-18 13:43:27.313 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=set echo off;
[Thread-72] [ 2016-03-18 13:43:27.313 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false
[Thread-72] [ 2016-03-18 13:43:27.313 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=set echo off;
[Thread-48] [ 2016-03-18 13:43:27.314 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:43:27.314 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=change datafilecopy '/u01/app/oracle/oradata/xe/pdbseed/system01.dbf','/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf' uncatalog;
[Thread-48] [ 2016-03-18 13:43:27.314 UTC ] [RMANUtil.cleanUpDataFieCopy:1355] done with PDB datafile restore
[Thread-48] [ 2016-03-18 13:43:27.314 UTC ] [RMANEngine.executeImpl:1186] m_bExecQuery=false
[Thread-48] [ 2016-03-18 13:43:27.314 UTC ] [RMANEngine.executeImpl:1194] Command being written to rman process=exit;
[Thread-48] [ 2016-03-18 13:43:27.314 UTC ] [RMANEngine.executeImpl:1222] Notify reader to start reading
[Thread-72] [ 2016-03-18 13:43:27.315 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=echo set off
[Thread-72] [ 2016-03-18 13:43:27.315 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:27.318 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=RMAN>
[Thread-72] [ 2016-03-18 13:43:27.318 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=
[Thread-72] [ 2016-03-18 13:43:27.318 UTC ] [RMANEngine.readSqlOutput:889] Log RMAN Output=Recovery Manager complete.
[Thread-72] [ 2016-03-18 13:43:27.329 UTC ] [RMANEngine.readSqlOutput:1033] Notifying writer to proceed because m_bInterrupted=falsebNotify=falsem_bReaderDone=truem_bFatalErrorOccured=false
[Thread-72] [ 2016-03-18 13:43:27.329 UTC ] [RMANEngine.run:817] Return Value from readSqlOutput=null
[Thread-48] [ 2016-03-18 13:43:27.329 UTC ] [RMANEngine.executeImpl:1234] Completed wait from reader
[Thread-48] [ 2016-03-18 13:43:27.330 UTC ] [RMANEngine.executeImpl:1281] DONE EXECUTING GIVEN COMMAND=exit;
[Thread-48] [ 2016-03-18 13:43:27.330 UTC ] [RMANEngine.done:1554] Done called
[Thread-48] [ 2016-03-18 13:43:27.330 UTC ] [RMANEngine.spoolOff:1440] Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/xe/rmanUtil
[Thread-48] [ 2016-03-18 13:43:27.330 UTC ] [RmanFileRestorer.restoreFiles:217] reset _catalog_foreign_restore
[Thread-48] [ 2016-03-18 13:43:27.335 UTC ] [OsUtilsBase.convertPathWithOSSeparator:3188] convert Path /u01/app/oracle/oradata/xe/pdbseed/pdbseed_temp012016-03-18_01-43-27-PM.dbf
[Thread-48] [ 2016-03-18 13:43:27.335 UTC ] [OsUtilsBase.convertPathWithOSSeparator:3224] converted Path /u01/app/oracle/oradata/xe/pdbseed/pdbseed_temp012016-03-18_01-43-27-PM.dbf
[Thread-48] [ 2016-03-18 13:43:27.335 UTC ] [PluggableDatabaseSQLGenerator.getSFNConvertForRmanBackup:558] sourcefileNameConvert : source_file_name_convert = ('/oradata/seeddata/pdbseed/temp01.dbf','/u01/app/oracle/oradata/xe/pdbseed/pdbseed_temp012016-03-18_01-43-27-PM.dbf',
'/oradata/seeddata/pdbseed/system01.dbf','/u01/app/oracle/oradata/xe/pdbseed/system01.dbf',
'/oradata/seeddata/pdbseed/sysaux01.dbf','/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf')
[Thread-48] [ 2016-03-18 13:43:27.335 UTC ] [PluggableDatabaseSQLGenerator.getDefinitionText:118] convertClause= source_file_name_convert = ('/oradata/seeddata/pdbseed/temp01.dbf','/u01/app/oracle/oradata/xe/pdbseed/pdbseed_temp012016-03-18_01-43-27-PM.dbf',
'/oradata/seeddata/pdbseed/system01.dbf','/u01/app/oracle/oradata/xe/pdbseed/system01.dbf',
'/oradata/seeddata/pdbseed/sysaux01.dbf','/u01/app/oracle/oradata/xe/pdbseed/sysaux01.dbf') NOCOPY
[Thread-48] [ 2016-03-18 13:43:28.240 UTC ] [PlugDatabaseStep.executeImpl:202] Done with Create PDB
[Thread-48] [ 2016-03-18 13:43:28.241 UTC ] [PlugDatabaseStep.openPDB:298] Opening PDB$SEED
[Thread-48] [ 2016-03-18 13:43:29.113 UTC ] [BasicStep.configureSettings:353] messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@40974600
[Thread-48] [ 2016-03-18 13:43:52.662 UTC ] [CloneDBCreationStep.executeImpl:966] Altering charactersets
[Thread-48] [ 2016-03-18 13:45:43.062 UTC ] [CloneDBCreationStep.executeImpl:991] current NCHAR Set: AL16UTF16
[Thread-48] [ 2016-03-18 13:45:43.062 UTC ] [CloneDBCreationStep.executeImpl:1002] Reset Original NLS_LANG : null
[Thread-48] [ 2016-03-18 13:45:43.062 UTC ] [SQLEngine.done:2278] Done called
[Thread-48] [ 2016-03-18 13:45:43.063 UTC ] [SQLEngine.spoolOff:2124] Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/xe/plugDatabase.log
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [SQLEngine.reInitialize:785] Reinitializing SQLEngine...
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [SQLPlusEngine.getCmmdParams:225] m_home 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:45:43.064 UTC ] [SQLPlusEngine.getCmmdParams:226] version > 112 true
[Thread-48] [ 2016-03-18 13:45:43.065 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:45:43.065 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:45:43.065 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:45:43.073 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:45:43.073 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:45:43.073 UTC ] [SQLEngine.getEnvParams:602] Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:45:43.073 UTC ] [SQLEngine.getEnvParams:612] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:45:43.074 UTC ] [SQLEngine.initialize:358] Execing SQLPLUS/SVRMGR process...
[Thread-48] [ 2016-03-18 13:45:43.081 UTC ] [SQLEngine.initialize:395] m_bReaderStarted: false
[Thread-48] [ 2016-03-18 13:45:43.082 UTC ] [SQLEngine.initialize:399] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:45:43.082 UTC ] [SQLEngine.initialize:448] Waiting for m_bReaderStarted to be true
[Thread-48] [ 2016-03-18 13:45:43.252 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/cfgtoollogs/dbca/xe/initxeTemp.ora
[Thread-48] [ 2016-03-18 13:47:01.597 UTC ] [CloneDBCreationStep.executeImpl:1065] call post create scripts
[Thread-48] [ 2016-03-18 13:47:01.600 UTC ] [BasicStep.execute:250] Executing Step : CLONE_POST_CREATE_SCRIPTS
[Thread-48] [ 2016-03-18 13:47:01.600 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-00942
[Thread-48] [ 2016-03-18 13:47:01.600 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-06512
[Thread-48] [ 2016-03-18 13:47:01.600 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-20000
[Thread-48] [ 2016-03-18 13:47:01.600 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-01952
[Thread-48] [ 2016-03-18 13:47:01.600 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-01927
[Thread-48] [ 2016-03-18 13:47:01.601 UTC ] [StepErrorHandler.setIgnorableErrors:251] setting Ignorable Error: ORA-02024
[Thread-48] [ 2016-03-18 13:47:01.601 UTC ] [BasicStep.configureSettings:353] messageHandler being set=null
[Thread-48] [ 2016-03-18 13:47:01.649 UTC ] [SQLEngine.setSpool:2056] old Spool = null
[Thread-48] [ 2016-03-18 13:47:01.649 UTC ] [SQLEngine.setSpool:2057] Setting Spool = /u01/app/oracle/cfgtoollogs/dbca/xe/postScripts.log
[Thread-48] [ 2016-03-18 13:47:01.649 UTC ] [SQLEngine.setSpool:2058] Is spool appendable? --> true
[Thread-48] [ 2016-03-18 13:47:01.649 UTC ] [ClonePostCreateScripts.executeImpl:187] Clearing all last login times
[Thread-48] [ 2016-03-18 13:47:01.660 UTC ] [PerlEngine.setSpool:1159] old Spool = null
[Thread-48] [ 2016-03-18 13:47:01.660 UTC ] [PerlEngine.setSpool:1160] Setting Spool = /u01/app/oracle/cfgtoollogs/dbca/xe/postScripts.log
[Thread-48] [ 2016-03-18 13:47:01.660 UTC ] [PerlEngine.setSpool:1161] Is spool appendable? --> true
[Thread-48] [ 2016-03-18 13:47:01.660 UTC ] [ClonePostCreateScripts.executeImpl:209] before dbmssml.sql using catcon
[Thread-48] [ 2016-03-18 13:47:01.660 UTC ] [BasicStep.executeScriptUsingPerlEngine:775] executing : [/u01/app/oracle/product/12.1.0/xe/rdbms/admin/catcon.pl, -n, 1, -l, /u01/app/oracle/cfgtoollogs/dbca/xe, -G, -b, dbmssml, -u, SYS, -U, SYS, /u01/app/oracle/product/12.1.0/xe/rdbms/admin/dbmssml.sql]; secretArgs: 2
[Thread-48] [ 2016-03-18 13:47:01.661 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:47:01.661 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:47:01.661 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:47:01.665 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:01.665 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:01.667 UTC ] [PerlEngine.execute:208] /u01/app/oracle/product/12.1.0/xe/perl/bin/perl
[Thread-48] [ 2016-03-18 13:47:01.667 UTC ] [PerlEngine.execute:208] /u01/app/oracle/product/12.1.0/xe/rdbms/admin/catcon.pl
[Thread-48] [ 2016-03-18 13:47:01.667 UTC ] [PerlEngine.execute:208] -n
[Thread-48] [ 2016-03-18 13:47:01.667 UTC ] [PerlEngine.execute:208] 1
[Thread-48] [ 2016-03-18 13:47:01.667 UTC ] [PerlEngine.execute:208] -l
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] /u01/app/oracle/cfgtoollogs/dbca/xe
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] -G
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] -b
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] dbmssml
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] -u
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] SYS
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] -U
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] SYS
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:208] /u01/app/oracle/product/12.1.0/xe/rdbms/admin/dbmssml.sql
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:210] ORACLE_SID=xe
[Thread-48] [ 2016-03-18 13:47:01.668 UTC ] [PerlEngine.execute:210] ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] PATH=/u01/app/oracle/product/12.1.0/xe/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] ORACLE_BASE=/u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] PERL5LIB=/u01/app/oracle/product/12.1.0/xe/rdbms/admin
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] SHELL=/bin/bash
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] SHLVL=2
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] XFILESEARCHPATH=/usr/dt/app-defaults/%L/Dt
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] DEBIAN_FRONTEND=noninteractive
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] MAIL=/var/mail/oracle
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] USER=oracle
[Thread-48] [ 2016-03-18 13:47:01.669 UTC ] [PerlEngine.execute:210] HOSTNAME=48748797223a
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] PWD=/
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] WEB_CONSOLE=true
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] HOME=/home/oracle
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] LOGNAME=oracle
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] _=/u01/app/oracle/product/12.1.0/xe/bin/dbca
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] NLSPATH=/usr/dt/lib/nls/msg/%L/%N.cat
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] DBCA_TOTAL_MEMORY=512
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:210] LD_LIBRARY_PATH=/u01/app/oracle-product/12.1.0/xe/jdk/jre/lib/amd64/server:/u01/app/oracle-product/12.1.0/xe/jdk/jre/lib/amd64:/u01/app/oracle-product/12.1.0/xe/jdk/jre/../lib/amd64:/u01/app/oracle/product/12.1.0/xe/lib:/u01/app/oracle/product/12.1.0/xe/network/lib:
[Thread-48] [ 2016-03-18 13:47:01.670 UTC ] [PerlEngine.execute:215] Execing PERL process...
[Thread-48] [ 2016-03-18 13:47:01.676 UTC ] [PerlEngine.execute:253] m_bReaderStarted: false
[Thread-48] [ 2016-03-18 13:47:01.677 UTC ] [PerlEngine.execute:257] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:47:01.679 UTC ] [PerlEngine.execute:310] Inputing secret arg: 0
[Thread-48] [ 2016-03-18 13:47:01.679 UTC ] [PerlEngine.execute:310] Inputing secret arg: 1
[Thread-48] [ 2016-03-18 13:47:01.906 UTC ] [PerlEngine.execute:353] Exit value = 139
[Thread-48] [ 2016-03-18 13:47:01.906 UTC ] [PerlEngine.execute:354] status is = 139
[Thread-48] [ 2016-03-18 13:47:01.907 UTC ] [ClonePostCreateScripts.executeImpl:490] Error while executing "/u01/app/oracle/product/12.1.0/xe/rdbms/admin/dbmssml.sql". Refer to "/u01/app/oracle/cfgtoollogs/dbca/xe/dbmssml0.log" for more details. Error in Process: /u01/app/oracle/product/12.1.0/xe/perl/bin/perl
oracle.sysman.assistants.util.step.BasicStep.executeScriptUsingPerlEngine(BasicStep.java:791)
oracle.sysman.assistants.util.step.BasicStep.executeScriptUsingPerlEngine(BasicStep.java:630)
oracle.sysman.assistants.dbca.backend.ClonePostCreateScripts.executeImpl(ClonePostCreateScripts.java:210)
oracle.sysman.assistants.util.step.BasicStep.execute(BasicStep.java:259)
oracle.sysman.assistants.util.step.BasicStep.callStep(BasicStep.java:300)
oracle.sysman.assistants.dbca.backend.CloneDBCreationStep.executeImpl(CloneDBCreationStep.java:1068)
oracle.sysman.assistants.util.step.BasicStep.execute(BasicStep.java:259)
oracle.sysman.assistants.util.step.Step.execute(Step.java:135)
oracle.sysman.assistants.util.step.StepContext$ModeRunner.run(StepContext.java:2883)
java.lang.Thread.run(Thread.java:682)
[Thread-48] [ 2016-03-18 13:47:01.907 UTC ] [BasicStep.configureSettings:353] messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@40974600
[Thread-48] [ 2016-03-18 13:47:01.907 UTC ] [BasicStep.configureSettings:353] messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@40974600
[Thread-48] [ 2016-03-18 13:47:01.907 UTC ] [StepContext$ModeRunner.run:2899] Error while executing "/u01/app/oracle/product/12.1.0/xe/rdbms/admin/dbmssml.sql". Refer to "/u01/app/oracle/cfgtoollogs/dbca/xe/dbmssml0.log" for more details. Error in Process: /u01/app/oracle/product/12.1.0/xe/perl/bin/perl
oracle.sysman.assistants.dbca.backend.ClonePostCreateScripts.executeImpl(ClonePostCreateScripts.java:492)
oracle.sysman.assistants.util.step.BasicStep.execute(BasicStep.java:259)
oracle.sysman.assistants.util.step.BasicStep.callStep(BasicStep.java:300)
oracle.sysman.assistants.dbca.backend.CloneDBCreationStep.executeImpl(CloneDBCreationStep.java:1068)
oracle.sysman.assistants.util.step.BasicStep.execute(BasicStep.java:259)
oracle.sysman.assistants.util.step.Step.execute(Step.java:135)
oracle.sysman.assistants.util.step.StepContext$ModeRunner.run(StepContext.java:2883)
java.lang.Thread.run(Thread.java:682)
[Thread-48] [ 2016-03-18 13:47:01.907 UTC ] [SQLEngine.done:2278] Done called
[Thread-48] [ 2016-03-18 13:47:01.909 UTC ] [SQLEngine.spoolOff:2124] Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/xe/postScripts.log
[Thread-48] [ 2016-03-18 13:47:01.914 UTC ] [Host.createStepSQLInterface:8605] sid =xe
[Thread-48] [ 2016-03-18 13:47:01.914 UTC ] [Host.createStepSQLInterface:8606] sidOrConnectString xe
[Thread-48] [ 2016-03-18 13:47:01.914 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: null
[Thread-48] [ 2016-03-18 13:47:01.915 UTC ] [InventoryUtil.getOUIInvSession:349] setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[Thread-48] [ 2016-03-18 13:47:01.915 UTC ] [OracleHome.getVersion:1010] Homeinfo /u01/app/oracle/product/12.1.0/xe,1
[Thread-48] [ 2016-03-18 13:47:01.979 UTC ] [OracleHome.getVersion:1038] OracleHome.server.getVersion Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:01.979 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:01.979 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:01.979 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:01.979 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:01.979 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:01.979 UTC ] [SQLPlusEngine.getCmmdParams:225] m_home 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:01.980 UTC ] [SQLPlusEngine.getCmmdParams:226] version > 112 true
[Thread-48] [ 2016-03-18 13:47:01.980 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:47:01.980 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:47:01.980 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:47:01.990 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:01.990 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:01.991 UTC ] [SQLEngine.getEnvParams:602] Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:47:01.991 UTC ] [SQLEngine.getEnvParams:612] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:47:01.991 UTC ] [SQLEngine.initialize:358] Execing SQLPLUS/SVRMGR process...
[Thread-48] [ 2016-03-18 13:47:01.994 UTC ] [SQLEngine.initialize:395] m_bReaderStarted: false
[Thread-48] [ 2016-03-18 13:47:01.994 UTC ] [SQLEngine.initialize:399] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:47:02.006 UTC ] [CloneRmanRestoreStep.cancel:722] Cleaning up partially extracted files failed
[Thread-48] [ 2016-03-18 13:47:02.161 UTC ] [SQLEngine.done:2278] Done called
[Thread-48] [ 2016-03-18 13:47:02.162 UTC ] [CloneRmanRestoreStep.cancel:737] RMAN cleanup failed
[Thread-48] [ 2016-03-18 13:47:02.162 UTC ] [CloneRmanRestoreStep.cancel:738] ORA-06502: PL/SQL: numeric or value error
ORA-06512: at "SYS.DBMS_BACKUP_RESTORE", line 3251
ORA-06512: at line 17
oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeImpl(SQLEngine.java:1739)
oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeScript(SQLEngine.java:1061)
oracle.sysman.assistants.util.sqlEngine.SQLPlusEngine.executeScript(SQLPlusEngine.java:342)
oracle.sysman.assistants.dbca.backend.CloneRmanRestoreStep.cancel(CloneRmanRestoreStep.java:733)
oracle.sysman.assistants.util.step.StepContext$ModeRunner.cancel(StepContext.java:2979)
oracle.sysman.assistants.util.step.StepContext$ModeRunner.run(StepContext.java:2940)
java.lang.Thread.run(Thread.java:682)
[Thread-48] [ 2016-03-18 13:47:02.162 UTC ] [CloneDBCreationStep.cancel:1178] CloneDBCreationStep.cancel(): nodeList= null
[Thread-48] [ 2016-03-18 13:47:02.162 UTC ] [SQLEngine.reInitialize:785] Reinitializing SQLEngine...
[Thread-48] [ 2016-03-18 13:47:02.162 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:02.162 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:02.162 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [OracleHome.getVersion:991] OracleHome.getVersion called. Current Version: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [OracleHome.getVersion:1059] Current Version From Inventory: 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [SQLPlusEngine.getCmmdParams:225] m_home 12.1.0.2.0
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [SQLPlusEngine.getCmmdParams:226] version > 112 true
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[Thread-48] [ 2016-03-18 13:47:02.163 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[Thread-48] [ 2016-03-18 13:47:02.170 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:02.170 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:02.170 UTC ] [SQLEngine.getEnvParams:602] Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:47:02.170 UTC ] [SQLEngine.getEnvParams:612] NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-48] [ 2016-03-18 13:47:02.170 UTC ] [SQLEngine.initialize:358] Execing SQLPLUS/SVRMGR process...
[Thread-48] [ 2016-03-18 13:47:02.174 UTC ] [SQLEngine.initialize:395] m_bReaderStarted: false
[Thread-48] [ 2016-03-18 13:47:02.174 UTC ] [SQLEngine.initialize:399] Starting Reader Thread...
[Thread-48] [ 2016-03-18 13:47:02.174 UTC ] [SQLEngine.initialize:448] Waiting for m_bReaderStarted to be true
[Thread-48] [ 2016-03-18 13:47:06.294 UTC ] [Oratab.getTempFile:805] Temp oratab: /tmp/oratab
[Thread-48] [ 2016-03-18 13:47:06.295 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/product/12.1.0/xe/dbs/orapwxe
[Thread-48] [ 2016-03-18 13:47:06.295 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/product/12.1.0/xe/dbs/initxe.ora
[Thread-48] [ 2016-03-18 13:47:06.295 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/admin/xe/pfile/init.ora
[Thread-48] [ 2016-03-18 13:47:06.295 UTC ] [CloneDBCreationStep.cancel:1225] CloneDBCreationStep.cancel(): bRaw=false
[Thread-48] [ 2016-03-18 13:47:06.295 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/sysaux01.dbf
[Thread-48] [ 2016-03-18 13:47:06.295 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/system01.dbf
[Thread-48] [ 2016-03-18 13:47:06.295 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/undotbs01.dbf
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/users01.dbf
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/redo01.log
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/redo02.log
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/redo03.log
[Thread-48] [ 2016-03-18 13:47:06.296 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:06.297 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:47:06.297 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/oradata/xe/control01.ctl
[Thread-48] [ 2016-03-18 13:47:06.297 UTC ] [CommonAttributes.replaceVariables:1402] var2 /u01/app/oracle
[Thread-48] [ 2016-03-18 13:47:06.297 UTC ] [CommonAttributes.replaceVariables:1402] var2 xe
[Thread-48] [ 2016-03-18 13:47:06.297 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/fast_recovery_area/xe/control02.ctl
[Thread-48] [ 2016-03-18 13:47:06.303 UTC ] [SQLEngine.done:2278] Done called
[main] [ 2016-03-18 13:47:06.307 UTC ] [Host.cleanup:3928] Dbca exit status is: 1
[main] [ 2016-03-18 13:47:06.307 UTC ] [Host.cleanup:3931] check point context oracle.sysman.assistants.util.CheckpointContext@58ee21f5
[main] [ 2016-03-18 13:47:06.308 UTC ] [InventoryUtil.getOUIInvSession:349] setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-03-18 13:47:06.308 UTC ] [InventoryUtil.getHomeName:113] homeName = OraDB12Home1
[main] [ 2016-03-18 13:47:06.308 UTC ] [OsUtilsBase.getBaseFromOrabase:659] oraBaseUtility /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:47:06.309 UTC ] [OsUtilsBase.getBaseFromOrabase:668] cmds: /u01/app/oracle/product/12.1.0/xe/bin/orabase
[main] [ 2016-03-18 13:47:06.309 UTC ] [OsUtilsBase.getBaseFromOrabase:672] envs: ORACLE_HOME=/u01/app/oracle/product/12.1.0/xe
[main] [ 2016-03-18 13:47:06.312 UTC ] [OsUtilsBase.getBaseFromOrabase:682] baseLocation from orabase
/u01/app/oracle
[main] [ 2016-03-18 13:47:06.312 UTC ] [OsUtilsBase.getBaseFromOrabase:707] orabaseLocation= /u01/app/oracle
[main] [ 2016-03-18 13:47:06.313 UTC ] [Host.cleanup:3957] check point oracle.sysman.oic.oics.OicsCheckPoint@5796de29
[main] [ 2016-03-18 13:47:06.333 UTC ] [OsUtilsBase.copyFile:1505] OsUtilsBase.copyFile:
[main] [ 2016-03-18 13:47:06.333 UTC ] [OsUtilsBase.copyFile:1553] **write of file at destination complete...
[main] [ 2016-03-18 13:47:06.333 UTC ] [OsUtilsBase.copyFile:1588] **file copy status:= true
[main] [ 2016-03-18 13:47:06.333 UTC ] [OsUtilsBase.deleteFile:1803] OsUtilsBase.deleteFile: /u01/app/oracle/cfgtoollogs/dbca/silent.log_2016-03-18_01-40-47-PM
[Thread-15] [ 2016-03-18 13:47:06.335 UTC ] [DbcaCleanupHook.run:44] Cleanup started
[Thread-15] [ 2016-03-18 13:47:06.335 UTC ] [OracleHome.cleanupDBOptionsIntance:1671] DB Options dummy instance sid=null
[Thread-15] [ 2016-03-18 13:47:06.336 UTC ] [DbcaCleanupHook.run:49] Cleanup ended
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.