2006-07-26 15:13:16 [MgmSrvr] INFO -- NDB Cluster Management Server. Version 5.1.7 (beta) 2006-07-26 15:13:16 [MgmSrvr] INFO -- Id: 1, Command port: 1186 2006-07-26 15:13:43 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 reserved for ip 127.0.0.1, m_reserved_nodes 0000000000000006. 2006-07-26 15:13:43 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:13:44 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 freed, m_reserved_nodes 0000000000000002. 2006-07-26 15:13:53 [MgmSrvr] INFO -- Mgmt server state: nodeid 3 reserved for ip 127.0.0.1, m_reserved_nodes 000000000000000a. 2006-07-26 15:13:53 [MgmSrvr] INFO -- Node 1: Node 3 Connected 2006-07-26 15:13:54 [MgmSrvr] INFO -- Node 2: Node 3 Connected 2006-07-26 15:13:54 [MgmSrvr] INFO -- Node 2: Start phase 1 completed 2006-07-26 15:13:54 [MgmSrvr] INFO -- Node 3: Node 2 Connected 2006-07-26 15:13:54 [MgmSrvr] INFO -- Mgmt server state: nodeid 3 freed, m_reserved_nodes 0000000000000002. 2006-07-26 15:13:56 [MgmSrvr] INFO -- Node 2: Node 3: API version 5.1.7 2006-07-26 15:13:56 [MgmSrvr] INFO -- Node 3: CM_REGCONF president = 2, own Node = 3, our dynamic id = 2 2006-07-26 15:13:56 [MgmSrvr] INFO -- Node 3: Node 2: API version 5.1.7 2006-07-26 15:13:56 [MgmSrvr] INFO -- Node 3: Start phase 1 completed 2006-07-26 15:13:56 [MgmSrvr] INFO -- Node 2: Start phase 2 completed (initial start) 2006-07-26 15:13:57 [MgmSrvr] INFO -- Node 3: Start phase 2 completed (initial start) 2006-07-26 15:13:57 [MgmSrvr] INFO -- Node 2: Start phase 3 completed (initial start) 2006-07-26 15:13:57 [MgmSrvr] INFO -- Node 3: Start phase 3 completed (initial start) 2006-07-26 15:14:26 [MgmSrvr] INFO -- Node 2: Start phase 4 completed (initial start) 2006-07-26 15:14:26 [MgmSrvr] INFO -- Node 3: Start phase 4 completed (initial start) 2006-07-26 15:14:29 [MgmSrvr] INFO -- Node 2: Local checkpoint 1 started. Keep GCI = 1 oldest restorable GCI = 1 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Start phase 5 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Start phase 6 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: President restarts arbitration thread [state=1] 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Start phase 7 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Communication to Node 4 opened 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Communication to Node 5 opened 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Communication to Node 6 opened 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Start phase 8 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Start phase 9 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Start phase 100 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Start phase 101 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Started (version 5.1.7) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Start phase 5 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Start phase 6 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Start phase 7 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Communication to Node 4 opened 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Communication to Node 5 opened 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Communication to Node 6 opened 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Start phase 8 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Start phase 9 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Start phase 100 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Start phase 101 completed (initial start) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Started (version 5.1.7) 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Node 1: API version 5.1.7 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Node 1: API version 5.1.7 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 3: Prepare arbitrator node 1 [ticket=19060001aa8afb08] 2006-07-26 15:14:30 [MgmSrvr] INFO -- Node 2: Started arbitrator node 1 [ticket=19060001aa8afb08] 2006-07-26 15:16:26 [MgmSrvr] INFO -- Mgmt server state: nodeid 4 reserved for ip 127.0.0.1, m_reserved_nodes 0000000000000012. 2006-07-26 15:16:26 [MgmSrvr] INFO -- Node 4: mysqld --server-id=0 2006-07-26 15:16:26 [MgmSrvr] INFO -- Node 2: Node 4 Connected 2006-07-26 15:16:27 [MgmSrvr] INFO -- Node 2: Node 4: API version 5.1.7 2006-07-26 15:16:27 [MgmSrvr] INFO -- Node 3: Node 4 Connected 2006-07-26 15:16:27 [MgmSrvr] INFO -- Node 3: Node 4: API version 5.1.7 2006-07-26 15:19:04 [MgmSrvr] INFO -- Node 2: Node shutdown initiated 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:19:15 [MgmSrvr] ALERT -- Node 3: Node 2 Disconnected 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Communication to Node 2 closed 2006-07-26 15:19:15 [MgmSrvr] ALERT -- Node 3: Network partitioning - arbitration required 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: President restarts arbitration thread [state=7] 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 2: Node shutdown completed. 2006-07-26 15:19:15 [MgmSrvr] ALERT -- Node 3: Arbitration won - positive reply from node 1 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: GCP Take over started 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: LCP Take over started 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: ParticipatingDIH = 0000000000000000 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: ParticipatingLQH = 0000000000000000 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_DIH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_LQH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LAST_LCP_FRAG_ORD = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_From_Master_Received = 1 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: GCP Take over completed 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: LCP Take over completed (state = 4) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: ParticipatingDIH = 0000000000000000 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: ParticipatingLQH = 0000000000000000 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_DIH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_LQH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LAST_LCP_FRAG_ORD = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_From_Master_Received = 1 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Table 0: TabCopyStatus: 4 TabUpdateStatus: 2 TabLcpStatus: 3 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 3)=1(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 3)=1(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Table 1: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=1(Idle) 1(on 3)=1(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 3)=1(Idle) 1(on 2)=1(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Table 2: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=0(Idle) 1(on 3)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 3)=0(Idle) 1(on 2)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Table 3: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=0(Idle) 1(on 3)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 3)=0(Idle) 1(on 2)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Table 4: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=0(Idle) 1(on 3)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 3)=0(Idle) 1(on 2)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Table 5: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=0(Idle) 1(on 3)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 3)=0(Idle) 1(on 2)=0(Idle) 2006-07-26 15:19:15 [MgmSrvr] INFO -- Node 3: Started arbitrator node 1 [ticket=192c0002aa8f53e6] 2006-07-26 15:19:18 [MgmSrvr] INFO -- Node 3: Communication to Node 2 opened 2006-07-26 15:19:39 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 reserved for ip 127.0.0.1, m_reserved_nodes 0000000000000016. 2006-07-26 15:19:39 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 2: Node 3 Connected 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 2: CM_REGCONF president = 3, own Node = 2, our dynamic id = 3 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 2: Node 3: API version 5.1.7 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 2: Start phase 1 completed 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 3: Node 2 Connected 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 3: Node 2: API version 5.1.7 2006-07-26 15:19:40 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 freed, m_reserved_nodes 0000000000000012. 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 2: Start phase 2 completed (node restart) 2006-07-26 15:19:40 [MgmSrvr] INFO -- Node 2: Receive arbitrator node 1 [ticket=192c0002aa8f53e6] 2006-07-26 15:19:41 [MgmSrvr] INFO -- Node 2: Start phase 3 completed (node restart) 2006-07-26 15:19:41 [MgmSrvr] INFO -- Node 2: Start phase 4 completed (node restart) 2006-07-26 15:19:43 [MgmSrvr] INFO -- Node 2: DICT: index 5 activated 2006-07-26 15:19:44 [MgmSrvr] INFO -- Node 3: Local checkpoint 2 started. Keep GCI = 1 oldest restorable GCI = 2 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Suma: asking node 3 to recreate subscriptions on me 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Suma: node 3 has completed restoring me 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Start phase 5 completed (node restart) 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Start phase 6 completed (node restart) 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Start phase 7 completed (node restart) 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Communication to Node 4 opened 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Communication to Node 5 opened 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Communication to Node 6 opened 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Start phase 8 completed (node restart) 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Start phase 9 completed (node restart) 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Start phase 100 completed (node restart) 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Node 1: API version 5.1.7 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Node 4 Connected 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Node 4: API version 5.1.7 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Suma: initiate handover with nodes 0000000000000008 GCI: 143 2006-07-26 15:19:46 [MgmSrvr] INFO -- Node 2: Suma: handover from node 3 gci: 143 buckets: 00000001 (2) 2006-07-26 15:19:53 [MgmSrvr] INFO -- Node 2: Start phase 101 completed (node restart) 2006-07-26 15:19:53 [MgmSrvr] INFO -- Node 2: Started (version 5.1.7) 2006-07-26 15:19:57 [MgmSrvr] INFO -- Node 3: Node shutdown initiated 2006-07-26 15:20:07 [MgmSrvr] ALERT -- Node 2: Node 3 Disconnected 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Communication to Node 3 closed 2006-07-26 15:20:07 [MgmSrvr] ALERT -- Node 2: Network partitioning - arbitration required 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: President restarts arbitration thread [state=7] 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 1: Node 3 Connected 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 3: Node shutdown completed. 2006-07-26 15:20:07 [MgmSrvr] ALERT -- Node 2: Arbitration won - positive reply from node 1 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: GCP Take over started 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: LCP Take over started 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: ParticipatingDIH = 0000000000000000 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: ParticipatingLQH = 0000000000000000 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LCP_COMPLETE_REP_Counter_DIH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LCP_COMPLETE_REP_Counter_LQH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LAST_LCP_FRAG_ORD = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LCP_COMPLETE_REP_From_Master_Received = 1 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: GCP Take over completed 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: LCP Take over completed (state = 4) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: ParticipatingDIH = 0000000000000000 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: ParticipatingLQH = 0000000000000000 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LCP_COMPLETE_REP_Counter_DIH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LCP_COMPLETE_REP_Counter_LQH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LAST_LCP_FRAG_ORD = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: m_LCP_COMPLETE_REP_From_Master_Received = 1 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Table 0: TabCopyStatus: 4 TabUpdateStatus: 2 TabLcpStatus: 3 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 0: noLcpReplicas==0 0(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 1: noLcpReplicas==0 0(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Table 1: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 0: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 1: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Table 2: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 0: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 1: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Table 3: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 0: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 1: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Table 4: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 0: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 1: noLcpReplicas==0 0(on 3)=2(Idle) 1(on 2)=2(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Table 5: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 0: noLcpReplicas==0 0(on 3)=0(Idle) 1(on 2)=0(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Fragment 1: noLcpReplicas==0 0(on 3)=0(Idle) 1(on 2)=0(Idle) 2006-07-26 15:20:07 [MgmSrvr] INFO -- Node 2: Started arbitrator node 1 [ticket=19fc0003aa901f79] 2006-07-26 15:20:10 [MgmSrvr] INFO -- Node 2: Communication to Node 3 opened 2006-07-26 15:20:12 [MgmSrvr] INFO -- Mgmt server state: nodeid 3 reserved for ip 127.0.0.1, m_reserved_nodes 000000000000001a. 2006-07-26 15:20:12 [MgmSrvr] INFO -- Node 1: Node 3 Connected 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 2: Node 3 Connected 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 2: Node 3: API version 5.1.7 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 3: Node 2 Connected 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 3: CM_REGCONF president = 2, own Node = 3, our dynamic id = 4 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 3: Node 2: API version 5.1.7 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 3: Start phase 1 completed 2006-07-26 15:20:13 [MgmSrvr] INFO -- Mgmt server state: nodeid 3 freed, m_reserved_nodes 0000000000000012. 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 3: Start phase 2 completed (node restart) 2006-07-26 15:20:13 [MgmSrvr] INFO -- Node 3: Receive arbitrator node 1 [ticket=19fc0003aa901f79] 2006-07-26 15:20:14 [MgmSrvr] INFO -- Node 3: Start phase 3 completed (node restart) 2006-07-26 15:20:14 [MgmSrvr] INFO -- Node 3: Start phase 4 completed (node restart) 2006-07-26 15:20:17 [MgmSrvr] INFO -- Node 3: DICT: index 5 activated 2006-07-26 15:20:18 [MgmSrvr] INFO -- Node 2: Local checkpoint 3 started. Keep GCI = 123 oldest restorable GCI = 124 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Suma: asking node 2 to recreate subscriptions on me 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Suma: node 2 has completed restoring me 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Start phase 5 completed (node restart) 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Start phase 6 completed (node restart) 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Start phase 7 completed (node restart) 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Communication to Node 4 opened 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Communication to Node 5 opened 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Communication to Node 6 opened 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Start phase 8 completed (node restart) 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Start phase 9 completed (node restart) 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Start phase 100 completed (node restart) 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Node 4 Connected 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Node 4: API version 5.1.7 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Suma: initiate handover with nodes 0000000000000004 GCI: 159 2006-07-26 15:20:20 [MgmSrvr] INFO -- Node 3: Suma: handover from node 2 gci: 159 buckets: 00000002 (2) 2006-07-26 15:20:21 [MgmSrvr] INFO -- Node 3: Node 1: API version 5.1.7 2006-07-26 15:20:27 [MgmSrvr] INFO -- Node 3: Start phase 101 completed (node restart) 2006-07-26 15:20:27 [MgmSrvr] INFO -- Node 3: Started (version 5.1.7) 2006-07-26 15:20:53 [MgmSrvr] INFO -- Node 2: Node shutdown initiated 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:21:04 [MgmSrvr] ALERT -- Node 3: Node 2 Disconnected 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Communication to Node 2 closed 2006-07-26 15:21:04 [MgmSrvr] ALERT -- Node 3: Network partitioning - arbitration required 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: President restarts arbitration thread [state=7] 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 2: Node shutdown completed. 2006-07-26 15:21:04 [MgmSrvr] ALERT -- Node 3: Arbitration won - positive reply from node 1 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: GCP Take over started 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: LCP Take over started 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: ParticipatingDIH = 0000000000000000 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: ParticipatingLQH = 0000000000000000 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_DIH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_LQH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LAST_LCP_FRAG_ORD = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_From_Master_Received = 1 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: GCP Take over completed 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: LCP Take over completed (state = 4) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: ParticipatingDIH = 0000000000000000 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: ParticipatingLQH = 0000000000000000 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_DIH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_Counter_LQH = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LAST_LCP_FRAG_ORD = [SignalCounter: m_count=0 0000000000000000] 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: m_LCP_COMPLETE_REP_From_Master_Received = 1 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Table 0: TabCopyStatus: 4 TabUpdateStatus: 2 TabLcpStatus: 3 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Table 1: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Table 2: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Table 3: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Table 4: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 2)=3(Idle) 1(on 3)=3(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Table 5: TabCopyStatus: 0 TabUpdateStatus: 0 TabLcpStatus: 3 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 0: noLcpReplicas==0 0(on 2)=0(Idle) 1(on 3)=0(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Fragment 1: noLcpReplicas==0 0(on 2)=0(Idle) 1(on 3)=0(Idle) 2006-07-26 15:21:04 [MgmSrvr] INFO -- Node 3: Started arbitrator node 1 [ticket=1a200004aa90fe30] 2006-07-26 15:21:07 [MgmSrvr] INFO -- Node 3: Communication to Node 2 opened 2006-07-26 15:25:00 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 reserved for ip 127.0.0.1, m_reserved_nodes 0000000000000016. 2006-07-26 15:25:00 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:25:01 [MgmSrvr] INFO -- Node 3: Node 2 Connected 2006-07-26 15:25:01 [MgmSrvr] INFO -- Node 2: Node 3 Connected 2006-07-26 15:25:01 [MgmSrvr] INFO -- Node 2: CM_REGCONF president = 3, own Node = 2, our dynamic id = 5 2006-07-26 15:25:01 [MgmSrvr] INFO -- Node 2: Node 3: API version 5.1.7 2006-07-26 15:25:01 [MgmSrvr] INFO -- Node 2: Start phase 1 completed 2006-07-26 15:25:01 [MgmSrvr] INFO -- Node 3: Node 2: API version 5.1.7 2006-07-26 15:25:01 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 freed, m_reserved_nodes 0000000000000012. 2006-07-26 15:25:02 [MgmSrvr] INFO -- Node 2: Start phase 2 completed (node restart) 2006-07-26 15:25:02 [MgmSrvr] INFO -- Node 2: Start phase 3 completed (node restart) 2006-07-26 15:25:02 [MgmSrvr] INFO -- Node 2: Start phase 4 completed (node restart) 2006-07-26 15:25:02 [MgmSrvr] INFO -- Node 2: Receive arbitrator node 1 [ticket=1a200004aa90fe30] 2006-07-26 15:25:04 [MgmSrvr] INFO -- Node 2: DICT: index 5 activated 2006-07-26 15:25:05 [MgmSrvr] INFO -- Node 3: Local checkpoint 4 started. Keep GCI = 148 oldest restorable GCI = 148 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Suma: asking node 3 to recreate subscriptions on me 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Suma: node 3 has completed restoring me 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Start phase 5 completed (node restart) 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Start phase 6 completed (node restart) 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Start phase 7 completed (node restart) 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Communication to Node 4 opened 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Communication to Node 5 opened 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Communication to Node 6 opened 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Start phase 8 completed (node restart) 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Start phase 9 completed (node restart) 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Start phase 100 completed (node restart) 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Node 4 Connected 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Node 4: API version 5.1.7 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Suma: initiate handover with nodes 0000000000000008 GCI: 286 2006-07-26 15:25:08 [MgmSrvr] INFO -- Node 2: Suma: handover from node 3 gci: 286 buckets: 00000001 (2) 2006-07-26 15:25:09 [MgmSrvr] INFO -- Node 2: Node 1: API version 5.1.7 2006-07-26 15:25:14 [MgmSrvr] INFO -- Node 2: Start phase 101 completed (node restart) 2006-07-26 15:25:14 [MgmSrvr] INFO -- Node 2: Started (version 5.1.7) 2006-07-26 15:25:28 [MgmSrvr] INFO -- Shutting down server... 2006-07-26 15:25:29 [MgmSrvr] INFO -- Mgmt server state: nodeid 4 freed, m_reserved_nodes 0000000000000002. 2006-07-26 15:25:29 [MgmSrvr] INFO -- Shutdown complete 2006-07-26 15:25:29 [MgmSrvr] INFO -- Node 3: Lost arbitrator node 1 - process exit [state=6] 2006-07-26 15:25:29 [MgmSrvr] INFO -- Node 3: President restarts arbitration thread [state=1] 2006-07-26 15:25:45 [MgmSrvr] INFO -- NDB Cluster Management Server. Version 5.1.11 (beta) 2006-07-26 15:25:45 [MgmSrvr] INFO -- Id: 1, Command port: 1186 2006-07-26 15:25:45 [MgmSrvr] INFO -- Node 1: Node 3 Connected 2006-07-26 15:25:45 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:25:46 [MgmSrvr] INFO -- Node 3: Started arbitrator node 1 [ticket=1a200006aa950a23] 2006-07-26 15:25:58 [MgmSrvr] INFO -- Node 2: Node shutdown initiated 2006-07-26 15:26:08 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:26:08 [MgmSrvr] ALERT -- Node 3: Node 2 Disconnected 2006-07-26 15:26:08 [MgmSrvr] INFO -- Node 3: Communication to Node 2 closed 2006-07-26 15:26:08 [MgmSrvr] ALERT -- Node 3: Network partitioning - arbitration required 2006-07-26 15:26:08 [MgmSrvr] INFO -- Node 3: President restarts arbitration thread [state=7] 2006-07-26 15:26:08 [MgmSrvr] INFO -- Node 2: Node shutdown completed. 2006-07-26 15:26:08 [MgmSrvr] ALERT -- Node 3: Arbitration won - positive reply from node 1 2006-07-26 15:26:08 [MgmSrvr] INFO -- Node 3: Started arbitrator node 1 [ticket=1a200007aa95a16f] 2006-07-26 15:26:11 [MgmSrvr] INFO -- Node 3: Communication to Node 2 opened 2006-07-26 15:26:20 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 reserved for ip 127.0.0.1, m_reserved_nodes 0000000000000006. 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 1: Node 2 Connected 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 2: Node 3 Connected 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 2: CM_REGCONF president = 3, own Node = 2, our dynamic id = 6 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 2: Node 3: API version 5.1.7 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 2: Start phase 1 completed 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 3: Node 2 Connected 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 3: Node 2: API version 5.1.11 2006-07-26 15:26:21 [MgmSrvr] INFO -- Node 2: Receive arbitrator node 1 [ticket=1a200007aa95a16f] 2006-07-26 15:26:21 [MgmSrvr] INFO -- Mgmt server state: nodeid 2 freed, m_reserved_nodes 0000000000000002. 2006-07-26 15:26:22 [MgmSrvr] INFO -- Node 2: Start phase 2 completed (node restart) 2006-07-26 15:26:22 [MgmSrvr] INFO -- Node 2: Start phase 3 completed (node restart) 2006-07-26 15:26:22 [MgmSrvr] INFO -- Node 2: Start phase 4 completed (node restart) 2006-07-26 15:26:24 [MgmSrvr] INFO -- Node 2: DICT: index 5 activated 2006-07-26 15:26:26 [MgmSrvr] INFO -- Node 3: Local checkpoint 5 started. Keep GCI = 173 oldest restorable GCI = 173 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Suma: asking node 3 to recreate subscriptions on me 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Suma: node 3 has completed restoring me 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Start phase 5 completed (node restart) 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Start phase 6 completed (node restart) 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Start phase 7 completed (node restart) 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Communication to Node 4 opened 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Communication to Node 5 opened 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Communication to Node 6 opened 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Start phase 8 completed (node restart) 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Start phase 9 completed (node restart) 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Start phase 100 completed (node restart) 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Node 4 Connected 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Node 4: API version 5.1.7 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Suma: initiate handover with nodes 0000000000000008 GCI: 322 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Suma: handover from node 3 gci: 322 buckets: 00000001 (2) 2006-07-26 15:26:27 [MgmSrvr] INFO -- Node 2: Node 1: API version 5.1.11 2006-07-26 15:26:34 [MgmSrvr] INFO -- Node 2: Start phase 101 completed (node restart) 2006-07-26 15:26:34 [MgmSrvr] INFO -- Node 2: Started (version 5.1.11)