[main] [ 2016-02-29 12:03:19.188 UTC ] [Host.<init>:1090]  Begin tracing..
[main] [ 2016-02-29 12:03:19.266 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:19.266 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:19.266 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:19.281 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:19.282 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:19.282 UTC ] [SilentHost.initialize:187]  SilentHost-> initialize: m_tempLogFile = /u01/app/oracle/cfgtoollogs/dbca/silent.log_2016-02-29_12-03-19-PM
[main] [ 2016-02-29 12:03:19.303 UTC ] [Host.checkIfBigClusterAndHubNode:1710]  Not a cluster environment: exiting BigCluster Check
[main] [ 2016-02-29 12:03:19.303 UTC ] [InventoryUtil.getOracleBase:149]  Getting ORACLE_BASE from inventory.
[main] [ 2016-02-29 12:03:19.305 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-02-29 12:03:19.305 UTC ] [InventoryUtil.getOracleBase:164]  Inventory Home Size = 1
[main] [ 2016-02-29 12:03:19.305 UTC ] [InventoryUtil.getOracleBase:189]  ORACLE_BASE returned from inventory:= /u01/app/oracle
[main] [ 2016-02-29 12:03:19.305 UTC ] [Host.displayOracleBaseWarning:1750]  oracleBaseFromEnv null
[main] [ 2016-02-29 12:03:19.306 UTC ] [Host.displayOracleBaseWarning:1756]  oracle_base from OUI  /u01/app/oracle oracle_base from envnull
[main] [ 2016-02-29 12:03:19.306 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-02-29 12:03:19.307 UTC ] [InventoryUtil.isCRSHome:386]  Homeinfo /u01/app/oracle/12.1.0.2/home1,1
[main] [ 2016-02-29 12:03:19.485 UTC ] [Host.validateGridHome:3878]  Validation false
[main] [ 2016-02-29 12:03:19.485 UTC ] [Host.startOperation:2395]  Source db null
[main] [ 2016-02-29 12:03:19.486 UTC ] [Host.startOperation:2396]  GDB Name orcl
[main] [ 2016-02-29 12:03:19.496 UTC ] [Host.startOperation:2397]  MgmtDB sid -MGMTDB
[main] [ 2016-02-29 12:03:19.496 UTC ] [Host.startOperation:2398]  MgmtDB name _mgmtdb
[main] [ 2016-02-29 12:03:19.496 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[main] [ 2016-02-29 12:03:19.500 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: null
[main] [ 2016-02-29 12:03:19.501 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-02-29 12:03:19.501 UTC ] [OracleHome.getVersion:1010]  Homeinfo /u01/app/oracle/12.1.0.2/home1,1
[main] [ 2016-02-29 12:03:19.586 UTC ] [OracleHome.getVersion:1038]  OracleHome.server.getVersion Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.586 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.586 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.586 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.587 UTC ] [CommonUtils.createPasswordFile:1243]  calling new orapwd for 11.1 or higher
[main] [ 2016-02-29 12:03:19.588 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.588 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.588 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.588 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.588 UTC ] [CommonUtils.getPasswordFileCreateCmd:1182]  for new orapwd for 11.1 or higher
[main] [ 2016-02-29 12:03:19.591 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.591 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.591 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.591 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.591 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  /u01/app/oracle/12.1.0.2/home1/bin/orapwd
[main] [ 2016-02-29 12:03:19.592 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  file=/u01/app/oracle/12.1.0.2/home1/dbs/orapwDBUA0319497
[main] [ 2016-02-29 12:03:19.592 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  force=y
[main] [ 2016-02-29 12:03:19.592 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  format=12
[main] [ 2016-02-29 12:03:19.592 UTC ] [OsUtilsBase.execProg:2123]  beginning execProg with input array.
[main] [ 2016-02-29 12:03:19.619 UTC ] [OsUtilsBase.execProg:2160]  finished execProg with input array. Status:0
[main] [ 2016-02-29 12:03:19.619 UTC ] [OracleHome.initOptionsStopOnError:1356]  Initializing Database Options with  for dummy sid=DBUA0319497 using initfile=/u01/app/oracle/12.1.0.2/home1/dbs/initDBUA0319497.ora using pwdfile=/u01/app/oracle/12.1.0.2/home1/dbs/orapwDBUA0319497
[main] [ 2016-02-29 12:03:19.621 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.622 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.622 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.622 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.622 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.622 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.622 UTC ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.622 UTC ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true
[main] [ 2016-02-29 12:03:19.623 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:19.623 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:19.623 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:19.625 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:19.625 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:19.625 UTC ] [SQLEngine.getEnvParams:602]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-02-29 12:03:19.625 UTC ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-02-29 12:03:19.627 UTC ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...
[main] [ 2016-02-29 12:03:19.637 UTC ] [SQLEngine.initialize:395]  m_bReaderStarted: false
[main] [ 2016-02-29 12:03:19.637 UTC ] [SQLEngine.initialize:399]  Starting Reader Thread... 
[main] [ 2016-02-29 12:03:19.755 UTC ] [SQLEngine.done:2278]  Done called
[main] [ 2016-02-29 12:03:19.755 UTC ] [OracleHome.initOptionsStopOnError:1409]  ORA-12547: TNS:lost contact

[main] [ 2016-02-29 12:03:19.755 UTC ] [OracleHome.initOptionsStopOnError:1410]  ORA-12547: TNS:lost contact

oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeImpl(SQLEngine.java:1739)
oracle.sysman.assistants.util.sqlEngine.SQLEngine.connect(SQLEngine.java:987)
oracle.sysman.assistants.util.OracleHome.initOptionsStopOnError(OracleHome.java:1362)
oracle.sysman.assistants.dbca.backend.Host.startOperation(Host.java:2411)
oracle.sysman.assistants.dbca.Dbca.execute(Dbca.java:153)
oracle.sysman.assistants.dbca.Dbca.main(Dbca.java:226)
[main] [ 2016-02-29 12:03:19.755 UTC ] [OracleHome.initOptionsStopOnError:1419]  shutting down dummy instance
[main] [ 2016-02-29 12:03:19.756 UTC ] [SQLEngine.reInitialize:785]  Reinitializing SQLEngine...
[main] [ 2016-02-29 12:03:19.756 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.756 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.756 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.756 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.756 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.756 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.756 UTC ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0
[main] [ 2016-02-29 12:03:19.757 UTC ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true
[main] [ 2016-02-29 12:03:19.757 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:19.757 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:19.757 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:19.760 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:19.760 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:19.760 UTC ] [SQLEngine.getEnvParams:602]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-02-29 12:03:19.760 UTC ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-02-29 12:03:19.761 UTC ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...
[main] [ 2016-02-29 12:03:19.762 UTC ] [SQLEngine.initialize:395]  m_bReaderStarted: false
[main] [ 2016-02-29 12:03:19.762 UTC ] [SQLEngine.initialize:399]  Starting Reader Thread... 
[main] [ 2016-02-29 12:03:19.769 UTC ] [SQLEngine.initialize:448]  Waiting for m_bReaderStarted to be true 
[main] [ 2016-02-29 12:03:19.792 UTC ] [SQLEngine.done:2278]  Done called
[main] [ 2016-02-29 12:03:19.792 UTC ] [OracleHome.initOptionsStopOnError:1426]  shutting down dummy instance failed 
[main] [ 2016-02-29 12:03:19.792 UTC ] [OracleHome.initOptionsStopOnError:1458]  Database Options queried: 0
[main] [ 2016-02-29 12:03:19.793 UTC ] [Host.checkOPS:2082]  Inside checkOPS
[main] [ 2016-02-29 12:03:19.793 UTC ] [Host.checkOPS:2094]  Trying to check cluster existence
[main] [ 2016-02-29 12:03:19.793 UTC ] [Host.checkOPS:2099]  cluster existence:false
[main] [ 2016-02-29 12:03:19.793 UTC ] [Host.checkOPS:2108]  Cluster Installed false
[main] [ 2016-02-29 12:03:19.793 UTC ] [Host.checkOPS:2124]  Cluster installed=false
[main] [ 2016-02-29 12:03:19.940 UTC ] [TemplateManager.validateTemplate:636]  Validation of template is succussful
[main] [ 2016-02-29 12:03:19.945 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[main] [ 2016-02-29 12:03:19.945 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:19.945 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:19.945 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:19.946 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-02-29 12:03:19.946 UTC ] [StepContext.setListenersToUpdate:946]  needLocalListener = false
[main] [ 2016-02-29 12:03:19.948 UTC ] [OracleHome.hasEELicense:247]  Running script to determine licensing: /u01/app/oracle/12.1.0.2/home1/bin/bndlchk
[main] [ 2016-02-29 12:03:19.967 UTC ] [OracleHome.hasEELicense:258]  home=/u01/app/oracle/12.1.0.2/home1 has EE licensing
[main] [ 2016-02-29 12:03:19.968 UTC ] [OracleHome.isDatabaseOptionOn:1251]  Checking Database Option: Spatial
[main] [ 2016-02-29 12:03:19.968 UTC ] [OracleHome.isDatabaseOptionOn:1308]  Database Option Spatial is false
[main] [ 2016-02-29 12:03:19.982 UTC ] [OracleHome.isDatabaseOptionOn:1251]  Checking Database Option: OLAP
[main] [ 2016-02-29 12:03:19.982 UTC ] [OracleHome.isDatabaseOptionOn:1308]  Database Option OLAP is false
[main] [ 2016-02-29 12:03:19.982 UTC ] [OracleHome.hasEELicense:247]  Running script to determine licensing: /u01/app/oracle/12.1.0.2/home1/bin/bndlchk
[main] [ 2016-02-29 12:03:19.986 UTC ] [OracleHome.hasEELicense:258]  home=/u01/app/oracle/12.1.0.2/home1 has EE licensing
[main] [ 2016-02-29 12:03:19.988 UTC ] [InitParamHandler.endElement:547]  CustomSGA flag: false
[main] [ 2016-02-29 12:03:19.988 UTC ] [InitParamHandler.endElement:548]  Database Type: MULTIPURPOSE
[main] [ 2016-02-29 12:03:19.988 UTC ] [InitParamHandler.endElement:549]  Mem Percentage: 30
[main] [ 2016-02-29 12:03:19.995 UTC ] [OsUtilsBase.getTotalPhysicalMemory:411]  Total Physical Memory in MB: 1876
[main] [ 2016-02-29 12:03:19.995 UTC ] [InitParamHandler.endElement:582]  Total memory MB: 562
[main] [ 2016-02-29 12:03:19.995 UTC ] [InitParamHandler.endElement:583]  Physical memory MB: 1876
[main] [ 2016-02-29 12:03:19.996 UTC ] [OsUtilsBase.is64Bit:395]  architecture is 64 bit: true
[main] [ 2016-02-29 12:03:19.996 UTC ] [MemoryCalculator.<clinit>:103]  setting memory minimums for 64 bit
[main] [ 2016-02-29 12:03:19.999 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381]  Volume: /
[main] [ 2016-02-29 12:03:19.999 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381]  Volume: /proc
[main] [ 2016-02-29 12:03:19.999 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381]  Volume: /sys
[main] [ 2016-02-29 12:03:19.999 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381]  Volume: /dev/pts
[main] [ 2016-02-29 12:03:19.999 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:381]  Volume: /dev/shm
[main] [ 2016-02-29 12:03:20.000 UTC ] [MemoryCalculator.isUnifiedMemoryFeasible:388]  /dev/shm Feasible: true
[main] [ 2016-02-29 12:03:20.000 UTC ] [InitParamHandler.endElement:600]  /dev/shm size in MB: 938
[main] [ 2016-02-29 12:03:20.000 UTC ] [InitParamHandler.endElement:608]  bUseUnified true
[main] [ 2016-02-29 12:03:20.000 UTC ] [DatafileCloneHandler.startElement:209]  atts name cdb
[main] [ 2016-02-29 12:03:20.001 UTC ] [DatafileCloneHandler.startElement:211]  atts lenght true
[main] [ 2016-02-29 12:03:20.003 UTC ] [TemplateManager.getParsedAttributes:1553]  Setting DB_RECOVERY_FILE_DEST: /u01/fra/
[main] [ 2016-02-29 12:03:20.005 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:331]  This thread doesnot exist in existing thread to group map
[main] [ 2016-02-29 12:03:20.005 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:269]  Current threadID=1
[main] [ 2016-02-29 12:03:20.005 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:270]  newThread threadID=1
[main] [ 2016-02-29 12:03:20.005 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:303]  redoGroups.length 1
[main] [ 2016-02-29 12:03:20.006 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:305]  new redoGroup id 2
[main] [ 2016-02-29 12:03:20.006 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:310]  redoGroups 1
[main] [ 2016-02-29 12:03:20.006 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:321]  arRedoGroups:[1, 2] adding to thread id 1
[main] [ 2016-02-29 12:03:20.007 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:269]  Current threadID=1
[main] [ 2016-02-29 12:03:20.007 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:270]  newThread threadID=1
[main] [ 2016-02-29 12:03:20.007 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:303]  redoGroups.length 2
[main] [ 2016-02-29 12:03:20.007 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:305]  new redoGroup id 3
[main] [ 2016-02-29 12:03:20.007 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:310]  redoGroups 1
[main] [ 2016-02-29 12:03:20.007 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:310]  redoGroups 2
[main] [ 2016-02-29 12:03:20.007 UTC ] [DatabaseStorageAttributes.addRedoLogGroup:321]  arRedoGroups:[1, 2, 3] adding to thread id 1
[main] [ 2016-02-29 12:03:20.008 UTC ] [TemplateManager.getParsedAttributes:1710]  is Template a CDB false
[main] [ 2016-02-29 12:03:20.009 UTC ] [Verifier.getDatabaseSize:2543]  Clone Datafile: sysaux01.dbf size: 560.0
[main] [ 2016-02-29 12:03:20.009 UTC ] [Verifier.getDatabaseSize:2543]  Clone Datafile: system01.dbf size: 780.0
[main] [ 2016-02-29 12:03:20.009 UTC ] [Verifier.getDatabaseSize:2543]  Clone Datafile: undotbs01.dbf size: 25.0
[main] [ 2016-02-29 12:03:20.010 UTC ] [Verifier.getDatabaseSize:2543]  Clone Datafile: users01.dbf size: 5.0
[main] [ 2016-02-29 12:03:20.010 UTC ] [Verifier.getControlfFileSizes:2868]  No. of Control files:=2
[main] [ 2016-02-29 12:03:20.010 UTC ] [Verifier.getDatabaseSize:2595]  dbSize: 1402880 redoLogSize: 307200 ctrlFileSize: 40
[main] [ 2016-02-29 12:03:20.010 UTC ] [TemplateManager.getParsedAttributes:1772]  Setting DB_RECOVERY_FILE_SIZE to default size: 5010
[main] [ 2016-02-29 12:03:20.011 UTC ] [TemplateManager.getParsedAttributes:1778]  Dynamic FRA true
[main] [ 2016-02-29 12:03:20.011 UTC ] [TemplateManager.parseCloneTemplate:1353]  See for any transportable datafiles in TemplateManager.....
[main] [ 2016-02-29 12:03:20.016 UTC ] [Host.setupOIDCommandlineParameters:9418]  setupOIDCommandlineParameters: 
[main] [ 2016-02-29 12:03:20.016 UTC ] [Host.setupOIDCommandlineParameters:9419]  m_regWithdirService: false
[main] [ 2016-02-29 12:03:20.016 UTC ] [Host.setupOIDCommandlineParameters:9420]  m_unregWithdirService: false
[main] [ 2016-02-29 12:03:20.016 UTC ] [Host.setupOIDCommandlineParameters:9421]  m_updateDirService: false
[main] [ 2016-02-29 12:03:20.016 UTC ] [Host.startOperation:2766]  Adding init param: processes=700
[main] [ 2016-02-29 12:03:20.017 UTC ] [Host.startOperation:2766]  Adding init param: open_cursors=300
[main] [ 2016-02-29 12:03:20.017 UTC ] [Host.startOperation:2789]  setDataBaseType :0
[main] [ 2016-02-29 12:03:20.017 UTC ] [Host.startOperation:2872]  is Template CDB false
[main] [ 2016-02-29 12:03:20.017 UTC ] [TemplateManager.isInstallTemplate:2348]  Selected Template by user:=General Purpose
[main] [ 2016-02-29 12:03:20.017 UTC ] [TemplateManager.isInstallTemplate:2355]  The Message Id to be searched:=GENERAL_PURPOSE
[main] [ 2016-02-29 12:03:20.017 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.017 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.018 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.018 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-02-29 12:03:20.018 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[main] [ 2016-02-29 12:03:20.018 UTC ] [Verifier.processRawConfigFile:2991]  StorageType == 0
[main] [ 2016-02-29 12:03:20.018 UTC ] [Verifier.setOradataDest:3120]  setOradataDest:dfDest=/u01/oradata/
[main] [ 2016-02-29 12:03:20.018 UTC ] [TemplateManager.updateDatafileDestination:2032]  updateDatafiles:datafileDir=/u01/oradata/
[main] [ 2016-02-29 12:03:20.019 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[main] [ 2016-02-29 12:03:20.019 UTC ] [TemplateManager.updateDatafileDestination:2205]  From template, RedoLogGrName=1
[main] [ 2016-02-29 12:03:20.019 UTC ] [TemplateManager.updateDatafileDestination:2217]  new file name redo01.log
[main] [ 2016-02-29 12:03:20.019 UTC ] [TemplateManager.updateDatafileDestination:2205]  From template, RedoLogGrName=2
[main] [ 2016-02-29 12:03:20.020 UTC ] [TemplateManager.updateDatafileDestination:2217]  new file name redo02.log
[main] [ 2016-02-29 12:03:20.020 UTC ] [TemplateManager.updateDatafileDestination:2205]  From template, RedoLogGrName=3
[main] [ 2016-02-29 12:03:20.020 UTC ] [TemplateManager.updateDatafileDestination:2217]  new file name redo03.log
[main] [ 2016-02-29 12:03:20.020 UTC ] [SilentHost.performOperation:458]  processRawConfigFile=true
[main] [ 2016-02-29 12:03:20.024 UTC ] [Host.initializeValidationContext:1255]   sid, uniquenname, domain set in validationcontextorcl orcl, orcl, 
[main] [ 2016-02-29 12:03:20.024 UTC ] [Verifier.getStorageDataForValidation:4935]  Entering getStorageDataForValidation
[main] [ 2016-02-29 12:03:20.031 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.031 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.032 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.032 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-02-29 12:03:20.032 UTC ] [ASMUtils.initialize:247]  ASMHome: null
[main] [ 2016-02-29 12:03:20.034 UTC ] [KfodUtil.kfodOutput:361]  null/rdbms/bin/kfod
[main] [ 2016-02-29 12:03:20.034 UTC ] [KfodUtil.kfodOutput:361]  nohdr=TRUE
[main] [ 2016-02-29 12:03:20.034 UTC ] [KfodUtil.kfodOutput:361]  OP=GROUPS
[main] [ 2016-02-29 12:03:20.034 UTC ] [KfodUtil.kfodOutput:361]  status=true
[main] [ 2016-02-29 12:03:20.035 UTC ] [KfodUtil.kfodOutput:361]  asmcompatibility=true dbcompatibility=true
[main] [ 2016-02-29 12:03:20.038 UTC ] [KfodUtil.kfodOutput:368]  Cannot run program "null/rdbms/bin/kfod": java.io.IOException: error=2, No such file or directory
[main] [ 2016-02-29 12:03:20.039 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-02-29 12:03:20.039 UTC ] [KfodUtil.kfodOutput:372]  Kfod result null
[main] [ 2016-02-29 12:03:20.039 UTC ] [Verifier.initASMDGSpace:3578]  omfLocation=null
[main] [ 2016-02-29 12:03:20.039 UTC ] [Verifier.initASMDGSpace:3591]  ControlFile Name /u01/oradata//{DB_UNIQUE_NAME}/
[main] [ 2016-02-29 12:03:20.039 UTC ] [Verifier.initASMDGSpace:3591]  ControlFile Name /u01/fra//{DB_UNIQUE_NAME}/
[main] [ 2016-02-29 12:03:20.039 UTC ] [Verifier.initASMDGSpace:3612]  datafile name /u01/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-02-29 12:03:20.042 UTC ] [Verifier.initASMDGSpace:3612]  datafile name /u01/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-02-29 12:03:20.042 UTC ] [Verifier.initASMDGSpace:3612]  datafile name /u01/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-02-29 12:03:20.042 UTC ] [Verifier.initASMDGSpace:3612]  datafile name /u01/oradata/{DB_UNIQUE_NAME}/
[main] [ 2016-02-29 12:03:20.043 UTC ] [Verifier.initASMDGSpace:3622]  Adding an extra 80 space for undo tbs 
[main] [ 2016-02-29 12:03:20.043 UTC ] [Verifier.initASMDGSpace:3781]  Skipping this redolog as its not on ASM
[main] [ 2016-02-29 12:03:20.043 UTC ] [Verifier.initASMDGSpace:3781]  Skipping this redolog as its not on ASM
[main] [ 2016-02-29 12:03:20.043 UTC ] [Verifier.initASMDGSpace:3781]  Skipping this redolog as its not on ASM
[main] [ 2016-02-29 12:03:20.043 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[main] [ 2016-02-29 12:03:20.043 UTC ] [Verifier.initASMDGSpace:3823]  Skipping passwowd file compatible check for MGMTDB/sidb
[main] [ 2016-02-29 12:03:20.044 UTC ] [Verifier.initASMDGSpace:3878]  dbRecAreaSize0 vertrue/u01/fra/
[main] [ 2016-02-29 12:03:20.047 UTC ] [Verifier.initASMDGSpace:3884]  dbRecAreaSizever5010
[main] [ 2016-02-29 12:03:20.047 UTC ] [Verifier.initASMDGSpace:3938]  Exiting getStorageDataForValidation
[main] [ 2016-02-29 12:03:20.047 UTC ] [Verifier.initFSSpace:4421]  omfLocation=null
[main] [ 2016-02-29 12:03:20.047 UTC ] [Verifier.initFSSpace:4442]  Entering newCalculateCloneDatafilePathsAndSizes
[main] [ 2016-02-29 12:03:20.047 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.047 UTC ] [Verifier.initFSSpace:4483]   clone /u01/oradata/orcl/sysaux01.dbf 573440
[main] [ 2016-02-29 12:03:20.048 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.048 UTC ] [Verifier.updateSizeMap:4918]  Adding required size for /u01/oradata/orcl/ to 560
[main] [ 2016-02-29 12:03:20.048 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.048 UTC ] [Verifier.initFSSpace:4483]   clone /u01/oradata/orcl/system01.dbf 798720
[main] [ 2016-02-29 12:03:20.048 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.048 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1340
[main] [ 2016-02-29 12:03:20.048 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.048 UTC ] [Verifier.initFSSpace:4483]   clone /u01/oradata/orcl/undotbs01.dbf 25600
[main] [ 2016-02-29 12:03:20.049 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.049 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1365
[main] [ 2016-02-29 12:03:20.049 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.049 UTC ] [Verifier.initFSSpace:4483]   clone /u01/oradata/orcl/users01.dbf 5120
[main] [ 2016-02-29 12:03:20.049 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.049 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1370
[main] [ 2016-02-29 12:03:20.049 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.052 UTC ] [Verifier.initFSSpace:4483]   clone /u01/oradata/orcl/temp01.dbf 61440
[main] [ 2016-02-29 12:03:20.052 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.052 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1430
[main] [ 2016-02-29 12:03:20.052 UTC ] [Verifier.initFSSpace:4488]  Adding an extra 80 space for undo tbs
[main] [ 2016-02-29 12:03:20.052 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/
[main] [ 2016-02-29 12:03:20.052 UTC ] [Verifier.updateSizeMap:4918]  Adding required size for /u01/oradata/ to 0
[main] [ 2016-02-29 12:03:20.052 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.053 UTC ] [Verifier.initFSSpace:4659]   redo /u01/oradata/orcl/ 102400
[main] [ 2016-02-29 12:03:20.053 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.053 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1530
[main] [ 2016-02-29 12:03:20.053 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.053 UTC ] [Verifier.initFSSpace:4659]   redo /u01/oradata/orcl/ 102400
[main] [ 2016-02-29 12:03:20.053 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.053 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1630
[main] [ 2016-02-29 12:03:20.053 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.054 UTC ] [Verifier.initFSSpace:4659]   redo /u01/oradata/orcl/ 102400
[main] [ 2016-02-29 12:03:20.054 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.054 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1730
[main] [ 2016-02-29 12:03:20.054 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.054 UTC ] [Verifier.initFSSpace:4691]   contrl and buffer /u01/oradata//orcl/ 120
[main] [ 2016-02-29 12:03:20.054 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.054 UTC ] [Verifier.updateSizeMap:4911]  Updating required size for /u01/oradata/orcl/ to 1730
[main] [ 2016-02-29 12:03:20.054 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.054 UTC ] [Verifier.initFSSpace:4691]   contrl and buffer /u01/fra//orcl/ 120
[main] [ 2016-02-29 12:03:20.055 UTC ] [Verifier.updateSizeMap:4905]  canonicalPath=/u01/fra/orcl/
[main] [ 2016-02-29 12:03:20.058 UTC ] [Verifier.updateSizeMap:4918]  Adding required size for /u01/fra/orcl/ to 0
[main] [ 2016-02-29 12:03:20.058 UTC ] [Verifier.initFSSpace:4828]  canonicalPathForFra=/u01/fra/
[main] [ 2016-02-29 12:03:20.058 UTC ] [Verifier.initFSSpace:4859]  Adding File path: /u01/oradata/orcl/sysaux01.dbf for file existence check
[main] [ 2016-02-29 12:03:20.058 UTC ] [Verifier.initFSSpace:4859]  Adding File path: /u01/oradata/orcl/system01.dbf for file existence check
[main] [ 2016-02-29 12:03:20.058 UTC ] [Verifier.initFSSpace:4859]  Adding File path: /u01/oradata/orcl/undotbs01.dbf for file existence check
[main] [ 2016-02-29 12:03:20.059 UTC ] [Verifier.initFSSpace:4859]  Adding File path: /u01/oradata/orcl/users01.dbf for file existence check
[main] [ 2016-02-29 12:03:20.059 UTC ] [Verifier.initFSSpace:4859]  Adding File path: /u01/oradata/orcl/temp01.dbf for file existence check
[main] [ 2016-02-29 12:03:20.059 UTC ] [Verifier.initFSSpace:4862]  Exiting getStorageDataForValidation
[main] [ 2016-02-29 12:03:20.059 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.059 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.059 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.061 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:20.061 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:20.061 UTC ] [SilentHost.performOperation:461]  after initialize validation context for silent mode
[main] [ 2016-02-29 12:03:20.129 UTC ] [ClusterVerification.getInstance:396]  Method Entry
[main] [ 2016-02-29 12:03:20.134 UTC ] [ParamManager.<init>:668]  m_paramInstantiated set to TRUE
[main] [ 2016-02-29 12:03:20.134 UTC ] [VerificationUtil.getLocalHost:1312]  Hostname retrieved: localhost.localdomain, returned: localhost
[main] [ 2016-02-29 12:03:20.136 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.136 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.136 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.137 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.137 UTC ] [VerificationUtil.isCRSConfigured:4209]  crsConfigured=false
[main] [ 2016-02-29 12:03:20.137 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.137 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.137 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.137 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.137 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 11
[main] [ 2016-02-29 12:03:20.138 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.138 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-02-29 12:03:20.138 UTC ] [Version.isPre:651]  isPre: Returning FALSE for major version check
[main] [ 2016-02-29 12:03:20.138 UTC ] [UnixSystem.isHAConfigured:2788]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.138 UTC ] [VerificationUtil.isHAConfigured:4181]  haConfigured=false
[main] [ 2016-02-29 12:03:20.165 UTC ] [Verifier.validateMemory:1219]  Verification Exception: The default location for framework home is not available. It must be specified
[main] [ 2016-02-29 12:03:20.165 UTC ] [ClusterVerification.getInstance:396]  Method Entry
[main] [ 2016-02-29 12:03:20.165 UTC ] [VerificationUtil.getLocalHost:1312]  Hostname retrieved: localhost.localdomain, returned: localhost
[main] [ 2016-02-29 12:03:20.165 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.166 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.166 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.166 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.166 UTC ] [VerificationUtil.isCRSConfigured:4209]  crsConfigured=false
[main] [ 2016-02-29 12:03:20.166 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.166 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.166 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 10
[main] [ 2016-02-29 12:03:20.166 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.167 UTC ] [Version.isPre:610]  version to be checked 12.1.0.2.0 major version to check against 11
[main] [ 2016-02-29 12:03:20.167 UTC ] [Version.isPre:621]  isPre.java: Returning FALSE
[main] [ 2016-02-29 12:03:20.167 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-02-29 12:03:20.167 UTC ] [Version.isPre:651]  isPre: Returning FALSE for major version check
[main] [ 2016-02-29 12:03:20.167 UTC ] [UnixSystem.isHAConfigured:2788]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.167 UTC ] [VerificationUtil.isHAConfigured:4181]  haConfigured=false
[main] [ 2016-02-29 12:03:20.167 UTC ] [Verifier.validateSGA:1248]  Verification Exception: The default location for framework home is not available. It must be specified
[main] [ 2016-02-29 12:03:20.167 UTC ] [Verifier.validateSharedPoolSize:1273]  checkUnified true
[main] [ 2016-02-29 12:03:20.168 UTC ] [Verifier.validateSharedPoolSize:1274]  checkUnified 589299712
[main] [ 2016-02-29 12:03:20.168 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.168 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.168 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.168 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-02-29 12:03:20.168 UTC ] [Host.validate:5345]  calling validatebackend 
[main] [ 2016-02-29 12:03:20.168 UTC ] [Verifier.validateBackend:1026]   starting validateBackend
[main] [ 2016-02-29 12:03:20.172 UTC ] [DBCAValidator.loadCreateDBValidationGroups:400]  run si db validations
[main] [ 2016-02-29 12:03:20.173 UTC ] [Host.noEntryinOratab:7194]  Check made for oratab arg passed............
[main] [ 2016-02-29 12:03:20.173 UTC ] [DBCAValidator.loadCreateDBValidationGroups:403]  skip dbid validatoin for SI 
[main] [ 2016-02-29 12:03:20.173 UTC ] [DBCAValidator.loadCreateDBValidationGroups:415]  isCluster false
[main] [ 2016-02-29 12:03:20.173 UTC ] [DBCAValidator.loadCreateDBValidationGroups:416]  isHARegnReq false
[main] [ 2016-02-29 12:03:20.177 UTC ] [DBIdentificationHelper.getDbNameError:224]   gdbname isorcl
[main] [ 2016-02-29 12:03:20.182 UTC ] [Verifier.validateBackend:1033]  Validation Result:
Unique database identifier check passed.
[main] [ 2016-02-29 12:03:20.182 UTC ] [Verifier.validateBackend:1058]   write result to trace true
[main] [ 2016-02-29 12:03:20.183 UTC ] [StorageSpaceCheck.validate:97]  Getting volumes from space maps 3, 1
[main] [ 2016-02-29 12:03:20.183 UTC ] [StorageSpaceCheck.getConsolidatedMap:312]  locationString processed: /u01/oradata/orcl original String: /u01/oradata/orcl/
[main] [ 2016-02-29 12:03:20.183 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.183 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.183 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.183 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-02-29 12:03:20.184 UTC ] [HAUtils.isPathOnACFS:3995]  isPathOnACFS:    false
[main] [ 2016-02-29 12:03:20.233 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /vagrant/
[main] [ 2016-02-29 12:03:20.234 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /sys/
[main] [ 2016-02-29 12:03:20.235 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/sys/fs/binfmt_misc/
[main] [ 2016-02-29 12:03:20.235 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/
[main] [ 2016-02-29 12:03:20.235 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/shm/
[main] [ 2016-02-29 12:03:20.235 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/pts/
[main] [ 2016-02-29 12:03:20.235 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /boot/
[main] [ 2016-02-29 12:03:20.235 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /
[main] [ 2016-02-29 12:03:20.235 UTC ] [StorageSpaceCheck.getConsolidatedMap:341]  Mount point of /u01/oradata/orcl/ is /
[main] [ 2016-02-29 12:03:20.236 UTC ] [StorageSpaceCheck.getConsolidatedMap:312]  locationString processed: /u01/fra/orcl original String: /u01/fra/orcl/
[main] [ 2016-02-29 12:03:20.236 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.239 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.239 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.239 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-02-29 12:03:20.240 UTC ] [HAUtils.isPathOnACFS:3995]  isPathOnACFS:    false
[main] [ 2016-02-29 12:03:20.240 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /vagrant/
[main] [ 2016-02-29 12:03:20.240 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /sys/
[main] [ 2016-02-29 12:03:20.240 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/sys/fs/binfmt_misc/
[main] [ 2016-02-29 12:03:20.240 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/
[main] [ 2016-02-29 12:03:20.240 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/shm/
[main] [ 2016-02-29 12:03:20.240 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/pts/
[main] [ 2016-02-29 12:03:20.241 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /boot/
[main] [ 2016-02-29 12:03:20.241 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /
[main] [ 2016-02-29 12:03:20.241 UTC ] [StorageSpaceCheck.getConsolidatedMap:341]  Mount point of /u01/fra/orcl/ is /
[main] [ 2016-02-29 12:03:20.241 UTC ] [StorageSpaceCheck.getConsolidatedMap:312]  locationString processed: /u01/oradata original String: /u01/oradata/
[main] [ 2016-02-29 12:03:20.241 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.241 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.241 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.241 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-02-29 12:03:20.242 UTC ] [HAUtils.isPathOnACFS:3995]  isPathOnACFS:    false
[main] [ 2016-02-29 12:03:20.245 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /vagrant/
[main] [ 2016-02-29 12:03:20.245 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /sys/
[main] [ 2016-02-29 12:03:20.246 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/sys/fs/binfmt_misc/
[main] [ 2016-02-29 12:03:20.246 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/
[main] [ 2016-02-29 12:03:20.246 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/shm/
[main] [ 2016-02-29 12:03:20.246 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/pts/
[main] [ 2016-02-29 12:03:20.246 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /boot/
[main] [ 2016-02-29 12:03:20.246 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /
[main] [ 2016-02-29 12:03:20.247 UTC ] [StorageSpaceCheck.getConsolidatedMap:341]  Mount point of /u01/oradata/ is /
[main] [ 2016-02-29 12:03:20.247 UTC ] [StorageSpaceCheck.getConsolidatedMap:312]  locationString processed: /u01/fra original String: /u01/fra/
[main] [ 2016-02-29 12:03:20.247 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.247 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.247 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.248 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-02-29 12:03:20.248 UTC ] [HAUtils.isPathOnACFS:3995]  isPathOnACFS:    false
[main] [ 2016-02-29 12:03:20.248 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /vagrant/
[main] [ 2016-02-29 12:03:20.248 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /sys/
[main] [ 2016-02-29 12:03:20.248 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/sys/fs/binfmt_misc/
[main] [ 2016-02-29 12:03:20.249 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /proc/
[main] [ 2016-02-29 12:03:20.249 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/shm/
[main] [ 2016-02-29 12:03:20.249 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /dev/pts/
[main] [ 2016-02-29 12:03:20.249 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /boot/
[main] [ 2016-02-29 12:03:20.249 UTC ] [OsUtilsBase.getMountPoint:2822]  mount point to be verified /
[main] [ 2016-02-29 12:03:20.249 UTC ] [StorageSpaceCheck.getConsolidatedMap:341]  Mount point of /u01/fra/ is /
[main] [ 2016-02-29 12:03:20.250 UTC ] [StorageSpaceCheck.validate:136]  Checking storage space in /
[main] [ 2016-02-29 12:03:20.250 UTC ] [HAUtils.getCurrentOracleHome:593]  Oracle home from system property: /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.250 UTC ] [HAUtils.getConfiguredGridHome:1343]  ----- Getting CRS HOME ----
[main] [ 2016-02-29 12:03:20.250 UTC ] [UnixSystem.getCRSHome:2878]  olrFileName = /etc/oracle/olr.loc
[main] [ 2016-02-29 12:03:20.250 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-02-29 12:03:20.250 UTC ] [HAUtils.isPathOnACFS:3995]  isPathOnACFS:    false
[main] [ 2016-02-29 12:03:20.251 UTC ] [StorageSpaceCheck.validate:165]  / isACFSMountPoint: false
[main] [ 2016-02-29 12:03:20.251 UTC ] [StorageSpaceCheck.parseSpaceResult:385]  actual: 13737 location: / mandatorySpace: 1730 optionalSpace: 5010
[main] [ 2016-02-29 12:03:20.252 UTC ] [Verifier.validateBackend:1033]  Validation Result:
/ has enough space. Required space is 6740 MB , available space is 13737 MB.
File Validations Successful.
[main] [ 2016-02-29 12:03:20.252 UTC ] [Verifier.validateBackend:1058]   write result to trace true
[main] [ 2016-02-29 12:03:20.253 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.253 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.253 UTC ] [Host.checkCloneJarExists:5160]  Backup piece /u01/app/oracle/12.1.0.2/home1/assistants/dbca/templates/Seed_Database.dfb exists
[main] [ 2016-02-29 12:03:20.253 UTC ] [Host.executeSteps:5975]  Executing steps....
[main] [ 2016-02-29 12:03:20.253 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[main] [ 2016-02-29 12:03:20.253 UTC ] [Host.setUpForOperation:4003]  setUpForOperation: Mode = 128
[main] [ 2016-02-29 12:03:20.253 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.254 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.254 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.256 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:20.256 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:20.256 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.257 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.257 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.258 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:20.259 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:20.259 UTC ] [OsUtilsBase.copyFile:1505]  OsUtilsBase.copyFile: 
[main] [ 2016-02-29 12:03:20.260 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.261 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.261 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.262 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:20.263 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:20.263 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle
[main] [ 2016-02-29 12:03:20.263 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.263 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle
[main] [ 2016-02-29 12:03:20.263 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.263 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.263 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.264 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.266 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:20.267 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:20.267 UTC ] [Host.getAuditNextGen:9054]  NextGen Audit /u01/app/oracle/audit
[main] [ 2016-02-29 12:03:20.267 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle
[main] [ 2016-02-29 12:03:20.267 UTC ] [Host.getFolders:10740]  adding path for DB_RECOVERY_FILE_DETS/u01/fra
[main] [ 2016-02-29 12:03:20.268 UTC ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_1
[main] [ 2016-02-29 12:03:20.268 UTC ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_2
[main] [ 2016-02-29 12:03:20.268 UTC ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_3
[main] [ 2016-02-29 12:03:20.268 UTC ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_4
[main] [ 2016-02-29 12:03:20.268 UTC ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_5
[main] [ 2016-02-29 12:03:20.268 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.268 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.269 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.269 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.269 UTC ] [Host.getFolders:10786]  adding path for /u01/app/oracle/12.1.0.2/home1/dbs
[main] [ 2016-02-29 12:03:20.269 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.269 UTC ] [Host.getFolders:10838]  adding path /u01/oradata/orcl
[main] [ 2016-02-29 12:03:20.269 UTC ] [Host.getFolders:10841]  m_enableDBConsolidation: false
[main] [ 2016-02-29 12:03:20.269 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.269 UTC ] [Host.getFolders:10841]  m_enableDBConsolidation: false
[main] [ 2016-02-29 12:03:20.269 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.270 UTC ] [Host.getFolders:10841]  m_enableDBConsolidation: false
[main] [ 2016-02-29 12:03:20.270 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.270 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.270 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.270 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.270 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.270 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.270 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[main] [ 2016-02-29 12:03:20.272 UTC ] [Host.setUpForOperation:4253]  Folder[0]=/u01/app/oracle
[main] [ 2016-02-29 12:03:20.272 UTC ] [Host.setUpForOperation:4253]  Folder[1]=/u01/app/oracle/12.1.0.2/home1/dbs
[main] [ 2016-02-29 12:03:20.272 UTC ] [Host.setUpForOperation:4253]  Folder[2]=/u01/app/oracle/admin/orcl/adump
[main] [ 2016-02-29 12:03:20.273 UTC ] [Host.setUpForOperation:4253]  Folder[3]=/u01/app/oracle/admin/orcl/dpdump
[main] [ 2016-02-29 12:03:20.273 UTC ] [Host.setUpForOperation:4253]  Folder[4]=/u01/app/oracle/admin/orcl/pfile
[main] [ 2016-02-29 12:03:20.273 UTC ] [Host.setUpForOperation:4253]  Folder[5]=/u01/app/oracle/audit
[main] [ 2016-02-29 12:03:20.273 UTC ] [Host.setUpForOperation:4253]  Folder[6]=/u01/app/oracle/cfgtoollogs/dbca/orcl
[main] [ 2016-02-29 12:03:20.273 UTC ] [Host.setUpForOperation:4253]  Folder[7]=/u01/fra
[main] [ 2016-02-29 12:03:20.273 UTC ] [Host.setUpForOperation:4253]  Folder[8]=/u01/fra/orcl
[main] [ 2016-02-29 12:03:20.274 UTC ] [Host.setUpForOperation:4253]  Folder[9]=/u01/oradata/orcl
[main] [ 2016-02-29 12:03:20.274 UTC ] [Host.executeSteps:6091]  setupForOperation returned: true
[main] [ 2016-02-29 12:03:20.274 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.274 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.274 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.277 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:20.277 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:20.277 UTC ] [Host.createStepSQLInterface:8605]  sid =orcl
[main] [ 2016-02-29 12:03:20.277 UTC ] [Host.createStepSQLInterface:8606]  sidOrConnectString orcl
[main] [ 2016-02-29 12:03:20.277 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: null
[main] [ 2016-02-29 12:03:20.279 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-02-29 12:03:20.279 UTC ] [OracleHome.getVersion:1010]  Homeinfo /u01/app/oracle/12.1.0.2/home1,1
[main] [ 2016-02-29 12:03:20.446 UTC ] [OracleHome.getVersion:1038]  OracleHome.server.getVersion Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:20.446 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:20.446 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:20.447 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:20.447 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[main] [ 2016-02-29 12:03:20.447 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[main] [ 2016-02-29 12:03:20.447 UTC ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0
[main] [ 2016-02-29 12:03:20.447 UTC ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true
[main] [ 2016-02-29 12:03:20.447 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.447 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:20.448 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:20.451 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:20.451 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:20.451 UTC ] [SQLEngine.getEnvParams:602]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-02-29 12:03:20.452 UTC ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[main] [ 2016-02-29 12:03:20.452 UTC ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...
[main] [ 2016-02-29 12:03:20.455 UTC ] [SQLEngine.initialize:395]  m_bReaderStarted: false
[main] [ 2016-02-29 12:03:20.455 UTC ] [SQLEngine.initialize:399]  Starting Reader Thread... 
[Thread-50] [ 2016-02-29 12:03:20.470 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.470 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.470 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.472 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.472 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.473 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[Thread-50] [ 2016-02-29 12:03:20.474 UTC ] [InventoryUtil.getHomeName:113]  homeName = OraDB12Home1
[Thread-50] [ 2016-02-29 12:03:20.475 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.476 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.476 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.478 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.478 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.478 UTC ] [StepManager.getSteps:275]  logging folder set: /u01/app/oracle/cfgtoollogs/dbca/orcl
[Thread-50] [ 2016-02-29 12:03:20.478 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.478 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.478 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.480 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.480 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.480 UTC ] [StepManager.getSteps:295]  ckFileName /u01/app/oracle/cfgtoollogs/dbca/orcl/OraDB12Home1_orcl_creation_checkpoint.xml ckContext oracle.sysman.assistants.util.CheckpointContext@4d74f02c
[Thread-50] [ 2016-02-29 12:03:20.498 UTC ] [CheckpointContext.isCleanupNeeded:140]   failedChkFiles {/u01/app/oracle/cfgtoollogs/dbca/orcl/OraDB12Home1_orcl_creation_checkpoint.xml=null}
[Thread-50] [ 2016-02-29 12:03:20.499 UTC ] [CheckpointContext.isCleanupNeeded:149]  Failed check files iterator java.util.HashMap$KeyIterator@2bf09a31
[Thread-50] [ 2016-02-29 12:03:20.499 UTC ] [CheckpointContext.isCleanupNeeded:155]  failed chjeckpoint file /u01/app/oracle/cfgtoollogs/dbca/orcl/OraDB12Home1_orcl_creation_checkpoint.xml
[Thread-50] [ 2016-02-29 12:03:20.499 UTC ] [CheckpointContext.isCleanupNeeded:160]  getting all check point list
[Thread-50] [ 2016-02-29 12:03:20.499 UTC ] [CheckpointContext.getAllCheckPoints:224]  getting all check point list
[Thread-50] [ 2016-02-29 12:03:20.499 UTC ] [CheckpointContext.getAllCheckPoints:226]  returning all check point list
[Thread-50] [ 2016-02-29 12:03:20.499 UTC ] [CheckpointContext.isCleanupNeeded:163]   all check point list size 1
[Thread-50] [ 2016-02-29 12:03:20.500 UTC ] [CheckpointContext.isCleanupNeeded:189]  adding cleanup step since it is a retry operation.
[Thread-50] [ 2016-02-29 12:03:20.500 UTC ] [CheckpointContext.isCleanupNeeded:196]  old checkpoint file deleted: true
[Thread-50] [ 2016-02-29 12:03:20.501 UTC ] [CheckpointContext.retrieveCheckPoint:338]  check point db_orcl retrieved from file oracle.sysman.oic.oics.OicsCheckPoint@6d3136e5
[Thread-50] [ 2016-02-29 12:03:20.501 UTC ] [StepManager.getSteps:326]  checkpoint from file oracle.sysman.oic.oics.OicsCheckPoint@6d3136e5
[Thread-50] [ 2016-02-29 12:03:20.501 UTC ] [StepManager.getSteps:332]  adding checkpoint to session
[Thread-50] [ 2016-02-29 12:03:20.501 UTC ] [StepManager.getSteps:334]  saved new checkpoint session for DB creation
[Thread-50] [ 2016-02-29 12:03:20.501 UTC ] [StepManager.getSteps:430]  StepManager isHARegistrationRequired : false
[Thread-50] [ 2016-02-29 12:03:20.513 UTC ] [StepContext$ModeRunner.run:2873]  ---- Progress Needed:=true
[Thread-50] [ 2016-02-29 12:03:20.513 UTC ] [BasicStep.execute:250]  Executing Step : CLEANUP
[Thread-50] [ 2016-02-29 12:03:20.514 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=null
[Thread-50] [ 2016-02-29 12:03:20.514 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.514 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.514 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.525 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.525 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.526 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[Thread-50] [ 2016-02-29 12:03:20.526 UTC ] [InventoryUtil.getHomeName:113]  homeName = OraDB12Home1
[Thread-50] [ 2016-02-29 12:03:20.527 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@7f1fc4b8
[Thread-50] [ 2016-02-29 12:03:20.527 UTC ] [StepContext$ModeRunner.run:2873]  ---- Progress Needed:=true
[Thread-50] [ 2016-02-29 12:03:20.527 UTC ] [BasicStep.execute:250]  Executing Step : CLONE_DB_CREATION_RMAN_RESTORE
[Thread-50] [ 2016-02-29 12:03:20.527 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01092
[Thread-50] [ 2016-02-29 12:03:20.527 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01034
[Thread-50] [ 2016-02-29 12:03:20.528 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-03114
[Thread-50] [ 2016-02-29 12:03:20.528 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-12560
[Thread-50] [ 2016-02-29 12:03:20.528 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01109
[Thread-50] [ 2016-02-29 12:03:20.528 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=null
[Thread-50] [ 2016-02-29 12:03:20.528 UTC ] [Host.getRMANConnectionInfo:1171]  Connect to Instance orcl
[Thread-50] [ 2016-02-29 12:03:20.530 UTC ] [BasicStep.execute:250]  Executing Step : INSTANCE_CREATION
[Thread-50] [ 2016-02-29 12:03:20.530 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=null
[Thread-50] [ 2016-02-29 12:03:20.531 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.532 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.534 UTC ] [InitParamAttributes.sortParams:3916]  m_sortOn:-1 sortOn:4
[Thread-50] [ 2016-02-29 12:03:20.543 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param db_block_size
[Thread-50] [ 2016-02-29 12:03:20.543 UTC ] [InitParamAttributes.createInitFile:1816]  The value is 8192
[Thread-50] [ 2016-02-29 12:03:20.543 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param open_cursors
[Thread-50] [ 2016-02-29 12:03:20.543 UTC ] [InitParamAttributes.createInitFile:1816]  The value is 300
[Thread-50] [ 2016-02-29 12:03:20.547 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param db_domain
[Thread-50] [ 2016-02-29 12:03:20.547 UTC ] [InitParamAttributes.createInitFile:1816]  The value is ""
[Thread-50] [ 2016-02-29 12:03:20.547 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param db_name
[Thread-50] [ 2016-02-29 12:03:20.548 UTC ] [InitParamAttributes.createInitFile:1816]  The value is "orcl"
[Thread-50] [ 2016-02-29 12:03:20.548 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param control_files
[Thread-50] [ 2016-02-29 12:03:20.548 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.548 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.548 UTC ] [InitParamAttributes.createInitFile:1816]  The value is ("/u01/oradata//orcl/control01.ctl", "/u01/fra//orcl/control02.ctl")
[Thread-50] [ 2016-02-29 12:03:20.548 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param db_recovery_file_dest
[Thread-50] [ 2016-02-29 12:03:20.549 UTC ] [InitParamAttributes.createInitFile:1816]  The value is "/u01/fra/"
[Thread-50] [ 2016-02-29 12:03:20.549 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param db_recovery_file_dest_size
[Thread-50] [ 2016-02-29 12:03:20.549 UTC ] [InitParamAttributes.createInitFile:1816]  The value is 5010m
[Thread-50] [ 2016-02-29 12:03:20.549 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param compatible
[Thread-50] [ 2016-02-29 12:03:20.549 UTC ] [InitParamAttributes.createInitFile:1816]  The value is 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.549 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param diagnostic_dest
[Thread-50] [ 2016-02-29 12:03:20.549 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.550 UTC ] [InitParamAttributes.createInitFile:1816]  The value is /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.551 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param memory_target
[Thread-50] [ 2016-02-29 12:03:20.551 UTC ] [InitParamAttributes.createInitFile:1816]  The value is 562m
[Thread-50] [ 2016-02-29 12:03:20.551 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param processes
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [InitParamAttributes.createInitFile:1816]  The value is 700
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param audit_file_dest
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [InitParamAttributes.createInitFile:1816]  The value is "/u01/app/oracle/admin/orcl/adump"
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param audit_trail
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [InitParamAttributes.createInitFile:1816]  The value is db
[Thread-50] [ 2016-02-29 12:03:20.552 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param remote_login_passwordfile
[Thread-50] [ 2016-02-29 12:03:20.553 UTC ] [InitParamAttributes.createInitFile:1816]  The value is EXCLUSIVE
[Thread-50] [ 2016-02-29 12:03:20.553 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param dispatchers
[Thread-50] [ 2016-02-29 12:03:20.553 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.553 UTC ] [InitParamAttributes.createInitFile:1816]  The value is "(PROTOCOL=TCP) (SERVICE=orclXDB)"
[Thread-50] [ 2016-02-29 12:03:20.553 UTC ] [InitParamAttributes.createInitFile:1811]  Processing init param undo_tablespace
[Thread-50] [ 2016-02-29 12:03:20.553 UTC ] [InitParamAttributes.createInitFile:1816]  The value is UNDOTBS1
[Thread-50] [ 2016-02-29 12:03:20.555 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[Thread-50] [ 2016-02-29 12:03:20.555 UTC ] [InstanceStep.executeImpl:280]  Checking Register with HA Listener
[Thread-50] [ 2016-02-29 12:03:20.555 UTC ] [Host.noEntryinOratab:7194]  Check made for oratab arg passed............
[Thread-50] [ 2016-02-29 12:03:20.556 UTC ] [Oratab.getTempFile:805]  Temp oratab: /tmp/oratab
[Thread-50] [ 2016-02-29 12:03:20.558 UTC ] [InstanceStep.executeImpl:331]  Password file location prior to parsing /u01/app/oracle/12.1.0.2/home1/dbs/orapworcl
[Thread-50] [ 2016-02-29 12:03:20.558 UTC ] [InstanceStep.executeImpl:333]  Password file location prior to parsing /u01/app/oracle/12.1.0.2/home1/dbs/orapworcl
[Thread-50] [ 2016-02-29 12:03:20.558 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.559 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.559 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.559 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.559 UTC ] [CommonUtils.createPasswordFile:1243]  calling new orapwd for 11.1 or higher
[Thread-50] [ 2016-02-29 12:03:20.559 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.560 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.560 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.560 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.560 UTC ] [CommonUtils.getPasswordFileCreateCmd:1182]  for new orapwd for 11.1 or higher
[Thread-50] [ 2016-02-29 12:03:20.561 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.561 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.562 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.562 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.562 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  /u01/app/oracle/12.1.0.2/home1/bin/orapwd
[Thread-50] [ 2016-02-29 12:03:20.562 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  file=/u01/app/oracle/12.1.0.2/home1/dbs/orapworcl
[Thread-50] [ 2016-02-29 12:03:20.562 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  force=y
[Thread-50] [ 2016-02-29 12:03:20.562 UTC ] [CommonUtils.getPasswordFileCreateCmd:1213]  format=12
[Thread-50] [ 2016-02-29 12:03:20.562 UTC ] [OsUtilsBase.execProg:2123]  beginning execProg with input array.
[Thread-50] [ 2016-02-29 12:03:20.577 UTC ] [OsUtilsBase.execProg:2160]  finished execProg with input array. Status:0
[Thread-50] [ 2016-02-29 12:03:20.577 UTC ] [InstanceStep.setASMAdminGID:373]  isWindows: false Storage Type: 0
[Thread-50] [ 2016-02-29 12:03:20.578 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@7f1fc4b8
[Thread-50] [ 2016-02-29 12:03:20.578 UTC ] [CloneRmanRestoreStep.executeImpl:270]  Instance Creation went fine..........
[Thread-50] [ 2016-02-29 12:03:20.578 UTC ] [CloneRmanRestoreStep.executeImpl:277]  db_recovery_file_dest="/u01/fra/"
[Thread-50] [ 2016-02-29 12:03:20.578 UTC ] [CloneRmanRestoreStep.executeImpl:280]  db_recovery_file_dest_size=5010m
[Thread-50] [ 2016-02-29 12:03:20.578 UTC ] [InitParameterFile.getLongValue:687]  May have unit. Stripping unit and trying with 5010
[Thread-50] [ 2016-02-29 12:03:20.578 UTC ] [CloneRmanRestoreStep.executeImpl:297]  Setting NLS_LANG to AMERICA.AL32UTF8
[Thread-50] [ 2016-02-29 12:03:20.579 UTC ] [SQLEngine.done:2278]  Done called
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [SQLEngine.reInitialize:785]  Reinitializing SQLEngine...
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.580 UTC ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true
[Thread-50] [ 2016-02-29 12:03:20.581 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.581 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.581 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.582 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.583 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.583 UTC ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-50] [ 2016-02-29 12:03:20.583 UTC ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...
[Thread-50] [ 2016-02-29 12:03:20.584 UTC ] [SQLEngine.initialize:395]  m_bReaderStarted: false
[Thread-50] [ 2016-02-29 12:03:20.584 UTC ] [SQLEngine.initialize:399]  Starting Reader Thread... 
[Thread-50] [ 2016-02-29 12:03:20.590 UTC ] [SQLEngine.initialize:448]  Waiting for m_bReaderStarted to be true 
[Thread-63] [ 2016-02-29 12:03:20.613 UTC ] [BasicStep.handleNonIgnorableError:479]  oracle.sysman.assistants.util.SilentMessageHandler@7f1fc4b8:messageHandler
[Thread-63] [ 2016-02-29 12:03:20.613 UTC ] [BasicStep.handleNonIgnorableError:480]  ORA-12547: TNS:lost contact
:msg
[Thread-50] [ 2016-02-29 12:03:20.614 UTC ] [SQLEngine.setSpool:2056]  old Spool  = null
[Thread-50] [ 2016-02-29 12:03:20.614 UTC ] [SQLEngine.setSpool:2057]  Setting Spool  = /u01/app/oracle/cfgtoollogs/dbca/orcl/CloneRmanRestore.log
[Thread-50] [ 2016-02-29 12:03:20.614 UTC ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true
[Thread-50] [ 2016-02-29 12:03:20.615 UTC ] [CloneRmanRestoreStep.executeImpl:323]  _diag_hm_rc_enabled to false
[Thread-50] [ 2016-02-29 12:03:20.615 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.615 UTC ] [CloneRmanRestoreStep.executeImpl:358]  Using control file: /u01/app/oracle/12.1.0.2/home1/assistants/dbca/templates/Seed_Database.ctl; sourceDBName: seeddata
[Thread-50] [ 2016-02-29 12:03:20.617 UTC ] [OsUtilsBase.copyFile:1505]  OsUtilsBase.copyFile: 
[Thread-50] [ 2016-02-29 12:03:20.671 UTC ] [OsUtilsBase.copyFile:1553]  **write of file at destination complete...
[Thread-50] [ 2016-02-29 12:03:20.676 UTC ] [OsUtilsBase.copyFile:1588]  **file copy status:= true
[Thread-50] [ 2016-02-29 12:03:20.676 UTC ] [CloneRmanRestoreStep.executeImpl:383]  set _enable_pluggable_database to true
[Thread-50] [ 2016-02-29 12:03:20.721 UTC ] [CloneRmanRestoreStep.executeImpl:399]  update db alert log File
[Thread-50] [ 2016-02-29 12:03:20.721 UTC ] [CloneRmanRestoreStep.executeImpl:402]  Clearing v$datafile_copy entries
[Thread-50] [ 2016-02-29 12:03:20.721 UTC ] [CloneRmanRestoreStep.executeImpl:404]  Clearing all backup entries
[Thread-50] [ 2016-02-29 12:03:20.722 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.723 UTC ] [CloneRmanRestoreStep.executeImpl:457]  Getting the map of data file ids and names for restore
[Thread-50] [ 2016-02-29 12:03:20.723 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.723 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591]  newname for datafile /u01/oradata/orcl/sysaux01.dbf
[Thread-50] [ 2016-02-29 12:03:20.723 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.723 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591]  newname for datafile /u01/oradata/orcl/system01.dbf
[Thread-50] [ 2016-02-29 12:03:20.724 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.724 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591]  newname for datafile /u01/oradata/orcl/undotbs01.dbf
[Thread-50] [ 2016-02-29 12:03:20.724 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.724 UTC ] [CloneRmanRestoreStep.getDatafileIdNameMapping:591]  newname for datafile /u01/oradata/orcl/users01.dbf
[Thread-50] [ 2016-02-29 12:03:20.726 UTC ] [RMANUtil.getRMANUtil:147]   creating new util instance
[Thread-50] [ 2016-02-29 12:03:20.726 UTC ] [RMANUtil.getRMANUtil:148]   target sid orcl
[Thread-50] [ 2016-02-29 12:03:20.726 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.727 UTC ] [RMANEngine.getCmmdParams:343]   using rman from  /u01/app/oracle/12.1.0.2/home1/bin/rman
[Thread-50] [ 2016-02-29 12:03:20.728 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.728 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.728 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.730 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.730 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.730 UTC ] [RMANEngine.getEnvParams:436]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-50] [ 2016-02-29 12:03:20.730 UTC ] [RMANEngine.initialize:195]  Execing RMAN process from initialize...
[Thread-50] [ 2016-02-29 12:03:20.732 UTC ] [RMANEngine.initialize:221]  Created RMAN process from initialize...
[Thread-50] [ 2016-02-29 12:03:20.732 UTC ] [RMANEngine.initialize:235]  Starting Reader Thread... 
[Thread-50] [ 2016-02-29 12:03:20.733 UTC ] [RMANEngine.initialize:239]  Started reader thread for RMAN process in initialize...
[Thread-68] [ 2016-02-29 12:03:20.734 UTC ] [RMANEngine.run:803]  Started reader thread for RMAN process from initialize...
[Thread-68] [ 2016-02-29 12:03:20.734 UTC ] [RMANEngine.run:807]  Set m_bReaderStarted  to true  in initialize...
[Thread-50] [ 2016-02-29 12:03:20.734 UTC ] [RMANEngine.initialize:247]  Started error thread for RMAN process from initialize...
[Thread-50] [ 2016-02-29 12:03:20.734 UTC ] [RMANEngine.initialize:252]  Created output writer for RMAN process from initialize...
[Thread-50] [ 2016-02-29 12:03:20.734 UTC ] [RMANEngine.initialize:290]  Created Log writer for RMAN process from initialize...
[Thread-50] [ 2016-02-29 12:03:20.734 UTC ] [RMANEngine.initialize:295]  Value of m_bReaderStarted in initialize=true
[Thread-50] [ 2016-02-29 12:03:20.735 UTC ] [RMANEngine.initialize:310]  Made sure reader has started for RMAN process from initialize...
[Thread-50] [ 2016-02-29 12:03:20.735 UTC ] [RMANEngine.connect:775]  NOT using OS auth to connect
[Thread-50] [ 2016-02-29 12:03:20.735 UTC ] [RMANEngine.executeImpl:1186]  m_bExecQuery=false
[Thread-50] [ 2016-02-29 12:03:20.735 UTC ] [RMANEngine.executeImpl:1194]  Command being written to rman process=connect target SYS/******
[Thread-50] [ 2016-02-29 12:03:20.735 UTC ] [RMANEngine.executeImpl:1222]  Notify reader to start reading
[Thread-68] [ 2016-02-29 12:03:20.758 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=
[Thread-68] [ 2016-02-29 12:03:20.759 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Recovery Manager: Release 12.1.0.2.0 - Production on Mon Feb 29 12:03:20 2016
[Thread-68] [ 2016-02-29 12:03:20.759 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=
[Thread-68] [ 2016-02-29 12:03:20.759 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Copyright (c) 1982, 2014, Oracle and/or its affiliates.  All rights reserved.
[Thread-68] [ 2016-02-29 12:03:20.829 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=
[Thread-68] [ 2016-02-29 12:03:20.830 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> 
[Thread-68] [ 2016-02-29 12:03:20.843 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN-00571: ===========================================================
[Thread-68] [ 2016-02-29 12:03:20.844 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
[Thread-68] [ 2016-02-29 12:03:20.844 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN-00571: ===========================================================
[Thread-68] [ 2016-02-29 12:03:20.844 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=ORA-12547: TNS:lost contact
[Thread-68] [ 2016-02-29 12:03:20.844 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=
[Thread-68] [ 2016-02-29 12:03:20.845 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> 
[Thread-68] [ 2016-02-29 12:03:20.846 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=echo set on
[Thread-68] [ 2016-02-29 12:03:20.846 UTC ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=set echo off;
[Thread-68] [ 2016-02-29 12:03:20.846 UTC ] [RMANEngine.readSqlOutput:932]  hasError is true
[Thread-68] [ 2016-02-29 12:03:20.846 UTC ] [RMANEngine.readSqlOutput:938]  ERROR TRACE DETECTED
[Thread-68] [ 2016-02-29 12:03:20.846 UTC ] [RMANEngine.readSqlOutput:945]  m_errHdlr=null
[Thread-68] [ 2016-02-29 12:03:20.847 UTC ] [RMANEngine.readSqlOutput:969]  FATAL ERROR DETECTED
[Thread-68] [ 2016-02-29 12:03:20.847 UTC ] [RMANEngine.readSqlOutput:1033]  Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=truem_bFatalErrorOccured=true
[Thread-50] [ 2016-02-29 12:03:20.847 UTC ] [RMANEngine.executeImpl:1234]  Completed wait from reader
[Thread-50] [ 2016-02-29 12:03:20.847 UTC ] [RMANEngine.executeImpl:1249]  Calling done as got exception
[Thread-50] [ 2016-02-29 12:03:20.847 UTC ] [RMANEngine.done:1554]  Done called
[Thread-50] [ 2016-02-29 12:03:20.847 UTC ] [RMANEngine.executeImpl:1276]  GOT FATAL Error=Recovery Manager: Release 12.1.0.2.0 - Production on Mon Feb 29 12:03:20 2016
Copyright (c) 1982, 2014, Oracle and/or its affiliates.  All rights reserved.
RMAN> 
RMAN-00571: ===========================================================
RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
RMAN-00571: ===========================================================
ORA-12547: TNS:lost contact
RMAN> 
echo set on

[Thread-50] [ 2016-02-29 12:03:20.847 UTC ] [RMANUtil.catalogBackup:487]  Error while cataloging RMAN Backups: Cannot get RMANUtil
[Thread-50] [ 2016-02-29 12:03:20.848 UTC ] [CloneRmanRestoreStep.executeImpl:491]  exception renaming datafiles oracle.sysman.assistants.util.rmanEngine.RMANFatalErrorException: Error while cataloging RMAN Backups
[Thread-50] [ 2016-02-29 12:03:20.848 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@7f1fc4b8
[Thread-50] [ 2016-02-29 12:03:20.848 UTC ] [StepContext$ModeRunner.run:2899]  Error while cataloging RMAN Backups
oracle.sysman.assistants.dbca.backend.CloneRmanRestoreStep.executeImpl(CloneRmanRestoreStep.java:492)
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-50] [ 2016-02-29 12:03:20.848 UTC ] [SQLEngine.done:2278]  Done called
[Thread-50] [ 2016-02-29 12:03:20.848 UTC ] [SQLEngine.spoolOff:2124]  Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/orcl/CloneRmanRestore.log
[Thread-50] [ 2016-02-29 12:03:20.850 UTC ] [Host.createStepSQLInterface:8605]  sid =orcl
[Thread-50] [ 2016-02-29 12:03:20.850 UTC ] [Host.createStepSQLInterface:8606]  sidOrConnectString orcl
[Thread-50] [ 2016-02-29 12:03:20.850 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: null
[Thread-68] [ 2016-02-29 12:03:20.851 UTC ] [RMANEngine.run:817]  Return Value from readSqlOutput=null
[Thread-50] [ 2016-02-29 12:03:20.853 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[Thread-50] [ 2016-02-29 12:03:20.854 UTC ] [OracleHome.getVersion:1010]  Homeinfo /u01/app/oracle/12.1.0.2/home1,1
[Thread-50] [ 2016-02-29 12:03:20.933 UTC ] [OracleHome.getVersion:1038]  OracleHome.server.getVersion Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.933 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.933 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.934 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.936 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.936 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.936 UTC ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-50] [ 2016-02-29 12:03:20.937 UTC ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...
[Thread-50] [ 2016-02-29 12:03:20.937 UTC ] [SQLEngine.initialize:395]  m_bReaderStarted: false
[Thread-50] [ 2016-02-29 12:03:20.938 UTC ] [SQLEngine.initialize:399]  Starting Reader Thread... 
[Thread-50] [ 2016-02-29 12:03:20.948 UTC ] [CloneRmanRestoreStep.cancel:722]  Cleaning up partially extracted files failed
[Thread-50] [ 2016-02-29 12:03:20.956 UTC ] [SQLEngine.done:2278]  Done called
[Thread-50] [ 2016-02-29 12:03:20.956 UTC ] [CloneRmanRestoreStep.cancel:737]  RMAN cleanup failed
[Thread-50] [ 2016-02-29 12:03:20.956 UTC ] [CloneRmanRestoreStep.cancel:738]  ORA-12547: TNS:lost contact

oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeImpl(SQLEngine.java:1739)
oracle.sysman.assistants.util.sqlEngine.SQLEngine.connect(SQLEngine.java:987)
oracle.sysman.assistants.dbca.backend.CloneRmanRestoreStep.cancel(CloneRmanRestoreStep.java:725)
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-50] [ 2016-02-29 12:03:20.956 UTC ] [CloneDBCreationStep.cancel:1178]  CloneDBCreationStep.cancel(): nodeList= null
[Thread-50] [ 2016-02-29 12:03:20.956 UTC ] [SQLEngine.reInitialize:785]  Reinitializing SQLEngine...
[Thread-50] [ 2016-02-29 12:03:20.957 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.957 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.957 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.957 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.957 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.957 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.957 UTC ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0
[Thread-50] [ 2016-02-29 12:03:20.958 UTC ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true
[Thread-50] [ 2016-02-29 12:03:20.958 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.958 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[Thread-50] [ 2016-02-29 12:03:20.958 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[Thread-50] [ 2016-02-29 12:03:20.961 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.961 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[Thread-50] [ 2016-02-29 12:03:20.961 UTC ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-50] [ 2016-02-29 12:03:20.961 UTC ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...
[Thread-50] [ 2016-02-29 12:03:20.962 UTC ] [SQLEngine.initialize:395]  m_bReaderStarted: false
[Thread-50] [ 2016-02-29 12:03:20.962 UTC ] [SQLEngine.initialize:399]  Starting Reader Thread... 
[Thread-50] [ 2016-02-29 12:03:20.969 UTC ] [SQLEngine.initialize:448]  Waiting for m_bReaderStarted to be true 
[Thread-50] [ 2016-02-29 12:03:20.993 UTC ] [SQLEngine.done:2278]  Done called
[Thread-50] [ 2016-02-29 12:03:20.993 UTC ] [Oratab.getTempFile:805]  Temp oratab: /tmp/oratab
[Thread-50] [ 2016-02-29 12:03:20.994 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/12.1.0.2/home1/dbs/orapworcl
[Thread-50] [ 2016-02-29 12:03:20.994 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/12.1.0.2/home1/dbs/initorcl.ora
[Thread-50] [ 2016-02-29 12:03:20.994 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/admin/orcl/pfile/init.ora
[Thread-50] [ 2016-02-29 12:03:20.994 UTC ] [CloneDBCreationStep.cancel:1225]  CloneDBCreationStep.cancel(): bRaw=false
[Thread-50] [ 2016-02-29 12:03:20.994 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.994 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata/orcl/sysaux01.dbf
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata/orcl/system01.dbf
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata/orcl/undotbs01.dbf
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata/orcl/users01.dbf
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata/orcl/redo01.log
[Thread-50] [ 2016-02-29 12:03:20.995 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.996 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata/orcl/redo02.log
[Thread-50] [ 2016-02-29 12:03:20.996 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.996 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata/orcl/redo03.log
[Thread-50] [ 2016-02-29 12:03:20.996 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.996 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/oradata//orcl/control01.ctl
[Thread-50] [ 2016-02-29 12:03:20.996 UTC ] [CommonAttributes.replaceVariables:1402]  var2 orcl
[Thread-50] [ 2016-02-29 12:03:20.996 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/fra//orcl/control02.ctl
[Thread-50] [ 2016-02-29 12:03:21.001 UTC ] [SQLEngine.done:2278]  Done called
[main] [ 2016-02-29 12:03:21.002 UTC ] [SQLEngine.done:2278]  Done called
[main] [ 2016-02-29 12:03:21.002 UTC ] [Host.cleanup:3928]  Dbca exit status is: 1
[main] [ 2016-02-29 12:03:21.002 UTC ] [Host.cleanup:3931]  check point context oracle.sysman.assistants.util.CheckpointContext@4d74f02c
[main] [ 2016-02-29 12:03:21.003 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2016-02-29 12:03:21.003 UTC ] [InventoryUtil.getHomeName:113]  homeName = OraDB12Home1
[main] [ 2016-02-29 12:03:21.003 UTC ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:21.003 UTC ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/home1/bin/orabase
[main] [ 2016-02-29 12:03:21.004 UTC ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/home1
[main] [ 2016-02-29 12:03:21.012 UTC ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase 
/u01/app/oracle
[main] [ 2016-02-29 12:03:21.012 UTC ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle
[main] [ 2016-02-29 12:03:21.012 UTC ] [Host.cleanup:3957]  check point  oracle.sysman.oic.oics.OicsCheckPoint@6d3136e5
[main] [ 2016-02-29 12:03:21.031 UTC ] [OsUtilsBase.copyFile:1505]  OsUtilsBase.copyFile: 
[main] [ 2016-02-29 12:03:21.031 UTC ] [OsUtilsBase.copyFile:1553]  **write of file at destination complete...
[main] [ 2016-02-29 12:03:21.032 UTC ] [OsUtilsBase.copyFile:1588]  **file copy status:= true
[main] [ 2016-02-29 12:03:21.034 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/cfgtoollogs/dbca/silent.log_2016-02-29_12-03-19-PM
[Thread-15] [ 2016-02-29 12:03:21.035 UTC ] [DbcaCleanupHook.run:44]  Cleanup started
[Thread-15] [ 2016-02-29 12:03:21.035 UTC ] [OracleHome.cleanupDBOptionsIntance:1671]  DB Options dummy instance sid=null
[Thread-15] [ 2016-02-29 12:03:21.035 UTC ] [DbcaCleanupHook.run:49]  Cleanup ended