trace.log

[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