服务器正常，scan服务自动切换

fiozhang

一个在节点1上的scan服务自动停了，然后启动失败，最后到节点2启动了
附上节点1上的部分crsd日志，附件中比较全。想找找 Received state change for ora.LISTENER_SCAN2.lsnr 1 1 [old state = ONLINE, new state = OFFLINE] 的原因。



2012-07-27 13:17:57.758: [   CRSPE][5312] {2:40394:753} Processing PE command id=830. Description: [Stat Resource : 0000000005E10080]
2012-07-27 13:17:58.163: [   CRSPE][5312] {3:33177:4045} Processing PE command id=4123. Description: [Stat Resource : 0000000005E11C40]
2012-07-27 13:17:58.351: [   CRSPE][5312] {3:33177:4046} Processing PE command id=4124. Description: [Stat Resource : 0000000005E10770]
2012-07-27 13:18:19.801: [    AGFW][3176] {0:2:27} Agfw Proxy Server received the message: RESOURCE_STATUS[Proxy] ID 20481:217852
2012-07-27 13:18:19.801: [    AGFW][3176] {0:2:27} Received state change for ora.LISTENER_SCAN2.lsnr 1 1 [old state = ONLINE, new state = OFFLINE]
2012-07-27 13:18:19.801: [    AGFW][3176] {0:2:27} Agfw Proxy Server sending message to PE, Contents = [MIDTo:2|OpID:3|FromA:{Invalid|Node:0|Process:0|Type:0}|ToA:{Invalid|Node:-1|Process:-1|Type:-1}|MIDFrom:0|Type:4|Pri2|Id:36430:Ver:2]
2012-07-27 13:18:19.801: [    AGFW][3176] {0:2:27} Agfw Proxy Server replying to the message: RESOURCE_STATUS[Proxy] ID 20481:217852
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} State change received from oracle1 for ora.LISTENER_SCAN2.lsnr 1 1
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} Processing PE command id=6467. Description: [Resource State Change (ora.LISTENER_SCAN2.lsnr 1 1) : 000000000B16A6E0]
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} RI [ora.LISTENER_SCAN2.lsnr 1 1] new external state [OFFLINE] old value: [ONLINE] on oracle1 label = []
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} Resource Resource Instance ID[ora.LISTENER_SCAN2.lsnr 1 1]. Values:
STATE=OFFLINE
TARGET=ONLINE
LAST_SERVER=oracle1
CURRENT_RCOUNT=0
LAST_RESTART=1343300945
FAILURE_COUNT=0
FAILURE_HISTORY=
STATE_DETAILS=
INCARNATION=1
STATE_CHANGE_VERS=1
LAST_FAULT=0
LAST_STATE_CHANGE=1343366299
INTERNAL_STATE=0
DEGREE_ID=1
ID=ora.LISTENER_SCAN2.lsnr 1 1
CARDINALITY_ID=1
Lock Info:
Write Locks:none
ReadLocks:|STATE INITED| has failed!
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} Processing unplanned state change for [ora.LISTENER_SCAN2.lsnr 1 1]
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} Local Recovery picked for [ora.LISTENER_SCAN2.lsnr 1 1]
2012-07-27 13:18:19.801: [  CRSRPT][5308] {0:2:27} Published to EVM CRS_RESOURCE_STATE_CHANGE for ora.LISTENER_SCAN2.lsnr
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} RI [ora.LISTENER_SCAN2.lsnr 1 1] new internal state: [STARTING] old value: [STABLE]
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} Sending message to agfw: id = 36432
2012-07-27 13:18:19.801: [    AGFW][3176] {0:2:27} Agfw Proxy Server received the message: RESOURCE_START[ora.LISTENER_SCAN2.lsnr 1 1] ID 4098:36432
2012-07-27 13:18:19.801: [   CRSPE][5312] {0:2:27} CRS-2672: 尝试启动 'ora.LISTENER_SCAN2.lsnr' (在 'oracle1' 上)

