Home » Server Options » RAC & Failsafe » Root.sh failed: ORA-39510: CRS error performing start on instance '+ASM1' on '+ASM'
Root.sh failed: ORA-39510: CRS error performing start on instance '+ASM1' on '+ASM' [message #669856] Thu, 17 May 2018 10:50 Go to next message
sarahLian
Messages: 1
Registered: February 2016
Junior Member
Hello,

I have some issues with the installation of Oracle RAC 12.2 on RHEL 7. Could you please help me with this issue ?
Thanks in advance.

The root.sh failed with the following error:

ORA-39510: CRS error performing start on instance '+ASM1' on '+ASM'
[main] [ 2018-05-02 11:50:34.840 CEST ] [UsmcaLogger.logException:188] oracle.sysman.assistants.util.sqlEngine.SQLFatalErrorException: ORA-01034: ORACLE not available

oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeImpl(SQLEngine.java:1767)
oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeQuery(SQLEngine.java:899)
oracle.sysman.assistants.usmca.backend.USMInstance.configureLocalASM(USMInstance.java:3371)
oracle.sysman.assistants.usmca.service.UsmcaService.configureLocalASM(UsmcaService.java:1349)

asmca-180515PM045244.log:

[main] [ 2018-05-15 16:52:44.216 CEST ] [UsmcaCmdLineParser.isOraHomeCheckRequired:423]  Skipping Oracle Home check for local operation.

[main] [ 2018-05-15 16:52:44.447 CEST ] [HASContext.<init>:125]  moduleInit = 7

[main] [ 2018-05-15 16:52:44.447 CEST ] [Library.load:202]  library.load

[main] [ 2018-05-15 16:52:44.448 CEST ] [sPlatform.isHybrid:66]  osName=Linux osArch=s390x JVM=64 rc=false

[main] [ 2018-05-15 16:52:44.448 CEST ] [sPlatform.isHybrid:66]  osName=Linux osArch=s390x JVM=64 rc=false

[main] [ 2018-05-15 16:52:44.448 CEST ] [Library.load:290]  Loading  library /u01/app/12.2.0.1/grid/lib/libsrvmhas12.so

[main] [ 2018-05-15 16:52:44.457 CEST ] [SRVMContext.init:115]  Performing SRVM Context init. Init Counter=1

[main] [ 2018-05-15 16:52:44.463 CEST ] [Version.isPre:661]  version to be checked 12.2.0.1.0 major version to check against 10

[main] [ 2018-05-15 16:52:44.464 CEST ] [Version.isPre:672]  isPre.java: Returning FALSE

[main] [ 2018-05-15 16:52:44.465 CEST ] [OCR.loadLibrary:333]

Inside constructor of OCR

[main] [ 2018-05-15 16:52:44.465 CEST ] [OCR.loadLibrary:341]  Going to load the ocr library

[main] [ 2018-05-15 16:52:44.466 CEST ] [Library.load:202]  library.load

[main] [ 2018-05-15 16:52:44.466 CEST ] [sPlatform.isHybrid:66]  osName=Linux osArch=s390x JVM=64 rc=false

[main] [ 2018-05-15 16:52:44.466 CEST ] [sPlatform.isHybrid:66]  osName=Linux osArch=s390x JVM=64 rc=false

[main] [ 2018-05-15 16:52:44.467 CEST ] [Library.load:290]  Loading  library /u01/app/12.2.0.1/grid/lib/libsrvmocr12.so

[main] [ 2018-05-15 16:52:44.467 CEST ] [OCR.loadLibrary:343]  loaded ocr libraries

[main] [ 2018-05-15 16:52:44.467 CEST ] [SRVMContext.init:115]  Performing SRVM Context init. Init Counter=2

[main] [ 2018-05-15 16:52:44.468 CEST ] [OCR.isCluster:1055]  Calling OCRNative for isCluster()

[main] [ 2018-05-15 16:52:44.469 CEST ] [nativesystem.OCRNative.Native]  JNI: isCluster() Successfully initialized meta context

[main] [ 2018-05-15 16:52:44.469 CEST ] [nativesystem.OCRNative.Native]  JNI: procr_get_conf() retValue = 0

[main] [ 2018-05-15 16:52:44.470 CEST ] [nativesystem.OCRNative.Native]  The OCR file is configured  & local_only=false

[main] [ 2018-05-15 16:52:44.470 CEST ] [OCR.isCluster:1059]  OCR Result status = true

[main] [ 2018-05-15 16:52:44.470 CEST ] [OCR.isCluster:1071]  Bolean result = true

[main] [ 2018-05-15 16:52:44.471 CEST ] [cnative.ClusterNative.Native]  Native:: CLSBInit

[main] [ 2018-05-15 16:52:44.475 CEST ] [cnative.ClusterNative.Native]  in prcr_clsb:: successfully setup callback

[main] [ 2018-05-15 16:52:44.475 CEST ] [SRVMContext.init:140]  SRVM Context init-ed

[main] [ 2018-05-15 16:52:44.476 CEST ] [HASContext.<init>:136]  Initialised SRVMContext

[main] [ 2018-05-15 16:52:44.476 CEST ] [has.HASContextNative.Native]  prsr_trace: no lsf ctx, line=Native: allocHASContext

[main] [ 2018-05-15 16:52:44.476 CEST ] [has.HASContextNative.Native]  allocHASContext: module_init = 7

[main] [ 2018-05-15 16:52:44.477 CEST ] [has.HASContextNative.Native]

allocHASContext: META context [1]

[main] [ 2018-05-15 16:52:44.477 CEST ] [has.HASContextNative.Native]

allocHASContext: LSF context [1]

[main] [ 2018-05-15 16:52:44.477 CEST ] [has.HASContextNative.Native]  prsr_trace: Native: prsr_initSCLS

[main] [ 2018-05-15 16:52:44.477 CEST ] [has.HASContextNative.Native]

allocHASContext: SCLS context [1]

[main] [ 2018-05-15 16:52:44.478 CEST ] [has.HASContextNative.Native]

allocHASContext: retval [1]

[main] [ 2018-05-15 16:52:44.478 CEST ] [Util.<init>:103]  Util Instance created.

[main] [ 2018-05-15 16:52:44.479 CEST ] [has.UtilNative.Native]  prsr_trace: Native: getCRSHome

[main] [ 2018-05-15 16:52:44.480 CEST ] [has.UtilNative.Native]  prsr_trace: Native: getCRSHome crs_home=/u01/app/12.2.0.1/grid(**)

[main] [ 2018-05-15 16:52:44.480 CEST ] [HASContext.getCRSHome:642]  /u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:44.480 CEST ] [Util.getCRSHome:612]  getCRSHome: ret=/u01/app/12.2.0.1/grid

