1 2 Previous Next 15 Replies Latest reply on May 25, 2018 7:11 AM by Mishra1

    ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2

    898191

      Hi

       

      I have installed ASM (GI) on standalone server and completed successfully and facing the below issue while starting the ASM after manually rebooting the server.

      DB software has not yet been installed.

       

      DB - 12.2.0.1

      OS - RHEL (7.4)

       

      Error:

      sqlplus / as sysdba

      SQL*Plus: Release 12.2.0.1.0 Production on Wed May 16 03:13:41 2018

      Copyright (c) 1982, 2016, Oracle.  All rights reserved.

      Connected to an idle instance.

       

      SQL> startup;

      ORA-01078: failure in processing system parameters

      ORA-29701: unable to connect to Cluster Synchronization Service

       

      crsctl status resource -t

      CRS-4639: Could not contact Oracle High Availability Services

      CRS-4000: Command Status failed, or completed with errors.

        • 1. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
          Mishra1

          Hi

          Could try connecting as sysasm and trying starting and share below details

           

          1)alert log of asm instance.

          2)alert log of grid.

          • 2. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
            Cijo-Oracle

            Check if the cssd service is up.

             

            $GRID_HOME/bin/crsctl stat res -t

             

            Try to start has as below

             

            crsctl start has

             

            check again to see if ASM/css is up

            • 3. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
              Dude!

              What account are you using to startup ASM?

              Why are you connecting as sysdba and not sysasm to startup ASM?

               

              https://docs.oracle.com/database/121/OSTMG/GUID-5BD01B1E-6B43-4878-B875-7DB428F8E7BA.htm

               

              I suggest to login as the software owner of your GI installation, which could be grid instead of oracle depending on how you installed the software. I have the following in my notes:

               

              SQL> startup

              ORA-01078: failure in processing system parameters

              ORA-29701: unable to connect to Cluster Synchronization Service

               

              $ srvctl start asm

              • 4. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                898191

                sqlplus / as sysasm

                 

                SQL*Plus: Release 12.2.0.1.0 Production on Wed May 16 21:35:49 2018

                Copyright (c) 1982, 2016, Oracle.  All rights reserved.

                Connected to an idle instance.

                 

                SQL> startup;

                ORA-01078: failure in processing system parameters

                ORA-29701: unable to connect to Cluster Synchronization Service

                • 5. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                  898191

                  The errors are same even after i tried connecting using sysasm

                   

                  Log file as below.

                   

                  Starting background process DIAG

                  Starting background process PMAN

                  2018-05-15T00:39:46.429539+00:00

                  DIAG started with pid=11, OS id=28837

                  2018-05-15T00:39:46.472660+00:00

                  PMAN started with pid=12, OS id=28843

                  Starting background process DIA0

                  2018-05-15T00:39:46.535433+00:00

                  DIA0 started with pid=13, OS id=28850

                  Starting background process DBW0

                  2018-05-15T00:39:46.601550+00:00

                  DBW0 started with pid=14, OS id=28858

                  Starting background process LGWR

                  2018-05-15T00:39:46.660657+00:00

                  LGWR started with pid=15, OS id=28864

                  Starting background process CKPT

                  2018-05-15T00:39:46.713554+00:00

                  CKPT started with pid=16, OS id=28872

                  Starting background process SMON

                  2018-05-15T00:39:46.734832+00:00

                  SMON started with pid=8, OS id=28879

                  Starting background process LREG

                  2018-05-15T00:39:46.761331+00:00

                  LREG started with pid=17, OS id=28885

                  Starting background process PXMN

                  2018-05-15T00:39:46.796121+00:00

                  PXMN started with pid=18, OS id=28892

                  Starting background process RBAL

                  2018-05-15T00:39:46.817751+00:00

                  RBAL started with pid=19, OS id=28900

                  Starting background process GMON

                  Starting background process MMON

                  2018-05-15T00:39:46.839153+00:00

                  GMON started with pid=20, OS id=28908

                  2018-05-15T00:39:46.860029+00:00

                  MMON started with pid=21, OS id=28914

                  Starting background process MMNL

                  2018-05-15T00:39:46.880540+00:00

                  MMNL started with pid=22, OS id=28921

                  2018-05-15T00:39:46.959787+00:00

                  NOTE: Loaded library: /opt/oracle/extapi/64/asm/orcl/1/libasm.so

                  ORACLE_BASE from environment = /u01/app/oracle

                  2018-05-15T00:39:47.092676+00:00

                  SQL> ALTER DISKGROUP ALL MOUNT /* asm agent call crs *//* {0:0:302} */

                  2018-05-15T00:39:47.116339+00:00

                  Diskgroup with spfile:DATA

                  NOTE: cache registered group DATA 1/0xB0E4B43C

                  NOTE: cache began mount (first) of group DATA 1/0xB0E4B43C

                  NOTE: Assigning number (1,0) to disk (/dev/oracleasm/disks/DATA)

                  2018-05-15T00:39:53.244369+00:00

                  NOTE: GMON heartbeating for grp 1 (DATA)

                  GMON querying group 1 at 3 for pid 23, osid 28935

                  2018-05-15T00:39:53.245307+00:00

                  NOTE: cache is mounting group DATA created on 2018/05/15 00:39:07

                  NOTE: cache opening disk 0 of grp 1: DATA_0000 path:/dev/oracleasm/disks/DATA

                  NOTE: 05/15/18 00:39:53 DATA.F1X0 found on disk 0 au 10 fcn 0.0 datfmt 1

                  2018-05-15T00:39:53.245561+00:00

                  NOTE: cache mounting (first) external redundancy group 1/0xB0E4B43C (DATA)

                  NOTE: cache recovered group 1 to fcn 0.1751

                  NOTE: redo buffer size is 512 blocks (2105344 bytes)

                  2018-05-15T00:39:53.260530+00:00

                  NOTE: LGWR attempting to mount thread 1 for diskgroup 1 (DATA)

                  NOTE: LGWR found thread 1 closed at ABA 2.60 lock domain=0 inc#=0 instnum=1

                  NOTE: LGWR mounted thread 1 for diskgroup 1 (DATA)

                  2018-05-15T00:39:53.294333+00:00

                  NOTE: LGWR opened thread 1 (DATA) at fcn 0.1751 ABA 3.61 lock domain=1 inc#=0 instnum=1 gx.incarn=2967778364 mntstmp=2018/05/15 00:39:53.262000

                  2018-05-15T00:39:53.294467+00:00

                  NOTE: cache mounting group 1/0xB0E4B43C (DATA) succeeded

                  NOTE: cache ending mount (success) of group DATA number=1 incarn=0xb0e4b43c

                  2018-05-15T00:39:53.305395+00:00

                  NOTE: Instance updated compatible.asm to 12.2.0.1.0 for grp 1 (DATA).

                  2018-05-15T00:39:53.306271+00:00

                  NOTE: Instance updated compatible.rdbms to 10.1.0.0.0 for grp 1 (DATA).

                  2018-05-15T00:39:53.307478+00:00

                  SUCCESS: diskgroup DATA was mounted

                  ORACLE_BASE value has been saved for future startups

                  2018-05-15T00:39:53.337873+00:00

                  SUCCESS: ALTER DISKGROUP ALL MOUNT /* asm agent call crs *//* {0:0:302} */

                  2018-05-15T00:39:53.396883+00:00

                  NOTE: diskgroup resource ora.DATA.dg is online

                  2018-05-15T00:50:56.031833+00:00

                  ALTER SYSTEM SET local_listener=' (ADDRESS=(PROTOCOL=TCP)(HOST=10.0.0.8)(PORT=1521))' SCOPE=MEMORY SID='+ASM';

                  2018-05-15T01:00:55.983315+00:00

                  SQL> ALTER DISKGROUP DATA DISMOUNT /* asm agent stack shutdown *//* {0:0:357} */

                  2018-05-15T01:00:55.987235+00:00

                  NOTE: Active use of SPFILE in group DATA

                  ORA-15032: not all alterations performed

                  ORA-15027: active use of diskgroup "DATA" precludes its dismount

                   

                   

                  2018-05-15T01:00:55.989219+00:00

                  ERROR: ALTER DISKGROUP DATA DISMOUNT /* asm agent stack shutdown *//* {0:0:357} */

                  Shutting down instance (immediate) (OS id: 32595)

                  Shutting down instance: further logons disabled

                  Stopping background process MMNL

                  Stopping background process MMON

                  2018-05-15T01:00:57.157069+00:00

                  License high water mark = 5

                  2018-05-15T01:00:59.159842+00:00

                  SQL> ALTER DISKGROUP ALL DISMOUNT /* asm agent *//* {0:0:357} */

                  2018-05-15T01:00:59.160336+00:00

                  NOTE: cache dismounting (clean) group 1/0xB0E4B43C (DATA)

                  NOTE: messaging CKPT to quiesce pins Unix process pid: 32595, image: oracle@RHEL74testserver (TNS V1-V3)

                  2018-05-15T01:00:59.236553+00:00

                  NOTE: LGWR doing clean dismount of group 1 (DATA) thread 1

                  NOTE: LGWR closing thread 1 of diskgroup 1 (DATA) at ABA 3.122

                  2018-05-15T01:00:59.286161+00:00

                  NOTE: cache dismounted group 1/0xB0E4B43C (DATA)

                  2018-05-15T01:00:59.314733+00:00

                  GMON dismounting group 1 at 5 for pid 24, osid 32595

                  2018-05-15T01:00:59.335426+00:00

                  NOTE: Disk DATA_0000 in mode 0x7f marked for de-assignment

                  2018-05-15T01:00:59.337715+00:00

                  SUCCESS: diskgroup DATA was dismounted

                  NOTE: cache deleting context for group DATA 1/0xb0e4b43c

                  2018-05-15T01:00:59.338117+00:00

                  SUCCESS: ALTER DISKGROUP ALL DISMOUNT /* asm agent *//* {0:0:357} */

                  Shutting down archive processes

                  Archiving is disabled

                  2018-05-15T01:01:00.442324+00:00

                  Stopping background process VKTM

                  2018-05-15T01:01:00.449256+00:00

                  Shutting down archive processes

                  Archiving is disabled

                  2018-05-15T01:01:02.588252+00:00

                  Instance shutdown complete (OS id: 32595)

                  • 6. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                    898191

                    I logged in as Grid which used for installing the software.

                     

                    Errors are same though i tried using Sysasm.

                    • 7. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                      898191

                      crsctl stat res -t

                      CRS-4639: Could not contact Oracle High Availability Services

                      CRS-4000: Command Status failed, or completed with errors

                       

                       

                      crsctl start has

                      CRS-4123: Oracle High Availability Services has been started.

                      • 8. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                        Dude!

                        So, did you try:

                         

                        $ srvctl start asm

                         

                        • 9. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                          Mishra1

                          Hi

                          Could you as well please share the alertlogfile of grid.

                          I.e Grid_Base/diag/crs/hostname/crs/trace as well ohasd.log

                          • 10. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                            Deepak Kumar K

                            Please post content of grid alert log, crs trace, css trace files.

                            • 11. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                              898191

                              vi alert.log

                              2018-05-15 00:34:44.601 [CLSECHO(22489)]CLSRSC-0567: Beginning Oracle Grid Infrastructure configuration.

                              2018-05-15 00:34:46.407 [CLSECHO(22656)]AFD-9202: AFD can not be installed/loaded because ASMLib is installed.

                              2018-05-15 00:34:48.942 [OCRCONFIG(22784)]CRS-2101: The OLR was formatted using version 4.

                              2018-05-15 00:34:51.097 [CLSCFG(22859)]CRS-1001: The OCR was formatted using version 4.

                              2018-05-15 00:35:04.183 [OHASD(23077)]CRS-8500: Oracle Clusterware OHASD process is starting with operating system process ID 23077

                              2018-05-15 00:35:04.187 [OHASD(23077)]CRS-0714: Oracle Clusterware Release 12.2.0.1.0.

                              2018-05-15 00:35:04.194 [OHASD(23077)]CRS-2112: The OLR service started on node rhel74testserver.

                              2018-05-15 00:35:04.229 [OHASD(23077)]CRS-1301: Oracle High Availability Service started on node rhel74testserver.

                              2018-05-15 00:35:29.383 [ORAAGENT(23437)]CRS-8500: Oracle Clusterware ORAAGENT process is starting with operating system process ID 23437

                              2018-05-15 00:35:29.683 [EVMD(23455)]CRS-8500: Oracle Clusterware EVMD process is starting with operating system process ID 23455

                              2018-05-15 00:37:29.699 [CSSDAGENT(24241)]CRS-8500: Oracle Clusterware CSSDAGENT process is starting with operating system process ID 24241

                              2018-05-15 00:37:31.783 [ORAROOTAGENT(24243)]CRS-8500: Oracle Clusterware ORAROOTAGENT process is starting with operating system process ID 24243

                              2018-05-15 00:37:32.234 [OCSSD(24275)]CRS-8500: Oracle Clusterware OCSSD process is starting with operating system process ID 24275

                              2018-05-15 00:37:33.263 [OCSSD(24275)]CRS-1713: CSSD daemon is started in hub mode

                              2018-05-15 00:37:42.269 [OCSSD(24275)]CRS-1601: CSSD Reconfiguration complete. Active nodes are rhel74testserver .

                              2018-05-15 00:37:45.409 [OCSSD(24275)]CRS-1720: Cluster Synchronization Services daemon (CSSD) is ready for operation.

                              2018-05-15 00:38:18.473 [CLSECHO(25810)]ACFS-9500: Location of Oracle Home is '/u01/app/oracle/gridsw' as determined from the internal configuration data

                              2018-05-15 00:39:15.253 [CLSECHO(27626)]ACFS-9300: ADVM/ACFS distribution files found.

                              2018-05-15 00:39:15.562 [CLSECHO(27722)]ACFS-9314: Removing previous ADVM/ACFS installation.

                              2018-05-15 00:39:20.199 [CLSECHO(27758)]ACFS-9315: Previous ADVM/ACFS components successfully removed.

                              2018-05-15 00:39:20.279 [CLSECHO(27783)]ACFS-9307: Installing requested ADVM/ACFS software.

                              2018-05-15 00:39:20.305 [CLSECHO(27791)]ACFS-9503: ADVM and ACFS driver media location is '/u01/app/oracle/gridsw/usm/install/Oracle/EL7/x86_64/3.10.0-229/3.10.0-229-x86_64/bin'

                              2018-05-15 00:39:22.726 [CLSECHO(28033)]ACFS-9308: Loading installed ADVM/ACFS drivers.

                              2018-05-15 00:39:22.803 [CLSECHO(28041)]ACFS-9321: Creating udev for ADVM/ACFS.

                              2018-05-15 00:39:22.828 [CLSECHO(28049)]ACFS-9323: Creating module dependencies - this may take some time.

                              2018-05-15 00:39:51.331 [CLSECHO(29004)]ACFS-9154: Loading 'oracleoks.ko' driver.

                              2018-05-15 00:39:51.464 [CLSECHO(29018)]ACFS-9109: oracleoks.ko driver failed to load.

                              2018-05-15 00:39:51.547 [CLSECHO(29042)]ACFS-9428: Failed to load ADVM/ACFS drivers. A system reboot is recommended.

                              2018-05-15 00:39:51.578 [CLSECHO(29050)]ACFS-9310: ADVM/ACFS installation failed.

                              2018-05-15 01:01:02.655 [OCSSD(24275)]CRS-1603: CSSD on node rhel74testserver has been shut down.

                              2018-05-15 01:01:03.961 [OCSSD(24275)]CRS-1660: The CSS daemon shutdown has completed

                              2018-05-15 01:01:03.961 [OCSSD(24275)]CRS-8504: Oracle Clusterware OCSSD process with operating system process ID 24275 is exiting

                              2018-05-16 23:58:46.287 [CLSECHO(17267)]CRS-6021: Could not set system resource limits for Clusterware: "ulimit -l unlimited".

                              2018-05-16 23:58:46.301 [CLSECHO(17268)]CRS-6021: Could not set system resource limits for Clusterware: "ulimit -u 65536".

                              2018-05-16 23:58:53.111 [OHASD(17260)]CRS-8500: Oracle Clusterware OHASD process is starting with operating system process ID 17260

                              2018-05-16 23:58:53.158 [OHASD(17260)]CRS-0714: Oracle Clusterware Release 12.2.0.1.0.

                              2018-05-16 23:58:53.167 [OHASD(17260)]CRS-2112: The OLR service started on node rhel74testserver.

                              2018-05-16 23:58:53.179 [OHASD(17260)]CRS-1301: Oracle High Availability Service started on node rhel74testserver.

                              2018-05-16 23:58:53.811 [CSSDAGENT(17339)]CRS-8500: Oracle Clusterware CSSDAGENT process is starting with operating system process ID 17339

                              2018-05-16 23:58:54.761 [ORAAGENT(17333)]CRS-8500: Oracle Clusterware ORAAGENT process is starting with operating system process ID 17333

                              2018-05-16 23:58:54.861 [ORAROOTAGENT(17343)]CRS-8500: Oracle Clusterware ORAROOTAGENT process is starting with operating system process ID 17343

                              2018-05-16 23:58:55.461 [ORAAGENT(17392)]CRS-8500: Oracle Clusterware ORAAGENT process is starting with operating system process ID 17392

                              2018-05-16 23:58:55.761 [EVMD(17413)]CRS-8500: Oracle Clusterware EVMD process is starting with operating system process ID 17413

                              2018-05-16 23:59:00.171 [CSSDAGENT(17490)]CRS-8500: Oracle Clusterware CSSDAGENT process is starting with operating system process ID 17490

                              2018-05-16 23:59:00.210 [ORAROOTAGENT(17492)]CRS-8500: Oracle Clusterware ORAROOTAGENT process is starting with operating system process ID 17492

                              2018-05-16 23:59:00.521 [OCSSD(17522)]CRS-8500: Oracle Clusterware OCSSD process is starting with operating system process ID 17522

                              2018-05-16 23:59:01.564 [OCSSD(17522)]CRS-1713: CSSD daemon is started in hub mode

                              2018-05-16 23:59:10.151 [OCSSD(17522)]CRS-1601: CSSD Reconfiguration complete. Active nodes are rhel74testserver .

                              2018-05-16 23:59:13.512 [OCSSD(17522)]CRS-1720: Cluster Synchronization Services daemon (CSSD) is ready for operation.

                              2018-05-17 00:19:02.337 [OCSSD(17522)]CRS-1603: CSSD on node rhel74testserver has been shut down.

                              2018-05-17 00:19:03.651 [OCSSD(17522)]CRS-1660: The CSS daemon shutdown has completed

                              2018-05-17 00:19:03.651 [OCSSD(17522)]CRS-8504: Oracle Clusterware OCSSD process with operating system process ID 17522 is exiting

                               

                               

                               

                              vi ohasd.trc

                               

                               

                              2018-05-17 00:19:03.341 :   CRSPE:933218048: {0:0:119} Placement impossible due to placement policy: no online server passed placement policy filter for [ora.cssd 1 1] : 1

                              2018-05-17 00:19:03.341 :   CRSPE:933218048: {0:0:119} Exception while evaluating shutdown (start) of [ora.cssd 1 1] | Error stack:CRS-2632: There are no more servers to try to place resource 'ora.cssd' on that would satisfy its placement policy

                               

                               

                              2018-05-17 00:19:03.341 :   CRSPE:933218048: {0:0:119} Starting shutdown stage 3: 0x7f6d382ae4a0

                              2018-05-17 00:19:03.341 :   CRSPE:933218048: {0:0:119} Disabled new shutdown processing

                              2018-05-17 00:19:03.341 :   CRSPE:933218048: {0:0:119} Sending shutdown sync msg for rhel74testserver

                              2018-05-17 00:19:03.341 :UiServer:929015552: {0:0:119} Response: c4|5!ORDERk7|MESSAGEt60|CRS-2677: Stop of 'ora.cssd' on 'rhel74testserver' succeededk7|MSGTYPEt1|3k5|OBJIDt8|ora.cssdk4|WAITt1|0

                              2018-05-17 00:19:03.341 :GIPCXCPT:931116800:  gipcInternalConnectSync: failed sync request, ret gipcretConnectionRefused (29)

                              2018-05-17 00:19:03.341 :GIPCXCPT:931116800:  gipcConnectSyncF [EvmConConnect : evmgipcio.c : 229]: EXCEPTION[ ret gipcretConnectionRefused (29) ]  failed sync connect endp 0x7f6d441f3260 [00000000000017ed] { gipcEndpoint : localAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=)(GIPCID=00000000-00000000-0))', remoteAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', numPend 0, numReady 0, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 0, readyRef (nil), ready 0, wobj 0x7f6d441896b0, sendp 0x7f6d44189460 status 13flags 0xa108871a, flags-2 0x0, usrFlags 0x30020 }, addr 0x7f6d441f46f0 [00000000000017f4] { gipcAddress : name 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', objFlags 0x0, addrFlags 0x4 }, flags 0x8000000

                              2018-05-17 00:19:03.342 : CLSCEVT:931116800: (:CLSCE0047:)clsce_publish_internal 0x7f6d440e18b0 EvmConnCreate failed with status = 13, try = 0

                              2018-05-17 00:19:03.342 :GIPCXCPT:931116800:  gipcInternalConnectSync: failed sync request, ret gipcretConnectionRefused (29)

                              2018-05-17 00:19:03.342 :GIPCXCPT:931116800:  gipcConnectSyncF [EvmConConnect : evmgipcio.c : 229]: EXCEPTION[ ret gipcretConnectionRefused (29) ]  failed sync connect endp 0x7f6d4418ec00 [00000000000017fe] { gipcEndpoint : localAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=)(GIPCID=00000000-00000000-0))', remoteAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', numPend 0, numReady 0, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 0, readyRef (nil), ready 0, wobj 0x7f6d44189830, sendp 0x7f6d441f56a0 status 13flags 0xa108871a, flags-2 0x0, usrFlags 0x30020 }, addr 0x7f6d441f4780 [0000000000001805] { gipcAddress : name 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', objFlags 0x0, addrFlags 0x4 }, flags 0x8000000

                              2018-05-17 00:19:03.342 : CLSCEVT:931116800: (:CLSCE0047:)clsce_publish_internal 0x7f6d440e18b0 EvmConnCreate failed with status = 13, try = 1

                              2018-05-17 00:19:03.342 :  CRSEVT:931116800: {0:0:119} ClusterPubSub::publish Error posting to event stream. Connection will be retried on next publish [4]

                              2018-05-17 00:19:03.342 :  CRSRPT:931116800: {0:0:119} ClusterConnectException caught CRS_RESOURCE_STATE_CHANGE for ora.DATA.dg

                              2018-05-17 00:19:03.344 :    AGFW:1416677120: {0:11:41} Agfw Proxy Server received the message: AGENT_SHUTDOWN_REQUEST[Proxy] ID 20486:192

                              2018-05-17 00:19:03.344 :    AGFW:1416677120: {0:11:41} Verifying msg agentname = /u01/app/oracle/gridsw/bin/cssdagent_grid

                              2018-05-17 00:19:03.344 :    AGFW:1416677120: {0:11:41} Shutdown request received from /u01/app/oracle/gridsw/bin/cssdagent_grid

                              2018-05-17 00:19:03.344 :    AGFW:1416677120: {0:11:41} Agfw Proxy Server replying to the message: AGENT_SHUTDOWN_REQUEST[Proxy] ID 20486:192

                              2018-05-17 00:19:03.346 :GIPCXCPT:931116800:  gipcInternalConnectSync: failed sync request, ret gipcretConnectionRefused (29)

                              2018-05-17 00:19:03.346 :GIPCXCPT:931116800:  gipcConnectSyncF [EvmConConnect : evmgipcio.c : 229]: EXCEPTION[ ret gipcretConnectionRefused (29) ]  failed sync connect endp 0x7f6d441f4030 [0000000000001816] { gipcEndpoint : localAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=)(GIPCID=00000000-00000000-0))', remoteAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', numPend 0, numReady 0, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 0, readyRef (nil), ready 0, wobj 0x7f6d4418a8c0, sendp 0x7f6d4418a670 status 13flags 0xa108871a, flags-2 0x0, usrFlags 0x30020 }, addr 0x7f6d44189100 [000000000000181d] { gipcAddress : name 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', objFlags 0x0, addrFlags 0x4 }, flags 0x8000000

                              2018-05-17 00:19:03.346 : CLSCEVT:931116800: (:CLSCE0047:)clsce_publish_internal 0x7f6d440e18b0 EvmConnCreate failed with status = 13, try = 0

                              2018-05-17 00:19:03.347 :GIPCXCPT:931116800:  gipcInternalConnectSync: failed sync request, ret gipcretConnectionRefused (29)

                              2018-05-17 00:19:03.347 :GIPCXCPT:931116800:  gipcConnectSyncF [EvmConConnect : evmgipcio.c : 229]: EXCEPTION[ ret gipcretConnectionRefused (29) ]  failed sync connect endp 0x7f6d441f31f0 [0000000000001827] { gipcEndpoint : localAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=)(GIPCID=00000000-00000000-0))', remoteAddr 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', numPend 0, numReady 0, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 0, readyRef (nil), ready 0, wobj 0x7f6d4418a920, sendp 0x7f6d4418a6d0 status 13flags 0xa108871a, flags-2 0x0, usrFlags 0x30020 }, addr 0x7f6d44189100 [000000000000182e] { gipcAddress : name 'clsc://(ADDRESS=(PROTOCOL=ipc)(KEY=SYSTEM.evm.acceptor.auth)(GIPCID=00000000-00000000-0))', objFlags 0x0, addrFlags 0x4 }, flags 0x8000000

                              2018-05-17 00:19:03.347 : CLSCEVT:931116800: (:CLSCE0047:)clsce_publish_internal 0x7f6d440e18b0 EvmConnCreate failed with status = 13, try = 1

                              2018-05-17 00:19:03.347 :  CRSEVT:931116800: {0:0:119} ClusterPubSub::publish Error posting to event stream. Connection will be retried on next publish [4]

                              2018-05-17 00:19:03.347 :  CRSRPT:931116800: {0:0:119} ClusterConnectException caught CRS_RESOURCE_STATE_CHANGE for ora.DATA.dg

                              2018-05-17 00:19:03.347 :   CRSPE:933218048: {0:0:119} Received shutdown sync reply for rhel74testserver

                              2018-05-17 00:19:03.347 :   CRSPE:933218048: {0:0:119} ShutdownCmd 0x7f6d382ae4a0 completing after sync. No resources to stop.

                              2018-05-17 00:19:03.347 :UiServer:929015552: {0:0:119} Response: c4|5!ORDERk7|MESSAGEt109|CRS-2793: Shutdown of Oracle High Availability Services-managed resources on 'rhel74testserver' has completedk7|MSGTYPEt1|3k5|OBJIDt16|rhel74testserverk4|WAITt1|0

                              2018-05-17 00:19:03.348 :   CRSPE:933218048: {0:0:119} PE Command [ Server Shutdown {rhel74testserver} : stage=3 : 0x7f6d382ae4a0 ] has completed

                              2018-05-17 00:19:03.348 :    AGFW:1416677120: {0:0:119} Agfw Proxy Server received the message: CMD_COMPLETED[Proxy] ID 20482:912

                              2018-05-17 00:19:03.348 :    AGFW:1416677120: {0:0:119} Agfw Proxy Server replying to the message: CMD_COMPLETED[Proxy] ID 20482:912

                              2018-05-17 00:19:03.348 :   CRSPE:933218048: {0:0:119} Shutdown already in progress for server :rhel74testserver

                              2018-05-17 00:19:03.348 :UiServer:929015552: {0:0:122} Response: c4|5!ORDERk7|MESSAGEt92|CRS-2797: Shutdown is already in progress for 'rhel74testserver', waiting for it to completek7|MSGTYPEt1|3k5|OBJIDt16|rhel74testserverk4|WAITt1|0

                              2018-05-17 00:19:03.349 :   CRSPE:933218048: {0:0:119} Prepared shutdown cmd for: rhel74testserver

                              2018-05-17 00:19:03.349 :   CRSPE:933218048: {0:0:119} Shutdown processing completing to allow original shutdown to do the work.Server Shutdown {rhel74testserver} : stage=1 : 0x7f6d38073f90

                              2018-05-17 00:19:03.349 :   CRSPE:933218048: {0:0:119} PE Command [ Server Shutdown {rhel74testserver} : stage=1 : 0x7f6d38073f90 ] has completed

                              2018-05-17 00:19:03.349 :   CRSPE:933218048: {0:0:119} UI Command [Server Shutdown {rhel74testserver} : stage=1 : 0x7f6d38073f90] is replying to sender.

                              2018-05-17 00:19:03.349 :UiServer:929015552: {0:0:122} Response: c1|7!UI_DATAk6|RESULTt1|0

                              2018-05-17 00:19:03.349 :UiServer:929015552: {0:0:122} Done for ctx=0x7f6d40036c60

                              2018-05-17 00:19:03.352 :   CRSPE:933218048: {0:0:119} UI Command [Server Shutdown {rhel74testserver} : stage=3 : 0x7f6d382ae4a0] is replying to sender.

                              2018-05-17 00:19:03.352 :UiServer:929015552: {0:0:119} Response: c1|7!UI_DATAk6|RESULTt1|0

                              2018-05-17 00:19:03.352 :UiServer:929015552: {0:0:119} Done for ctx=0x7f6d400358a0

                              2018-05-17 00:19:03.352 : CRSMAIN:929015552: {0:0:119} Exiting on request of the Policy Engine...

                              2018-05-17 00:19:03.352 : CRSMAIN:929015552: {0:0:119} Done.

                              • 12. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                                898191

                                Please find the below logs.

                                 

                                 

                                vi alert.log

                                2018-05-15 00:34:44.601 [CLSECHO(22489)]CLSRSC-0567: Beginning Oracle Grid Infrastructure configuration.

                                2018-05-15 00:34:46.407 [CLSECHO(22656)]AFD-9202: AFD can not be installed/loaded because ASMLib is installed.

                                2018-05-15 00:34:48.942 [OCRCONFIG(22784)]CRS-2101: The OLR was formatted using version 4.

                                2018-05-15 00:34:51.097 [CLSCFG(22859)]CRS-1001: The OCR was formatted using version 4.

                                2018-05-15 00:35:04.183 [OHASD(23077)]CRS-8500: Oracle Clusterware OHASD process is starting with operating system process ID 23077

                                2018-05-15 00:35:04.187 [OHASD(23077)]CRS-0714: Oracle Clusterware Release 12.2.0.1.0.

                                2018-05-15 00:35:04.194 [OHASD(23077)]CRS-2112: The OLR service started on node rhel74testserver.

                                2018-05-15 00:35:04.229 [OHASD(23077)]CRS-1301: Oracle High Availability Service started on node rhel74testserver.

                                2018-05-15 00:35:29.383 [ORAAGENT(23437)]CRS-8500: Oracle Clusterware ORAAGENT process is starting with operating system process ID 23437

                                2018-05-15 00:35:29.683 [EVMD(23455)]CRS-8500: Oracle Clusterware EVMD process is starting with operating system process ID 23455

                                2018-05-15 00:37:29.699 [CSSDAGENT(24241)]CRS-8500: Oracle Clusterware CSSDAGENT process is starting with operating system process ID 24241

                                2018-05-15 00:37:31.783 [ORAROOTAGENT(24243)]CRS-8500: Oracle Clusterware ORAROOTAGENT process is starting with operating system process ID 24243

                                2018-05-15 00:37:32.234 [OCSSD(24275)]CRS-8500: Oracle Clusterware OCSSD process is starting with operating system process ID 24275

                                2018-05-15 00:37:33.263 [OCSSD(24275)]CRS-1713: CSSD daemon is started in hub mode

                                2018-05-15 00:37:42.269 [OCSSD(24275)]CRS-1601: CSSD Reconfiguration complete. Active nodes are rhel74testserver .

                                2018-05-15 00:37:45.409 [OCSSD(24275)]CRS-1720: Cluster Synchronization Services daemon (CSSD) is ready for operation.

                                2018-05-15 00:38:18.473 [CLSECHO(25810)]ACFS-9500: Location of Oracle Home is '/u01/app/oracle/gridsw' as determined from the internal configuration data

                                2018-05-15 00:39:15.253 [CLSECHO(27626)]ACFS-9300: ADVM/ACFS distribution files found.

                                2018-05-15 00:39:15.562 [CLSECHO(27722)]ACFS-9314: Removing previous ADVM/ACFS installation.

                                2018-05-15 00:39:20.199 [CLSECHO(27758)]ACFS-9315: Previous ADVM/ACFS components successfully removed.

                                2018-05-15 00:39:20.279 [CLSECHO(27783)]ACFS-9307: Installing requested ADVM/ACFS software.

                                2018-05-15 00:39:20.305 [CLSECHO(27791)]ACFS-9503: ADVM and ACFS driver media location is '/u01/app/oracle/gridsw/usm/install/Oracle/EL7/x86_64/3.10.0-229/3.10.0-229-x86_64/bin'

                                2018-05-15 00:39:22.726 [CLSECHO(28033)]ACFS-9308: Loading installed ADVM/ACFS drivers.

                                2018-05-15 00:39:22.803 [CLSECHO(28041)]ACFS-9321: Creating udev for ADVM/ACFS.

                                2018-05-15 00:39:22.828 [CLSECHO(28049)]ACFS-9323: Creating module dependencies - this may take some time.

                                2018-05-15 00:39:51.331 [CLSECHO(29004)]ACFS-9154: Loading 'oracleoks.ko' driver.

                                2018-05-15 00:39:51.464 [CLSECHO(29018)]ACFS-9109: oracleoks.ko driver failed to load.

                                2018-05-15 00:39:51.547 [CLSECHO(29042)]ACFS-9428: Failed to load ADVM/ACFS drivers. A system reboot is recommended.

                                2018-05-15 00:39:51.578 [CLSECHO(29050)]ACFS-9310: ADVM/ACFS installation failed.

                                2018-05-15 01:01:02.655 [OCSSD(24275)]CRS-1603: CSSD on node rhel74testserver has been shut down.

                                2018-05-15 01:01:03.961 [OCSSD(24275)]CRS-1660: The CSS daemon shutdown has completed

                                2018-05-15 01:01:03.961 [OCSSD(24275)]CRS-8504: Oracle Clusterware OCSSD process with operating system process ID 24275 is exiting

                                2018-05-16 23:58:46.287 [CLSECHO(17267)]CRS-6021: Could not set system resource limits for Clusterware: "ulimit -l unlimited".

                                2018-05-16 23:58:46.301 [CLSECHO(17268)]CRS-6021: Could not set system resource limits for Clusterware: "ulimit -u 65536".

                                2018-05-16 23:58:53.111 [OHASD(17260)]CRS-8500: Oracle Clusterware OHASD process is starting with operating system process ID 17260

                                2018-05-16 23:58:53.158 [OHASD(17260)]CRS-0714: Oracle Clusterware Release 12.2.0.1.0.

                                2018-05-16 23:58:53.167 [OHASD(17260)]CRS-2112: The OLR service started on node rhel74testserver.

                                2018-05-16 23:58:53.179 [OHASD(17260)]CRS-1301: Oracle High Availability Service started on node rhel74testserver.

                                2018-05-16 23:58:53.811 [CSSDAGENT(17339)]CRS-8500: Oracle Clusterware CSSDAGENT process is starting with operating system process ID 17339

                                2018-05-16 23:58:54.761 [ORAAGENT(17333)]CRS-8500: Oracle Clusterware ORAAGENT process is starting with operating system process ID 17333

                                2018-05-16 23:58:54.861 [ORAROOTAGENT(17343)]CRS-8500: Oracle Clusterware ORAROOTAGENT process is starting with operating system process ID 17343

                                2018-05-16 23:58:55.461 [ORAAGENT(17392)]CRS-8500: Oracle Clusterware ORAAGENT process is starting with operating system process ID 17392

                                2018-05-16 23:58:55.761 [EVMD(17413)]CRS-8500: Oracle Clusterware EVMD process is starting with operating system process ID 17413

                                2018-05-16 23:59:00.171 [CSSDAGENT(17490)]CRS-8500: Oracle Clusterware CSSDAGENT process is starting with operating system process ID 17490

                                2018-05-16 23:59:00.210 [ORAROOTAGENT(17492)]CRS-8500: Oracle Clusterware ORAROOTAGENT process is starting with operating system process ID 17492

                                2018-05-16 23:59:00.521 [OCSSD(17522)]CRS-8500: Oracle Clusterware OCSSD process is starting with operating system process ID 17522

                                2018-05-16 23:59:01.564 [OCSSD(17522)]CRS-1713: CSSD daemon is started in hub mode

                                2018-05-16 23:59:10.151 [OCSSD(17522)]CRS-1601: CSSD Reconfiguration complete. Active nodes are rhel74testserver .

                                2018-05-16 23:59:13.512 [OCSSD(17522)]CRS-1720: Cluster Synchronization Services daemon (CSSD) is ready for operation.

                                2018-05-17 00:19:02.337 [OCSSD(17522)]CRS-1603: CSSD on node rhel74testserver has been shut down.

                                2018-05-17 00:19:03.651 [OCSSD(17522)]CRS-1660: The CSS daemon shutdown has completed

                                2018-05-17 00:19:03.651 [OCSSD(17522)]CRS-8504: Oracle Clusterware OCSSD process with operating system process ID 17522 is exiting

                                 

                                vi ocssd.trc

                                2018-05-17 00:19:03.356 :    CSSD:3117446912: clssgmDiscEndpcl: gipcDestroy 0xf16

                                2018-05-17 00:19:03.651 :    CSSD:3114292992: clssbnmcTerminate: Terminating

                                2018-05-17 00:19:03.652 :    CSSD:3139262208: clssbnmcBCCMHandler: Sent data

                                2018-05-17 00:19:03.652 :    CSSD:3137685248: clssbnmsMbrTerminate: Terminating node rhel74testserver, nodeId 1:421981150

                                2018-05-17 00:19:03.652 :    CSSD:3137685248: clssbnmsUnregister: mbr nodeId 1:421981150 unregistered

                                2018-05-17 00:19:03.652 :    CSSD:3123496704: clssgmpcGMCReqWorkerThread: An event should not have been received in state 4

                                2018-05-17 00:19:03.652 :    CSSD:3136108288: clssbnmcRemoved: the termination failed at the server

                                2018-05-17 00:19:03.652 :    CSSD:3136108288: clssscUpdateProtoState: Protocol BCNM state changed from clssbcprtstateActive (3) to clssbcprtstateDisconnect (4) for SC pipe 0x7ff9b40c3560

                                2018-05-17 00:19:03.652 :    CSSD:3136108288: clssnmQueueClientEvent:  Sending Event(0), type 0, incarn 421981150

                                2018-05-17 00:19:03.652 :    CSSD:3136108288: clssnmQueueClientEvent: Node[1] state = 3, birth = 421981150, unique = 1526515141

                                2018-05-17 00:19:03.652 :    CSSD:3314978880: clssgmCtrlProcessWork: Protocol update msg

                                2018-05-17 00:19:03.652 :    CSSD:3314978880: clssscDisconnectPipe: Disconnecting SC pipe 0x7ff9b40c3560, BCCM pipe 0x7ff9b40c3160

                                2018-05-17 00:19:03.654 :GIPCXCPT:3140839168: gipcmodMuxTransferRecv: connection closed due to client request endp 0x7ff99c0740f0, ret gipcretConnectionLost (12)

                                2018-05-17 00:19:03.654 : CSSDBCM:3140839168: clssbcm_gipc_disconnect: gipcendp(0x16b) disconnected, cookie type(2), context(0x7ff9b40bc2a0)

                                2018-05-17 00:19:03.654 :    CSSD:3140839168: clssscServerBCCMHandler: Received a disconnect event on ctrl for SC pipe 0x7ff9b40bc6a0

                                2018-05-17 00:19:03.654 :    CSSD:3140839168: clssscServerBCCMHandler: Pipe count after disconnect is 0

                                2018-05-17 00:19:03.654 : CSSDBCM:3140839168: clssbcmRemoveProtocol: handle(0x7ff9b40bc710/CTRL), flags(0)

                                2018-05-17 00:19:03.654 : CSSDBCM:3140839168: clssbcm_gipc_disconnect: gipcendp(0x16b) disconnected. deleting base cookie(0x7ff99c077390)

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcm_gipc_disconnect: gipcendp(0x1dd) disconnected, cookie type(3), context(0x7ff9b40bc8f0)

                                2018-05-17 00:19:03.655 :    CSSD:3140839168: clssbnmsBCCMHandler: Received a disconnect event from bccm for hdl 0x7ff9b40bc8f0 with hostname rhel74testserver

                                2018-05-17 00:19:03.655 :    CSSD:3140839168: clssbnm_connobj_quiesce: Removing connection object (0x7ff99c077290) for host rhel74testserver, nodeId 1:421981150

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcm_SetCookie: Setting handle(0x7ff9b40bc8f0/BCNM) with cookie((nil)), old cookie(0x7ff99c077290)

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcmRemoveProtocol: handle(0x7ff9b40bc8f0/BCNM), flags(0)

                                2018-05-17 00:19:03.655 :    CSSD:3140839168: clssbnmConnDestroy: Destroying connection object (0x7ff99c077290) for host rhel74testserver, nodeId 1:421981150 ; refcount remaining on the node object is 2

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcm_gipc_disconnect: gipcendp(0x1dd) disconnected. deleting base cookie(0x7ff99c096b50)

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcm_gipc_disconnect: gipcendp(0x283) disconnected, cookie type(3), context(0x7ff9b40bcba0)

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcm_SetCookie: Setting handle(0x7ff9b40bcba0/BCGM) with cookie((nil)), old cookie(0x7ff9b40bcb20)

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcmRemoveProtocol: handle(0x7ff9b40bcba0/BCGM), flags(0)

                                2018-05-17 00:19:03.655 : CSSDBCM:3140839168: clssbcm_gipc_disconnect: gipcendp(0x283) disconnected. deleting base cookie(0x7ff99c097160)

                                2018-05-17 00:19:03.655 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x13b) disconnected, cookie type(2), context(0x7ff9b40c3160)

                                2018-05-17 00:19:03.655 :GIPCXCPT:3139262208: gipcmodNetworkSend: connection no longer valid on endp 0x7ff9b40c70b0 [0000000000000148] { gipcEndpoint : localAddr 'ipc', remoteAddr 'ipc://css_ctrllcl_rhel74testserver_', numPend 2, numReady 6, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 17522, readyRef 0x13cdb90, ready 1, wobj 0x7ff9b40c8ad0, sendp 0x7ff9b40c8880 status 0flags 0x2124a616, flags-2 0x100, usrFlags 0x24020 }, ret gipcretConnectionLost (12)

                                2018-05-17 00:19:03.655 :GIPCXCPT:3139262208: gipcSendF [gipcmodMuxSendInternal : gipcmodMux.c : 1389]: EXCEPTION[ ret gipcretConnectionLost (12) ]  failed to send on endp 0x7ff9b40c70b0 [0000000000000148] { gipcEndpoint : localAddr 'ipc', remoteAddr 'ipc://css_ctrllcl_rhel74testserver_', numPend 1, numReady 6, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 17522, readyRef 0x13cdb90, ready 1, wobj 0x7ff9b40c8ad0, sendp 0x7ff9b40c8880 status 0flags 0x2124a61e, flags-2 0x100, usrFlags 0x24020 }, addr 0000000000000000, buf 0x7ff9a00a5510, len 28, cookie 0x7ff9a00e5420, flags 0x4000

                                2018-05-17 00:19:03.655 : GIPCMUX:3139262208: gipcmodMuxDisconnectMsg: EXCEPTION[ ret gipcretConnectionLost (12) ]  breaking connection due to failed disconnect msg endp 0x7ff9a00a9fb0 [00000000000001c3] { gipcEndpoint : localAddr 'mux://0000000000000000', remoteAddr 'mux://00007ff9b40c69a0', numPend 1, numReady 0, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 17522, readyRef 0x13cdb90, ready 0, wobj 0x7ff9a00ac3a0, sendp (nil) status 0flags 0x20038616, flags-2 0x0, usrFlags 0x20010 }

                                2018-05-17 00:19:03.655 :GIPCXCPT:3139262208: gipcInternalSend: connection not valid for send operation endp 0x7ff9b40c70b0 [0000000000000148] { gipcEndpoint : localAddr 'ipc', remoteAddr 'ipc://css_ctrllcl_rhel74testserver_', numPend 1, numReady 6, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 17522, readyRef 0x13cdb90, ready 1, wobj 0x7ff9b40c8ad0, sendp 0x7ff9b40c8880 status 0flags 0x2124a61e, flags-2 0x100, usrFlags 0x24020 }, ret gipcretConnectionLost (12)

                                2018-05-17 00:19:03.655 :GIPCXCPT:3139262208: gipcSendF [gipcmodMuxSendInternal : gipcmodMux.c : 1389]: EXCEPTION[ ret gipcretConnectionLost (12) ]  failed to send on endp 0x7ff9b40c70b0 [0000000000000148] { gipcEndpoint : localAddr 'ipc', remoteAddr 'ipc://css_ctrllcl_rhel74testserver_', numPend 1, numReady 6, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 17522, readyRef 0x13cdb90, ready 1, wobj 0x7ff9b40c8ad0, sendp 0x7ff9b40c8880 status 0flags 0x2124a61e, flags-2 0x100, usrFlags 0x24020 }, addr 0000000000000000, buf 0x7ff9a014e310, len 28, cookie 0x7ff9a00e9250, flags 0x4000

                                2018-05-17 00:19:03.655 : GIPCMUX:3139262208: gipcmodMuxDisconnectMsg: EXCEPTION[ ret gipcretConnectionLost (12) ]  breaking connection due to failed disconnect msg endp 0x7ff9a00ab980 [00000000000001cd] { gipcEndpoint : localAddr 'mux://0000000000000000', remoteAddr 'mux://00007ff9b40c69a0', numPend 1, numReady 0, numDone 0, numDead 0, numTransfer 0, objFlags 0x0, pidPeer 17522, readyRef 0x13cdb90, ready 0, wobj 0x7ff9a00add70, sendp (nil) status 0flags 0x20038616, flags-2 0x0, usrFlags 0x20010 }

                                2018-05-17 00:19:03.655 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x13b) disconnected. deleting base cookie(0x7ff9b40c57e0)

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x1c3) disconnected, cookie type(3), context(0x7ff9b40c37b0)

                                2018-05-17 00:19:03.656 :    CSSD:3139262208: clssbnmcBCCMHandler: Received a disconnect event for hdl 0x7ff9b40c37b0

                                2018-05-17 00:19:03.656 :    CSSD:3139262208: clssbnmcDisconnectConn: disconnecting connection with hdl 0x7ff9b40c37b0

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_SetCookie: Setting handle(0x7ff9b40c37b0/BCNM) with cookie((nil)), old cookie(0x7ff9a00ad380)

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcmRemoveProtocol: handle(0x7ff9b40c37b0/BCNM), flags(0)

                                2018-05-17 00:19:03.656 :    CSSD:3139262208: clssbnmConnDestroy: Destroying connection object (0x7ff9a00ad380) for host rhel74testserver

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x1c3) disconnected. deleting base cookie(0x7ff9a00ab960)

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x1cd) disconnected, cookie type(3), context(0x7ff9b40c3a60)

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_SetCookie: Setting handle(0x7ff9b40c3a60/BCGM) with cookie((nil)), old cookie(0x7ff9a00ad990)

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x1cd) disconnected. deleting base cookie(0x7ff9a00ad330)

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x1b9) disconnected, cookie type(3), context(0x7ff9b40c35d0)

                                2018-05-17 00:19:03.656 :    CSSD:3139262208: clssscClientBCCMHandler: initiating resync because main pipe 0x7ff9b40c3560 got disconnected

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcmRemoveProtocol: handle(0x7ff9b40c35d0/CTRL), flags(0)

                                2018-05-17 00:19:03.656 : CSSDBCM:3139262208: clssbcm_gipc_disconnect: gipcendp(0x1b9) disconnected. deleting base cookie(0x7ff9a00a9f90)

                                • 13. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                                  Mishra1

                                  Hi

                                   

                                  Can you try loading acfs modules using acfsload you should run acfsload command as root user.

                                   

                                  acfsload loads or unloads Oracle ACFS, Oracle ADVM, and Oracle Kernel Services Driver (OKS) drivers.

                                   

                                  acfsload start

                                  • 14. Re: ASM(GI) startup issue on RHEL 7.4 standalone server DB-12cR2
                                    898191

                                    # ls acfs*

                                    acfsccm            acfsrepl_apply      acfsrepl_initializer  acfsroot

                                    acfsdriverstate    acfsrepl_apply.bin  acfsrepl_monitor      acfssinglefsmount

                                    acfshanfs          acfsreplcrs         acfsrepl_preapply

                                    acfsload           acfsrepl_dupd       acfsrepl_transport

                                    acfsregistrymount  acfsrepl_dupd.bin   acfsrm

                                     

                                    ./acfsload start

                                    ./acfsload: line 34: /lib/acfstoolsdriver.sh: No such file or directory

                                    1 2 Previous Next