2012-07-27 13:18:19.801: [    AGFW][3176] {0:2:27} Agfw Proxy Server forwarding the message: RESOURCE_START[ora.LISTENER_SCAN2.lsnr 1 1] ID 4098:36432 to the agent D:\app\11.2.0\grid_3\bin\oraagent.exe_nt authority\system
2012-07-27 13:18:19.925: [UiServer][3584] CS(000000000B35C090)set Properties ( system,000000000A4D4950)
2012-07-27 13:18:19.941: [UiServer][5308] {1:57525:6437} Sending message to PE. ctx= 000000000B3E1C30, Client PID: 5280
2012-07-27 13:18:19.941: [   CRSPE][5312] {1:57525:6437} Processing PE command id=6468. Description: [Stat Resource : 0000000005E11C40]
2012-07-27 13:18:19.941: [UiServer][5308] {1:57525:6437} Done for ctx=000000000B3E1C30
2012-07-27 13:18:20.081: [UiServer][3584] CS(000000000B35C090)set Properties ( system,000000000A4D5670)
2012-07-27 13:18:20.097: [UiServer][5308] {1:57525:6438} Sending message to PE. ctx= 000000000C1245C0, Client PID: 5280
2012-07-27 13:18:20.097: [   CRSPE][5312] {1:57525:6438} Processing PE command id=6469. Description: [Stat Resource : 0000000005E11C40]
2012-07-27 13:18:20.097: [UiServer][5308] {1:57525:6438} Done for ctx=000000000C1245C0
2012-07-27 13:18:20.237: [UiServer][3584] CS(000000000B35C090)set Properties ( system,000000000A4D4950)
2012-07-27 13:18:20.253: [UiServer][5308] {1:57525:6439} Sending message to PE. ctx= 000000000B009EB0, Client PID: 5280
2012-07-27 13:18:20.253: [   CRSPE][5312] {1:57525:6439} Processing PE command id=6470. Description: [Stat Resource : 0000000005E11C40]
2012-07-27 13:18:20.253: [UiServer][5308] {1:57525:6439} Done for ctx=000000000B009EB0
2012-07-27 13:18:20.378: [    AGFW][3176] {0:2:27} Received the reply to the message: RESOURCE_START[ora.LISTENER_SCAN2.lsnr 1 1] ID 4098:36433 from the agent D:\app\11.2.0\grid_3\bin\oraagent.exe_nt authority\system
2012-07-27 13:18:20.378: [    AGFW][3176] {0:2:27} Agfw Proxy Server sending the reply to PE for message:RESOURCE_START[ora.LISTENER_SCAN2.lsnr 1 1] ID 4098:36432
2012-07-27 13:18:20.378: [   CRSPE][5312] {0:2:27} Received reply to action [Start] message ID: 36432
2012-07-27 13:18:20.487: [    AGFW][3176] {0:2:27} Received the reply to the message: RESOURCE_START[ora.LISTENER_SCAN2.lsnr 1 1] ID 4098:36433 from the agent D:\app\11.2.0\grid_3\bin\oraagent.exe_nt authority\system
2012-07-27 13:18:20.487: [    AGFW][3176] {0:2:27} Agfw Proxy Server sending the last reply to PE for message:RESOURCE_START[ora.LISTENER_SCAN2.lsnr 1 1] ID 4098:36432
2012-07-27 13:18:20.487: [   CRSPE][5312] {0:2:27} Received reply to action [Start] message ID: 36432
2012-07-27 13:18:20.487: [   CRSPE][5312] {0:2:27} RI [ora.LISTENER_SCAN2.lsnr 1 1] new internal state: [STABLE] old value: [STARTING]
2012-07-27 13:18:20.487: [   CRSPE][5312] {0:2:27} CRS-2674: 未能启动 'ora.LISTENER_SCAN2.lsnr' (在 'oracle1' 上)

[ 本帖最后由 fiozhang 于 2012-7-31 17:36 编辑 ]