[Finalizer thread] [ 2018-05-15 16:52:44.483 CEST ] [Util.finalize:134]  Util: finalized called for oracle.ops.mgmt.has.Util@e5b175b6

[main] [ 2018-05-15 16:52:44.499 CEST ] [has.HASContextNative.Native]  prsr_trace: Native: allocHASContext

[main] [ 2018-05-15 16:52:44.500 CEST ] [has.HASContextNative.Native]  allocHASContext: module_init = 64

[main] [ 2018-05-15 16:52:44.500 CEST ] [has.HASContextNative.Native]  prsr_trace: Native: prsr_initCLSCRS

[main] [ 2018-05-15 16:52:44.500 CEST ] [has.HASContextNative.Native]  prsr_trace: prsr_initCLSCRS: before calling prcr_init_crs

[main] [ 2018-05-15 16:52:44.506 CEST ] [has.HASContextNative.Native]

allocHASContext: CLSCRS context [1]

[main] [ 2018-05-15 16:52:44.506 CEST ] [has.HASContextNative.Native]

allocHASContext: retval [1]

[main] [ 2018-05-15 16:52:44.512 CEST ] [cops.EntityOperations.Native]  prsr_trace: e_type=1

[main] [ 2018-05-15 16:52:44.559 CEST ] [Usmca.execute:266]  PRCR-1070 : Failed to check if resource ora.asm is registered

CRS-0184 : Cannot communicate with the CRS daemon.

[main] [ 2018-05-15 16:52:44.562 CEST ] [Util.<init>:103]  Util Instance created.

[main] [ 2018-05-15 16:52:44.562 CEST ] [has.UtilNative.Native]  prsr_trace: Native: hasHAPrivilege

[main] [ 2018-05-15 16:52:44.563 CEST ] [has.HASContextNative.Native]  prsr_trace: Native: allocHASContext

[main] [ 2018-05-15 16:52:44.563 CEST ] [has.HASContextNative.Native]  allocHASContext: module_init = 56

[main] [ 2018-05-15 16:52:44.564 CEST ] [has.HASContextNative.Native]

allocHASContext: SLTS context [1]

[main] [ 2018-05-15 16:52:44.564 CEST ] [has.HASContextNative.Native]  prsr_trace: Native: prsr_initCLSS

[main] [ 2018-05-15 16:52:44.565 CEST ] [has.HASContextNative.Native]

allocHASContext: CLSS context [1]

[main] [ 2018-05-15 16:52:44.566 CEST ] [has.HASContextNative.Native]  prsr_trace: Native: prsr_initCLSR

[main] [ 2018-05-15 16:52:44.566 CEST ] [has.HASContextNative.Native]  prsr_trace: prsr_initCLSR: before calling clsrcini

[main] [ 2018-05-15 16:52:44.566 CEST ] [has.HASContextNative.Native]  prsr_trace: prsr_initCLSR: before calling clsrcerrini

[main] [ 2018-05-15 16:52:44.566 CEST ] [has.HASContextNative.Native]

allocHASContext: CLSR context [1]

[main] [ 2018-05-15 16:52:44.566 CEST ] [has.HASContextNative.Native]

allocHASContext: retval [1]

[main] [ 2018-05-15 16:52:44.567 CEST ] [ClusterAlias.<init>:89]  ClusterAlias Instance created.

[main] [ 2018-05-15 16:52:45.174 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:52:45.177 CEST ] [HAUtils.<init>:331]  oui location /u01/app/oraInventory/ContentsXML

[main] [ 2018-05-15 16:52:45.231 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:52:45.231 CEST ] [OracleHome.isClientHome:1755]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:52:45.646 CEST ] [HAUtils.<init>:364]  isClientHome: false

[main] [ 2018-05-15 16:52:45.647 CEST ] [ClusterUtil.<init>:87]  ClusterUtil Instance created

[main] [ 2018-05-15 16:52:45.648 CEST ] [ClusterUtil.<init>:90]  ClusterUtil m_bSKGXN=true

[main] [ 2018-05-15 16:52:45.648 CEST ] [ClusterUtil.getCSSMode:717]  Returned from native code

