Root.sh failed: ORA-39510: CRS error performing start on instance '+ASM1' on '+ASM' [message #669856] |
Thu, 17 May 2018 10:50 |
|
sarahLian
Messages: 10 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 #671364 is a reply to message #671363] |
Fri, 24 August 2018 12:23 |
John Watson
Messages: 8960 Registered: January 2010 Location: Global Village
|
Senior Member |
|
|
If you look at which components are still offline, you will see that they are those that deal with the interconnect and with the shared storage. So your problem is with one, or both, of those.
You could try starting them manually. For example,
crsctl start resource ora.cluster_interconnect.haip -unsupported if I remember correctly. Or you could run the cluvfy utility in various forms to test each component. I am surprised it didn't throw any errors earlier. You did run it, did you?
|
|
|
|
|
|
|
Re: Root.sh failed: ORA-39510: CRS error performing start on instance '+ASM1' on '+ASM' [message #671382 is a reply to message #671380] |
Mon, 27 August 2018 09:32 |
John Watson
Messages: 8960 Registered: January 2010 Location: Global Village
|
Senior Member |
|
|
sarahLian wrote on Mon, 27 August 2018 14:41I didn't ignore your recommendations. I'm looking for any suggestions to solve the issue of the installation.
Yes I used this command but nothing different from the previous error ("Signature for storage...):
./runcluvfy.sh stage -pre cfs -n rac1,rac2 -s /dev/ASM0401.
I tried to restart the components manually but it doesn't work.
./crsctl start resource ora.cluster_interconnect.haip -unsupported
CRS-4535: Cannot communicate with Cluster Ready Services
CRS-4000: Command Start failed, or completed with errors.
I said you need to run cluvfy stage -pre crsinst, and also to test the various components such as shared disc access. And you tell me you ran -pre cfs. What for? You need to read the clufvy documentation, or a least look at the online help: $ cluvfy stage -list
USAGE:
cluvfy stage {-pre|-post} <stage-name> <stage-specific options> [-verbose]
Valid Stages are:
-pre cfs : pre-check for CFS setup
-pre crsinst : pre-check for CRS installation
-pre acfscfg : pre-check for ACFS Configuration.
-pre dbinst : pre-check for database installation
-pre dbcfg : pre-check for database configuration
-pre hacfg : pre-check for HA configuration
-pre nodeadd : pre-check for node addition.
-post hwos : post-check for hardware and operating system
-post cfs : post-check for CFS setup
-post crsinst : post-check for CRS installation
-post acfscfg : post-check for ACFS Configuration.
-post hacfg : post-check for HA configuration
-post nodeadd : post-check for node addition.
-post nodedel : post-check for node deletion.
$ cluvfy comp -list
USAGE:
cluvfy comp <component-name> <component-specific options> [-verbose]
Valid Components are:
nodereach : checks reachability between nodes
nodecon : checks node connectivity
cfs : checks CFS integrity
ssa : checks shared storage accessibility
space : checks space availability
sys : checks minimum system requirements
clu : checks cluster integrity
clumgr : checks cluster manager integrity
ocr : checks OCR integrity
olr : checks OLR integrity
ha : checks HA integrity
freespace : checks free space in CRS Home
crs : checks CRS integrity
nodeapp : checks node applications existence
admprv : checks administrative privileges
peer : compares properties with peers
software : checks software distribution
acfs : checks ACFS integrity
asm : checks ASM integrity
gpnp : checks GPnP integrity
gns : checks GNS integrity
scan : checks SCAN configuration
ohasd : checks OHASD integrity
clocksync : checks Clock Synchronization
vdisk : checks Voting Disk configuration and UDEV settings
healthcheck : checks mandatory requirements and/or best practice recommendations
dhcp : checks DHCP configuration
dns : checks DNS configuration
baseline : collect and compare baselines
$
As for the manual startyup, now that you have told me the error, I can make the correction. Trycrsctl start resource ora.cluster_interconnect.haip -unsupported -init
|
|
|
|
|
|
|
|