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)
| 1 |
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/network/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/network/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