[main] [ 2018-05-15 16:52:45.649 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getCSSMod

[main] [ 2018-05-15 16:52:45.649 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: mode of css: 1

[main] [ 2018-05-15 16:52:45.649 CEST ] [ClusterUtil.getCSSMode:722]  Returned from native code

[main] [ 2018-05-15 16:52:45.651 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getNodeActiveRole

[main] [ 2018-05-15 16:52:45.652 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getNodeActiveRole: after clssnsqueryrole

[main] [ 2018-05-15 16:52:45.652 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getNodeActiveRole:active role is [1]

[main] [ 2018-05-15 16:52:45.652 CEST ] [ClusterUtil.getNodeActiveRole:433]  Get NodeActiveRole 1

[main] [ 2018-05-15 16:52:45.652 CEST ] [ClusterUtil.<init>:87]  ClusterUtil Instance created

[main] [ 2018-05-15 16:52:45.653 CEST ] [ClusterUtil.<init>:90]  ClusterUtil m_bSKGXN=true

[main] [ 2018-05-15 16:52:45.655 CEST ] [ClusterUtil.getASMMode:771]  Calling native code getASMMode

[main] [ 2018-05-15 16:52:45.655 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getASMMode()

 

[main] [ 2018-05-15 16:52:45.890 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getASMMode(): returned ASM mode is [1]

 

[main] [ 2018-05-15 16:52:45.890 CEST ] [ClusterUtil.getASMMode:775]  Returned from native code

[main] [ 2018-05-15 16:52:45.891 CEST ] [ClusterUtil.getASMMode:776]  mode string returned from native code is LEGACY

[main] [ 2018-05-15 16:52:45.891 CEST ] [ASMFactoryImpl.getASMMode:4388]  ASMMode = legacy

[main] [ 2018-05-15 16:52:45.891 CEST ] [Usmca.checkIfBigClusterAndHubNode:622]  isBigCluster false

[main] [ 2018-05-15 16:52:45.892 CEST ] [Usmca.checkIfBigClusterAndHubNode:623]  isHubNode true

[main] [ 2018-05-15 16:52:45.892 CEST ] [Usmca.checkIfBigClusterAndHubNode:624]  isClientcluster false

[main] [ 2018-05-15 16:52:45.895 CEST ] [UsmcaCmdLineParser.discoverRAC:470]  Cluster Existence Check

[main] [ 2018-05-15 16:52:45.895 CEST ] [UsmcaCmdLineParser.discoverRAC:474]  cluster existence:true

[main] [ 2018-05-15 16:52:45.901 CEST ] [UsmcaLogger.logInfo:156]  AFD driver state script exists false

[main] [ 2018-05-15 16:52:45.908 CEST ] [UsmcaCmdLineParser.setDataForOperation:4507]  resolving size of disks

[main] [ 2018-05-15 16:52:45.909 CEST ] [UsmcaCmdLineParser.resolveSizeOfDisks:4899]  Disk String: '/dev/dasd*1'

[main] [ 2018-05-15 16:52:45.910 CEST ] [KfodUtil.kfodOutput:375]  /u01/app/12.2.0.1/grid/bin/kfod

[main] [ 2018-05-15 16:52:45.913 CEST ] [KfodUtil.kfodOutput:375]  nohdr=TRUE

[Finalizer thread] [ 2018-05-15 16:52:45.913 CEST ] [ClusterUtil.finalize:119]  ClusterUtil: finalized called for oracle.ops.mgmt.has.Clust                                                          erUtil@932358b2

[main] [ 2018-05-15 16:52:45.913 CEST ] [KfodUtil.kfodOutput:375]  OP=DISKS

[main] [ 2018-05-15 16:52:45.913 CEST ] [KfodUtil.kfodOutput:375]  status=true

[main] [ 2018-05-15 16:52:45.914 CEST ] [KfodUtil.kfodOutput:375]  asm_diskstring='/dev/dasd*1' disks=all

[main] [ 2018-05-15 16:52:46.024 CEST ] [KfodUtil.kfodOutput:386]  Kfod result

7042 CANDIDATE /dev/dasda1 grid     oinstall

40960 CANDIDATE /dev/dasdb1 grid     oinstall

30720 CANDIDATE /dev/dasdc1 grid     oinstall

15360 CANDIDATE /dev/dasdd1 grid     oinstall

10240 CANDIDATE /dev/dasde1 grid     oinstall

[main] [ 2018-05-15 16:52:46.028 CEST ] [UsmcaCmdLineParser.resolveSizeOfDisks:4923]  getting size of disk: /dev/dasdc1

[main] [ 2018-05-15 16:52:46.028 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasda1

[main] [ 2018-05-15 16:52:46.028 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasdb1

[main] [ 2018-05-15 16:52:46.029 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasdc1

[main] [ 2018-05-15 16:52:46.029 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4959]  disk's size found

[main] [ 2018-05-15 16:52:46.029 CEST ] [UsmcaCmdLineParser.resolveSizeOfDisks:4927]  size of disk: 30720

[main] [ 2018-05-15 16:52:46.029 CEST ] [UsmcaCmdLineParser.resolveSizeOfDisks:4923]  getting size of disk: /dev/dasde1

[main] [ 2018-05-15 16:52:46.030 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasda1

[main] [ 2018-05-15 16:52:46.030 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasdb1

[main] [ 2018-05-15 16:52:46.030 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasdc1

[main] [ 2018-05-15 16:52:46.030 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasdd1

[main] [ 2018-05-15 16:52:46.031 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4956]  disk path retrieved: /dev/dasde1

[main] [ 2018-05-15 16:52:46.031 CEST ] [UsmcaCmdLineParser.getSizeOfDisk:4959]  disk's size found

[main] [ 2018-05-15 16:52:46.031 CEST ] [UsmcaCmdLineParser.resolveSizeOfDisks:4927]  size of disk: 10240

[main] [ 2018-05-15 16:52:46.031 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.cli.UsmcaCmdLineParser Method :                                                           validateForConfigureLocalASM

[main] [ 2018-05-15 16:52:46.032 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.cli.UsmcaCmdLineParser Method :                                                           validateDiskGroups()

[main] [ 2018-05-15 16:52:46.032 CEST ] [OracleHome.getBannerVersion:1166]  OracleHome.getBannerVersion called

[main] [ 2018-05-15 16:52:46.032 CEST ] [OracleHome.getBannerVersion:1200]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:52:46.033 CEST ] [OracleHome.getBannerVersion:1204]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/gr                                                           id/jdk/jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/networ k/lib:                                                          /u01/app/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:52:46.033 CEST ] [OracleHome.getBannerVersion:1204]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:46.160 CEST ] [OracleHome.getBannerVersion:1209]  Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:52:46.160 CEST ] [OracleHome.getBannerVersion:1166]  OracleHome.getBannerVersion called

[main] [ 2018-05-15 16:52:46.160 CEST ] [OracleHome.getBannerVersion:1200]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:52:46.161 CEST ] [OracleHome.getBannerVersion:1204]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/gr                                                           id/jdk/jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/networ k/lib:                                                          /u01/app/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:52:46.161 CEST ] [OracleHome.getBannerVersion:1204]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:46.168 CEST ] [OracleHome.getBannerVersion:1209]  Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:52:46.171 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.cli.UsmcaCmdLineParser Method : v                                                          alidateDiskGroups()

[main] [ 2018-05-15 16:52:46.171 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.cli.UsmcaCmdLineParser Method : v                                                          alidateForConfigureLocalASM

[main] [ 2018-05-15 16:52:46.187 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.model.UsmcaModel Method : initi                                                          alize

[main] [ 2018-05-15 16:52:46.187 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: null

[main] [ 2018-05-15 16:52:46.189 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:52:46.189 CEST ] [OracleHome.getVersion:1037]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:52:46.319 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: null

[main] [ 2018-05-15 16:52:46.319 CEST ] [OracleHome.getVersion:1093]  using sqlplus: /u01/app/12.2.0.1/grid/bin/sqlplus

[main] [ 2018-05-15 16:52:46.319 CEST ] [OracleHome.getVersion:1125]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:52:46.319 CEST ] [OracleHome.getVersion:1129]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/jdk                                                           /jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/network/lib: /u01/a                                                          pp/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:52:46.320 CEST ] [OracleHome.getVersion:1129]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:46.343 CEST ] [OracleHome.getVersion:1134]  /u01/app/12.2.0.1/grid/bin/sqlplus Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:52:46.343 CEST ] [OracleHome.getVersion:1152]  Current version from sqlplus: 12.2.0.1.0

[main] [ 2018-05-15 16:52:46.343 CEST ] [UsmcaLogger.logInfo:156]  Software Version: 12.2.0.1.0

[main] [ 2018-05-15 16:52:46.746 CEST ] [OsUtilsBase.getBaseFromOrabase:688]  oraBaseUtility /u01/app/12.2.0.1/grid/bin/orabase

[main] [ 2018-05-15 16:52:46.746 CEST ] [OsUtilsBase.getBaseFromOrabase:697]  cmds: /u01/app/12.2.0.1/grid/bin/orabase

[main] [ 2018-05-15 16:52:46.747 CEST ] [OsUtilsBase.getBaseFromOrabase:701]  envs:  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:46.748 CEST ] [OsUtilsBase.deleteFromEnvironment:2261]  Removed from env ORACLE_BASE=/u01/app/grid

[main] [ 2018-05-15 16:52:46.751 CEST ] [OsUtilsBase.getBaseFromOrabase:711]  baseLocation from orabase

/u01/app/grid

[main] [ 2018-05-15 16:52:46.751 CEST ] [OsUtilsBase.getBaseFromOrabase:736]   orabaseLocation= /u01/app/grid

[main] [ 2018-05-15 16:52:46.966 CEST ] [NetworkUtils.checkListenerStatus:520]  Checking default listener status

[main] [ 2018-05-15 16:52:46.967 CEST ] [NetworkUtils.checkListenerStatus:525]  Checking listener status: LISTENER

[main] [ 2018-05-15 16:52:47.051 CEST ] [NetworkUtils.checkListenerStatus:531]  lsnrct status output

 

LSNRCTL for Linux: Version 12.2.0.1.0 - Production on 15-MAY-2018 16:52:46

 

Copyright (c) 1991, 2017, Oracle.  All rights reserved.

 

Connecting to (ADDRESS=(PROTOCOL=tcp)(HOST=)(PORT=1521))

TNS-12541: TNS:no listener

TNS-12560: TNS:protocol adapter error

  TNS-00511: No listener

   Linux Error: 111: Connection refused

[main] [ 2018-05-15 16:52:47.054 CEST ] [UsmcaLogger.logInfo:156]  ignoreprereqsfalse

[main] [ 2018-05-15 16:52:47.055 CEST ] [UsmcaLogger.logInfo:156]  ACFS driver state script exists false

[main] [ 2018-05-15 16:52:47.056 CEST ] [UsmcaLogger.logInfo:156]  AFD driver state script exists false

[main] [ 2018-05-15 16:52:47.057 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.model.UsmcaModel Method : initial                                                          ize

[main] [ 2018-05-15 16:52:47.058 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.model.UsmcaModel Method : perfo                                                          rmOperation

[main] [ 2018-05-15 16:52:47.062 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.model.UsmcaModel Method : perfo                                                          rmConfigureLocalASM

[main] [ 2018-05-15 16:52:47.073 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : di                                                          scoverASMInstance

[main] [ 2018-05-15 16:52:47.074 CEST ] [UsmcaLogger.logInfo:156]  oracle home discovered /u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:47.077 CEST ] [OsUtilsBase.deleteFromEnvironment:2261]  Removed from env ORACLE_BASE=/u01/app/grid

[main] [ 2018-05-15 16:52:47.383 CEST ] [InstallUtils.getOraBaseConfigLocation:583]  orabaseconfig location from orabaseconfig util /u01/ap                                                          p/12.2.0.1/grid

[main] [ 2018-05-15 16:52:47.388 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : fi                                                          ndOSAuthentication

[main] [ 2018-05-15 16:52:47.389 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : cr                                                          eateSQLEngine

[main] [ 2018-05-15 16:52:47.391 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: null

[main] [ 2018-05-15 16:52:47.393 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:52:47.393 CEST ] [OracleHome.getVersion:1037]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:52:47.535 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: null

[main] [ 2018-05-15 16:52:47.535 CEST ] [OracleHome.getVersion:1093]  using sqlplus: /u01/app/12.2.0.1/grid/bin/sqlplus

[main] [ 2018-05-15 16:52:47.535 CEST ] [OracleHome.getVersion:1125]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:52:47.535 CEST ] [OracleHome.getVersion:1129]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/jdk                                                           /jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/network/lib: /u01/a                                                          pp/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:52:47.536 CEST ] [OracleHome.getVersion:1129]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:47.581 CEST ] [OracleHome.getVersion:1134]  /u01/app/12.2.0.1/grid/bin/sqlplus Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:52:47.581 CEST ] [OracleHome.getVersion:1152]  Current version from sqlplus: 12.2.0.1.0

[main] [ 2018-05-15 16:52:47.581 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:52:47.581 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:52:47.582 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:52:47.582 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:52:47.583 CEST ] [SQLPlusEngine.getCmmdParams:226]  m_home 12.2.0.1.0

[main] [ 2018-05-15 16:52:47.583 CEST ] [SQLPlusEngine.getCmmdParams:227]  version > 112 true

[main] [ 2018-05-15 16:52:47.584 CEST ] [SQLEngine.getEnvParams:607]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:52:47.584 CEST ] [SQLEngine.getEnvParams:617]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:52:47.585 CEST ] [SQLEngine.initialize:363]  Execing SQLPLUS/SVRMGR process...

[main] [ 2018-05-15 16:52:47.586 CEST ] [SQLEngine.initialize:400]  m_bReaderStarted: false

[main] [ 2018-05-15 16:52:47.587 CEST ] [SQLEngine.initialize:404]  Starting Reader Thread...

[main] [ 2018-05-15 16:52:47.588 CEST ] [SQLEngine.initialize:453]  Waiting for m_bReaderStarted to be true

[main] [ 2018-05-15 16:52:47.605 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : crea                                                          teSQLEngine

[main] [ 2018-05-15 16:52:47.606 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: null

[main] [ 2018-05-15 16:52:47.607 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:52:47.607 CEST ] [OracleHome.getVersion:1037]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:52:47.677 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: null

[main] [ 2018-05-15 16:52:47.677 CEST ] [OracleHome.getVersion:1093]  using sqlplus: /u01/app/12.2.0.1/grid/bin/sqlplus

[main] [ 2018-05-15 16:52:47.677 CEST ] [OracleHome.getVersion:1125]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:52:47.677 CEST ] [OracleHome.getVersion:1129]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/jdk                                                           /jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/network/lib: /u01/a                                                          pp/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:52:47.677 CEST ] [OracleHome.getVersion:1129]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:47.713 CEST ] [OracleHome.getVersion:1134]  /u01/app/12.2.0.1/grid/bin/sqlplus Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:52:47.713 CEST ] [OracleHome.getVersion:1152]  Current version from sqlplus: 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.398 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : find                                                          OSAuthentication

[main] [ 2018-05-15 16:52:56.398 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : disc                                                          overASMInstance

[main] [ 2018-05-15 16:52:56.398 CEST ] [UsmcaLogger.logInfo:156]  Reuse Diskgroup false

[main] [ 2018-05-15 16:52:56.399 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : is                                                          DiskgroupsExistsForConfigure

[main] [ 2018-05-15 16:52:56.399 CEST ] [KfodUtil.kfodOutput:375]  /u01/app/12.2.0.1/grid/bin/kfod

[main] [ 2018-05-15 16:52:56.399 CEST ] [KfodUtil.kfodOutput:375]  nohdr=TRUE

[main] [ 2018-05-15 16:52:56.399 CEST ] [KfodUtil.kfodOutput:375]  OP=DISKS DISKS=ASM DS=TRUE

[main] [ 2018-05-15 16:52:56.400 CEST ] [KfodUtil.kfodOutput:375]  status=true

[main] [ 2018-05-15 16:52:56.400 CEST ] [KfodUtil.kfodOutput:375]  asm_diskstring='/dev/dasd*1'

[main] [ 2018-05-15 16:52:56.454 CEST ] [KfodUtil.kfodOutput:386]  Kfod result

[main] [ 2018-05-15 16:52:56.455 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : isDi                                                          skgroupsExistsForConfigure

[main] [ 2018-05-15 16:52:56.457 CEST ] [ClusterUtil.<init>:87]  ClusterUtil Instance created

[main] [ 2018-05-15 16:52:56.458 CEST ] [ClusterUtil.<init>:90]  ClusterUtil m_bSKGXN=true

[main] [ 2018-05-15 16:52:56.458 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getClusterName

 

[main] [ 2018-05-15 16:52:56.458 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: 1. Native: getClusterName: rc:13 sz: 15

 

[main] [ 2018-05-15 16:52:56.458 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: 3. Native: getClusterName: rc:0 sz:15 cluname:mycluster                                                          -scan

 

[main] [ 2018-05-15 16:52:56.458 CEST ] [ClusterUtil.<init>:87]  ClusterUtil Instance created

[main] [ 2018-05-15 16:52:56.459 CEST ] [ClusterUtil.<init>:90]  ClusterUtil m_bSKGXN=true

[main] [ 2018-05-15 16:52:56.459 CEST ] [ClusterUtil.getCSSMode:717]  Returned from native code

[main] [ 2018-05-15 16:52:56.459 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: Native: getCSSMod

 

[main] [ 2018-05-15 16:52:56.459 CEST ] [has.ClusterUtilNative.Native]  prsr_trace: mode of css: 1

 

[main] [ 2018-05-15 16:52:56.459 CEST ] [ClusterUtil.getCSSMode:722]  Returned from native code

[main] [ 2018-05-15 16:52:56.459 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : se                                                          tUpForLocalCreation

[main] [ 2018-05-15 16:52:56.462 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : setU                                                          pForLocalCreation

[main] [ 2018-05-15 16:52:56.463 CEST ] [UsmcaLogger.logInfo:156]  Creating ASM with SID: +ASM1

[main] [ 2018-05-15 16:52:56.463 CEST ] [UsmcaLogger.logInfo:156]  Creating temp ora file: /u01/app/12.2.0.1/grid/dbs/init+ASM1.ora

[Finalizer thread] [ 2018-05-15 16:52:56.466 CEST ] [ClusterUtil.finalize:119]  ClusterUtil: finalized called for oracle.ops.mgmt.has.Clust                                                          erUtil@65a26713

[Finalizer thread] [ 2018-05-15 16:52:56.466 CEST ] [ClusterUtil.finalize:119]  ClusterUtil: finalized called for oracle.ops.mgmt.has.Clust                                                          erUtil@c0ee85da

[main] [ 2018-05-15 16:52:56.467 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : cr                                                          eateASMInitFile

[main] [ 2018-05-15 16:52:56.467 CEST ] [USMInstance.createASMInitFile:4174]  creating init file /u01/app/12.2.0.1/grid/dbs/init+ASM1.ora

[main] [ 2018-05-15 16:52:56.470 CEST ] [InitParamAttributes.sortParams:3986]  m_sortOn:-1 sortOn:4

[main] [ 2018-05-15 16:52:56.472 CEST ] [InitParamAttributes.createInitFile:1877]  Processing init param asm_diskstring

[main] [ 2018-05-15 16:52:56.472 CEST ] [InitParamAttributes.createInitFile:1882]  The value is '/dev/dasd*1'

[main] [ 2018-05-15 16:52:56.473 CEST ] [InitParamAttributes.createInitFile:1877]  Processing init param asm_power_limit

[main] [ 2018-05-15 16:52:56.473 CEST ] [InitParamAttributes.createInitFile:1882]  The value is 1

[main] [ 2018-05-15 16:52:56.473 CEST ] [InitParamAttributes.createInitFile:1877]  Processing init param large_pool_size

[main] [ 2018-05-15 16:52:56.474 CEST ] [InitParamAttributes.createInitFile:1882]  The value is 12M

[main] [ 2018-05-15 16:52:56.474 CEST ] [InitParamAttributes.createInitFile:1877]  Processing init param remote_login_passwordfile

[main] [ 2018-05-15 16:52:56.474 CEST ] [InitParamAttributes.createInitFile:1882]  The value is EXCLUSIVE

[main] [ 2018-05-15 16:52:56.476 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : crea                                                          teASMInitFile

[main] [ 2018-05-15 16:52:56.476 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : cr                                                          eateSQLEngine

[main] [ 2018-05-15 16:52:56.476 CEST ] [SQLEngine.done:2314]  Done called

[main] [ 2018-05-15 16:52:56.480 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: null

[main] [ 2018-05-15 16:52:56.482 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:52:56.482 CEST ] [OracleHome.getVersion:1037]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:52:56.590 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: null

[main] [ 2018-05-15 16:52:56.591 CEST ] [OracleHome.getVersion:1093]  using sqlplus: /u01/app/12.2.0.1/grid/bin/sqlplus

[main] [ 2018-05-15 16:52:56.591 CEST ] [OracleHome.getVersion:1125]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:52:56.592 CEST ] [OracleHome.getVersion:1129]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/jdk                                                           /jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/network/lib: /u01/a                                                          pp/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:52:56.593 CEST ] [OracleHome.getVersion:1129]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:56.610 CEST ] [OracleHome.getVersion:1134]  /u01/app/12.2.0.1/grid/bin/sqlplus Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:52:56.611 CEST ] [OracleHome.getVersion:1152]  Current version from sqlplus: 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.611 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.611 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.613 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.614 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.614 CEST ] [SQLPlusEngine.getCmmdParams:226]  m_home 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.614 CEST ] [SQLPlusEngine.getCmmdParams:227]  version > 112 true

[main] [ 2018-05-15 16:52:56.614 CEST ] [SQLEngine.getEnvParams:607]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:52:56.614 CEST ] [SQLEngine.getEnvParams:617]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:52:56.615 CEST ] [SQLEngine.initialize:363]  Execing SQLPLUS/SVRMGR process...

[main] [ 2018-05-15 16:52:56.618 CEST ] [SQLEngine.initialize:400]  m_bReaderStarted: false

[main] [ 2018-05-15 16:52:56.620 CEST ] [SQLEngine.initialize:404]  Starting Reader Thread...

[main] [ 2018-05-15 16:52:56.620 CEST ] [SQLEngine.initialize:453]  Waiting for m_bReaderStarted to be true

[main] [ 2018-05-15 16:52:56.633 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : crea                                                          teSQLEngine

[main] [ 2018-05-15 16:52:56.633 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: null

[main] [ 2018-05-15 16:52:56.637 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:52:56.637 CEST ] [OracleHome.getVersion:1037]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:52:56.722 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: null

[main] [ 2018-05-15 16:52:56.722 CEST ] [OracleHome.getVersion:1093]  using sqlplus: /u01/app/12.2.0.1/grid/bin/sqlplus

[main] [ 2018-05-15 16:52:56.723 CEST ] [OracleHome.getVersion:1125]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:52:56.723 CEST ] [OracleHome.getVersion:1129]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/jdk                                                           /jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/network/lib: /u01/a                                                          pp/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:52:56.723 CEST ] [OracleHome.getVersion:1129]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:52:56.743 CEST ] [OracleHome.getVersion:1134]  /u01/app/12.2.0.1/grid/bin/sqlplus Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:52:56.744 CEST ] [OracleHome.getVersion:1152]  Current version from sqlplus: 12.2.0.1.0

[main] [ 2018-05-15 16:52:56.744 CEST ] [UsmcaLogger.logInfo:156]  Role SYSASM

[main] [ 2018-05-15 16:52:56.744 CEST ] [UsmcaLogger.logInfo:156]  OS Auth true

[main] [ 2018-05-15 16:52:57.761 CEST ] [UsmcaLogger.logInfo:156]  Shutdown running instance, if any.

[main] [ 2018-05-15 16:53:00.774 CEST ] [UsmcaLogger.logEntry:131]  Entering oracle.sysman.assistants.usmca.backend.USMInstance Method : cr                                                          eateSQLEngine

[main] [ 2018-05-15 16:53:00.774 CEST ] [SQLEngine.done:2314]  Done called

[main] [ 2018-05-15 16:53:00.775 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: null

[main] [ 2018-05-15 16:53:00.777 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:53:00.779 CEST ] [OracleHome.getVersion:1037]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:53:00.867 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: null

[main] [ 2018-05-15 16:53:00.867 CEST ] [OracleHome.getVersion:1093]  using sqlplus: /u01/app/12.2.0.1/grid/bin/sqlplus

[main] [ 2018-05-15 16:53:00.867 CEST ] [OracleHome.getVersion:1125]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:53:00.867 CEST ] [OracleHome.getVersion:1129]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/jdk                                                           /jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/network/lib: /u01/a                                                          pp/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:53:00.867 CEST ] [OracleHome.getVersion:1129]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:53:00.877 CEST ] [OracleHome.getVersion:1134]  /u01/app/12.2.0.1/grid/bin/sqlplus Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:53:00.877 CEST ] [OracleHome.getVersion:1152]  Current version from sqlplus: 12.2.0.1.0

[main] [ 2018-05-15 16:53:00.877 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:53:00.877 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:53:00.878 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:53:00.878 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:53:00.878 CEST ] [SQLPlusEngine.getCmmdParams:226]  m_home 12.2.0.1.0

[main] [ 2018-05-15 16:53:00.878 CEST ] [SQLPlusEngine.getCmmdParams:227]  version > 112 true

[main] [ 2018-05-15 16:53:00.878 CEST ] [SQLEngine.getEnvParams:607]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:53:00.878 CEST ] [SQLEngine.getEnvParams:617]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:53:00.879 CEST ] [SQLEngine.initialize:363]  Execing SQLPLUS/SVRMGR process...

[main] [ 2018-05-15 16:53:00.880 CEST ] [SQLEngine.initialize:400]  m_bReaderStarted: false

[main] [ 2018-05-15 16:53:00.880 CEST ] [SQLEngine.initialize:404]  Starting Reader Thread...

[main] [ 2018-05-15 16:53:00.890 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : crea                                                          teSQLEngine

[main] [ 2018-05-15 16:53:00.890 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: null

[main] [ 2018-05-15 16:53:00.891 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS

[main] [ 2018-05-15 16:53:00.891 CEST ] [OracleHome.getVersion:1037]  Homeinfo /u01/app/12.2.0.1/grid,1

[main] [ 2018-05-15 16:53:00.940 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: null

[main] [ 2018-05-15 16:53:00.940 CEST ] [OracleHome.getVersion:1093]  using sqlplus: /u01/app/12.2.0.1/grid/bin/sqlplus

[main] [ 2018-05-15 16:53:00.941 CEST ] [OracleHome.getVersion:1125]  adding oracle home to sqlplus env

[main] [ 2018-05-15 16:53:00.941 CEST ] [OracleHome.getVersion:1129]  LD_LIBRARY_PATH=/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/jdk                                                           /jre/lib/s390x/compressedrefs:/u01/app/12.2.0.1/grid/jdk/jre/lib/s390x:/u01/app/12.2.0.1/grid/lib:/u01/app/12.2.0.1/grid/network/lib: /u01/a                                                          pp/12.2.0.1/grid/lib::/usr/lib

[main] [ 2018-05-15 16:53:00.941 CEST ] [OracleHome.getVersion:1129]  ORACLE_HOME=/u01/app/12.2.0.1/grid

[main] [ 2018-05-15 16:53:00.948 CEST ] [OracleHome.getVersion:1134]  /u01/app/12.2.0.1/grid/bin/sqlplus Banner:

 

SQL*Plus: Release 12.2.0.1.0 Production

 

[main] [ 2018-05-15 16:53:00.949 CEST ] [OracleHome.getVersion:1152]  Current version from sqlplus: 12.2.0.1.0

[main] [ 2018-05-15 16:53:00.949 CEST ] [UsmcaLogger.logInfo:156]  Role SYSASM

[main] [ 2018-05-15 16:53:00.949 CEST ] [UsmcaLogger.logInfo:156]  OS Auth true

[main] [ 2018-05-15 16:54:06.021 CEST ] [SQLEngine.done:2314]  Done called

[main] [ 2018-05-15 16:54:06.021 CEST ] [USMInstance.configureLocalASM:3367]  ORA-39510: CRS error performing start on instance '+ASM1' on                                                           '+ASM'

 

[main] [ 2018-05-15 16:54:06.021 CEST ] [SQLEngine.reInitialize:806]  Reinitializing SQLEngine...

[main] [ 2018-05-15 16:54:06.021 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:54:06.021 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:54:06.022 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:54:06.022 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:54:06.022 CEST ] [OracleHome.getVersion:1018]  OracleHome.getVersion called.  Current Version: 12.2.0.1.0

[main] [ 2018-05-15 16:54:06.022 CEST ] [OracleHome.getVersion:1086]  Current Version From Inventory: 12.2.0.1.0

[main] [ 2018-05-15 16:54:06.022 CEST ] [SQLPlusEngine.getCmmdParams:226]  m_home 12.2.0.1.0

[main] [ 2018-05-15 16:54:06.023 CEST ] [SQLPlusEngine.getCmmdParams:227]  version > 112 true

[main] [ 2018-05-15 16:54:06.023 CEST ] [SQLEngine.getEnvParams:607]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:54:06.023 CEST ] [SQLEngine.getEnvParams:617]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8

[main] [ 2018-05-15 16:54:06.024 CEST ] [SQLEngine.initialize:363]  Execing SQLPLUS/SVRMGR process...

[main] [ 2018-05-15 16:54:06.025 CEST ] [SQLEngine.initialize:400]  m_bReaderStarted: false

[main] [ 2018-05-15 16:54:06.026 CEST ] [SQLEngine.initialize:404]  Starting Reader Thread...

[main] [ 2018-05-15 16:54:06.026 CEST ] [SQLEngine.initialize:453]  Waiting for m_bReaderStarted to be true

[main] [ 2018-05-15 16:54:07.055 CEST ] [SQLEngine.done:2314]  Done called

[main] [ 2018-05-15 16:54:07.056 CEST ] [UsmcaLogger.logException:186]  SEVERE:method oracle.sysman.assistants.usmca.backend.USMInstance:co                                                          nfigureLocalASM

[main] [ 2018-05-15 16:54:07.056 CEST ] [UsmcaLogger.logException:187]  ORA-01034: ORACLE not available

 

[main] [ 2018-05-15 16:54:07.056 CEST ] [UsmcaLogger.logException:188]  oracle.sysman.assistants.util.sqlEngine.SQLFatalErrorException: ORA                                                          -01034: ORACLE not available

 

oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeImpl(SQLEngine.java:1767)

oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeQuery(SQLEngine.java:899)

oracle.sysman.assistants.usmca.backend.USMInstance.configureLocalASM(USMInstance.java:3371)

oracle.sysman.assistants.usmca.service.UsmcaService.configureLocalASM(UsmcaService.java:1349)

oracle.sysman.assistants.usmca.model.UsmcaModel.performConfigureLocalASM(UsmcaModel.java:1110)

oracle.sysman.assistants.usmca.model.UsmcaModel.performOperation(UsmcaModel.java:906)

oracle.sysman.assistants.usmca.Usmca.execute(Usmca.java:320)

oracle.sysman.assistants.usmca.Usmca.main(Usmca.java:653)

[main] [ 2018-05-15 16:54:07.204 CEST ] [UsmcaLogger.logException:186]  SEVERE:method oracle.sysman.assistants.usmca.backend.USMInstance:co                                                          nfigureLocalASM

[main] [ 2018-05-15 16:54:07.204 CEST ] [UsmcaLogger.logException:187]  ORA-39510: CRS error performing start on instance '+ASM1' on '+ASM'

 

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logException:188]  oracle.sysman.assistants.util.sqlEngine.SQLFatalErrorException: ORA                                                          -39510: CRS error performing start on instance '+ASM1' on '+ASM'

 

oracle.sysman.assistants.util.sqlEngine.SQLEngine.executeImpl(SQLEngine.java:1767)

oracle.sysman.assistants.util.sqlEngine.SQLEngine.startup(SQLEngine.java:2209)

oracle.sysman.assistants.usmca.backend.USMInstance.configureLocalASM(USMInstance.java:3361)

oracle.sysman.assistants.usmca.service.UsmcaService.configureLocalASM(UsmcaService.java:1349)

oracle.sysman.assistants.usmca.model.UsmcaModel.performConfigureLocalASM(UsmcaModel.java:1110)

oracle.sysman.assistants.usmca.model.UsmcaModel.performOperation(UsmcaModel.java:906)

oracle.sysman.assistants.usmca.Usmca.execute(Usmca.java:320)

oracle.sysman.assistants.usmca.Usmca.main(Usmca.java:653)

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logInfo:156]  ASM failed to start. Check /u01/app/grid/cfgtoollogs/asmca/asmca-180515P                                                          M045244.log for details.

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logInfo:156]  Instance running false

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logInfo:156]  ASM failed to start. Check /u01/app/grid/cfgtoollogs/asmca/asmca-180515P                                                          M045244.log for details.

[main] [ 2018-05-15 16:54:07.205 CEST ] [OsUtilsBase.deleteFile:1832]  OsUtilsBase.deleteFile: /u01/app/12.2.0.1/grid/dbs/init+ASM1.ora

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logInfo:156]  deleting temp ora file. for sid: /u01/app/12.2.0.1/grid/dbs/init+ASM1.or                                                          a

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.backend.USMInstance Method : conf                                                          igureLocalASM

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.model.UsmcaModel Method : perform                                                          ConfigureLocalASM

[main] [ 2018-05-15 16:54:07.205 CEST ] [UsmcaLogger.logExit:139]  Exiting oracle.sysman.assistants.usmca.model.UsmcaModel Method : perform                                                          Operation
Re: Root.sh failed: ORA-39510: CRS error performing start on instance '+ASM1' on '+ASM' [message #669858 is a reply to message #669856] Thu, 17 May 2018 17:21 Go to previous message
John Watson
Messages: 7399
Registered: January 2010
Location: Global Village
Senior Member
Welcome to the forum. Please read the OraFAQ Forum Guide and How to use code tags and make your code easier to read

You say "installation of Oracle RAC". Do you mean installing Grid Infrastructure or installing the database software?

What is the result of running
crsctl stat res -t -init
crsctl stat res -t
Previous Topic: [INS-30512] Automatic Storage Management software is not configured on this cluster
Next Topic: Oracle cold failover cluster
Goto Forum:
  


Current Time: Fri May 25 15:28:17 CDT 2018