
从RAC的crs日志看到vip漂移失败,导致数据库集群节点重启
2021-08-26 09:26:41.272 [CRSD(16816)]CRS-2771: Maximum restart attempts reached for resource ‘ora.node2.vip’; will not restart.
2021-08-26 09:26:42.147 [CRSD(16816)]CRS-2878: Failed to restart resource ‘ora.net1.network’
2021-08-26 09:26:42.148 [CRSD(16816)]CRS-2769: Unable to failover resource ‘ora.net1.network’.
2021-08-26 09:26:43.478 [CRSD(16816)]CRS-2769: Unable to failover resource ‘ora.net1.network’.
2021-08-26 09:47:51.642 [ORAROOTAGENT(16321)]CRS-5818: Aborted command ‘check’ for resource ‘ora.drivers.acfs’. Details at (:CRSAGF00113:) {0:0:231} in /u01/app/grid/diag/crs/node1/crs/trace/ohasd_orarootagent_root.trc.
2021-08-26 09:48:06.560 [ORAROOTAGENT(16321)]CRS-5014: Agent “ORAROOTAGENT” timed out starting process “/u01/app/12c/grid/bin/acfsload” for action “check”: details at “(:CLSN00009:)” in “/u01/app/grid/diag/crs/node1/crs/trace/ohasd_orarootagent_root.trc”
2021-08-26 10:06:59.351 [ORAAGENT(17118)]CRS-5014: Agent “ORAAGENT” timed out starting process “/u01/app/12c/grid/bin/lsnrctl” for action “check”: details at “(:CLSN00009:)” in “/u01/app/grid/diag/crs/node1/crs/trace/crsd_oraagent_grid.trc”
2021-08-26 10:10:32.503 [OCSSD(16630)]CRS-1608: This node was evicted by node 2, node2; details at (:CSSNM00005:) in /u01/app/grid/diag/crs/node1/crs/trace/ocssd.trc.
2021-08-26 10:10:35.078 [OCSSD(16630)]CRS-1656: The CSS daemon is terminating due to a fatal error; Details at (:CSSSC00012:) in /u01/app/grid/diag/crs/node1/crs/trace/ocssd.trc
2021-08-26 10:10:40.531 [OCSSD(16630)]CRS-1652: Starting clean up of CRSD resources.
2021-08-26 10:10:50.327 [CSSDMONITOR(16598)]CRS-1661: The CSS daemon is not responding. Reboot will occur in 14030 milliseconds; Details at (:CLSN00111:) in /u01/app/grid/diag/crs/node1/crs/trace/ohasd_cssdmonitor_root.trc
2021-08-26 10:10:50.327 [CSSDAGENT(16615)]CRS-1661: The CSS daemon is not responding. Reboot will occur in 13990 milliseconds; Details at (:CLSN00111:) in /u01/app/grid/diag/crs/node1/crs/trace/ohasd_cssdagent_root.trc
2021-08-26 10:13:49.388 [OHASD(3182)]CRS-8500: Oracle Clusterware OHASD process is starting with operating system process ID 3182
2021-08-26 10:13:49.485 [OHASD(3182)]CRS-0714: Oracle Clusterware Release 12.2.0.1.0.
2021-08-26 10:13:49.524 [OHASD(3182)]CRS-2112: The OLR service started on node node1.
2021-08-26 10:13:49.580 [OHASD(3182)]CRS-1301: Oracle High Availability Service started on node node1.
从/u01/app/grid/diag/crs/node1/crs/trace/ohasd_orarootagent_root.trc上看日志如下
2021-08-26 09:47:52.116 : USRTHRD:3358193408: {0:5:3} HAIP: assigned ip ‘169.254.8.94’
2021-08-26 09:47:52.116 : USRTHRD:3358193408: {0:5:3} HAIP: check ip ‘169.254.8.94’
2021-08-26 09:47:52.116 : USRTHRD:3358193408: {0:5:3} Start: 1 HAIP assignment, 1, 1, 1, 1
2021-08-26 09:47:52.116 : USRTHRD:3358193408: {0:5:3} to verify wt, 1-1-1
2021-08-26 09:47:52.116 : USRTHRD:3358193408: {0:5:3} to verify inf event
2021-08-26 09:47:52.170 : AGENT:3382523648: {0:0:231} {0:0:231} Created alert : (:CRSAGF00113:) : Aborting the command: check for resource: ora.drivers.acfs 1 1
2021-08-26 09:47:52.170 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] (:CLSN00110:) clsn_agent::abort {
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] abort {
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Agent::doStateDump Default Agent Dump
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Agent::doStateDump last call info:
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:44:14.124 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck check clsagfw_res_status:0
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:44:14.124 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck exit clsagfw_res_status:0 }
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:45:12.978 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck entry {
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:45:12.978 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck notifyObservers
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:45:12.978 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck call check
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:45:12.990 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:DaemonAgent::check 100 entry
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:45:13.772 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:DaemonAgent::check 199 exit clsagfw_res_Status:0
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:45:13.772 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck check clsagfw_res_status:0
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:45:13.772 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck exit clsagfw_res_status:0 }
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:46:12.980 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck entry {
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:46:12.980 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck notifyObservers
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:46:12.980 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck call check
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:46:13.34 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:DaemonAgent::check 100 entry
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:46:13.675 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:DaemonAgent::check 199 exit clsagfw_res_Status:0
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:46:13.675 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck check clsagfw_res_status:0
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:46:13.675 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck exit clsagfw_res_status:0 }
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:47:12.971 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck entry {
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:47:12.971 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck notifyObservers
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:47:12.971 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:Agent::commonCheck call check
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] Time:08/26/2021 09:47:14.431 Tint:{0:0:231} action:104 resname:ora.drivers.acfs lastCall:DaemonAgent::check 100 entry
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] abort command: check
2021-08-26 09:47:52.171 :CLSDYNAM:3382523648: [ora.drivers.acfs]{0:0:231} [check] tryActionLock {
2021-08-26 09:47:52.224 :CLSDYNAM:3365713664: [ora.drivers.acfs]{0:0:231} [check] (:CLSN00009:)Utils:execCmd abort request: killing /u01/app/12c/grid/bin/acfsload
2021-08-26 09:47:54.040 : CLSDMC:2330683136: command 0 failed with status 16908801
CLSDMC:3363612416: Timeout [16000 sec] to receive ack from connection [ipc://node1_CTSSD]
2021-08-26 09:48:04.387 :CLSDYNAM:2330683136: [ora.crsd]{0:5:3} [check] DaemonAgent::check returned 0