2013-09-17 05:31:10 [ndbd] INFO -- Angel pid: 1867 started child: 1868 2013-09-17 05:31:10 [ndbd] INFO -- Configuration fetched from '127.0.0.1:1186', generation: 1 NDBMT: non-mt 2013-09-17 05:31:10 [ndbd] INFO -- NDB Cluster -- DB node 2 2013-09-17 05:31:10 [ndbd] INFO -- mysql-5.6.11 ndb-7.3.2 -- 2013-09-17 05:31:10 [ndbd] INFO -- numa_set_interleave_mask(numa_all_nodes) : no numa support 2013-09-17 05:31:10 [ndbd] INFO -- Ndbd_mem_manager::init(1) min: 26732Mb initial: 26860Mb Adding 36Mb to ZONE_LO (1,1151) Instantiating DBSPJ instanceNo=0 2013-09-17 05:31:10 [ndbd] INFO -- Start initiated (mysql-5.6.11 ndb-7.3.2) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer Adding 8156Mb to ZONE_LO (1152,260991) Adding 8192Mb to ZONE_LO (262145,262142) Adding 8192Mb to ZONE_HI (524289,262142) Adding 2285Mb to ZONE_HI (786433,73095) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer WOPool::init(61, 9) RWPool::init(22, 14) 2013-09-17 05:31:39 [ndbd] INFO -- timerHandlingLab now: 1180240 sent: 1180085 diff: 155 RWPool::init(42, 75) blockSz: 800, wpp: 8188 -> 624 (76) RWPool::init(29, 624) RWPool::init(42, 16) RWPool::init(62, 12) RWPool::init(42, 83) RWPool::init(c2, 18) RWPool::init(e2, 16) WOPool::init(41, 8) RWPool::init(82, 12) RWPool::init(a2, 54) WOPool::init(21, 10) blockSz: 1024, wpp: 8188 -> 816 (28) RWPool::init(68, 816) 2013-09-17 05:31:42 [ndbd] INFO -- Start phase 0 completed Dbspj::execSTTOR() inst:0 phase=1 2013-09-17 05:31:42 [ndbd] INFO -- Start phase 1 completed 2013-09-17 05:31:43 [ndbd] INFO -- Start phase 2 completed 2013-09-17 05:31:43 [ndbd] INFO -- Start phase 3 completed Dbspj::execSTTOR() inst:0 phase=4 2013-09-17 05:31:43 [ndbd] INFO -- Start phase 4 completed Using 1 fragments per node 2013-09-17 05:31:44 [ndbd] INFO -- Start phase 5 completed 2013-09-17 05:31:44 [ndbd] INFO -- Start phase 6 completed 2013-09-17 05:31:44 [ndbd] INFO -- President restarts arbitration thread [state=1] 2013-09-17 05:31:44 [ndbd] WARNING -- Could not find an arbitrator, cluster is not partition-safe m_active_buckets.set(0) 2013-09-17 05:31:44 [ndbd] INFO -- Start phase 7 completed 2013-09-17 05:31:44 [ndbd] INFO -- Start phase 8 completed 2013-09-17 05:31:44 [ndbd] INFO -- Start phase 9 completed 2013-09-17 05:31:44 [ndbd] INFO -- Start phase 100 completed 2013-09-17 05:31:44 [ndbd] INFO -- Start phase 101 completed 2013-09-17 05:31:44 [ndbd] INFO -- Node started 2013-09-17 05:31:45 [ndbd] INFO -- Started arbitrator node 1 [ticket=074c0001001214da] 2013-09-17 05:42:00 [ndbd] INFO -- Shutdown initiated 2013-09-17 05:42:00 [ndbd] INFO -- Shutdown completed - exiting 2013-09-17 05:42:02 [ndbd] INFO -- Angel shutting down 2013-09-17 05:42:02 [ndbd] INFO -- Node 2: Node shutdown completed. 2013-09-17 05:47:30 [ndbd] INFO -- Angel pid: 2179 started child: 2180 2013-09-17 05:47:30 [ndbd] INFO -- Configuration fetched from '127.0.0.1:1186', generation: 1 NDBMT: non-mt 2013-09-17 05:47:30 [ndbd] INFO -- NDB Cluster -- DB node 2 2013-09-17 05:47:30 [ndbd] INFO -- mysql-5.6.11 ndb-7.3.2 -- 2013-09-17 05:47:30 [ndbd] INFO -- numa_set_interleave_mask(numa_all_nodes) : no numa support 2013-09-17 05:47:30 [ndbd] INFO -- Ndbd_mem_manager::init(1) min: 26732Mb initial: 26860Mb Adding 36Mb to ZONE_LO (1,1151) Instantiating DBSPJ instanceNo=0 2013-09-17 05:47:30 [ndbd] INFO -- Start initiated (mysql-5.6.11 ndb-7.3.2) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer Adding 8156Mb to ZONE_LO (1152,260991) Adding 8192Mb to ZONE_LO (262145,262142) Adding 8192Mb to ZONE_HI (524289,262142) Adding 2285Mb to ZONE_HI (786433,73095) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer WOPool::init(61, 9) RWPool::init(22, 14) 2013-09-17 05:47:59 [ndbd] INFO -- timerHandlingLab now: 2159851 sent: 2159697 diff: 154 RWPool::init(42, 75) blockSz: 800, wpp: 8188 -> 624 (76) RWPool::init(29, 624) RWPool::init(42, 16) RWPool::init(62, 12) RWPool::init(42, 83) RWPool::init(c2, 18) RWPool::init(e2, 16) WOPool::init(41, 8) RWPool::init(82, 12) RWPool::init(a2, 54) WOPool::init(21, 10) blockSz: 1024, wpp: 8188 -> 816 (28) RWPool::init(68, 816) 2013-09-17 05:47:59 [ndbd] INFO -- Start phase 0 completed Dbspj::execSTTOR() inst:0 phase=1 2013-09-17 05:47:59 [ndbd] INFO -- Start phase 1 completed 2013-09-17 05:48:00 [ndbd] INFO -- Start phase 2 completed 2013-09-17 05:48:00 [ndbd] INFO -- Start phase 3 completed Dbspj::execSTTOR() inst:0 phase=4 2013-09-17 05:48:00 [ndbd] INFO -- Start phase 4 completed Using 1 fragments per node 2013-09-17 05:48:02 [ndbd] INFO -- Start phase 5 completed 2013-09-17 05:48:02 [ndbd] INFO -- Start phase 6 completed 2013-09-17 05:48:02 [ndbd] INFO -- President restarts arbitration thread [state=1] 2013-09-17 05:48:02 [ndbd] WARNING -- Could not find an arbitrator, cluster is not partition-safe m_active_buckets.set(0) 2013-09-17 05:48:02 [ndbd] INFO -- Start phase 7 completed 2013-09-17 05:48:02 [ndbd] INFO -- Start phase 8 completed 2013-09-17 05:48:02 [ndbd] INFO -- Start phase 9 completed 2013-09-17 05:48:02 [ndbd] INFO -- Start phase 100 completed 2013-09-17 05:48:02 [ndbd] INFO -- Start phase 101 completed 2013-09-17 05:48:02 [ndbd] INFO -- Node started 2013-09-17 05:48:02 [ndbd] INFO -- Started arbitrator node 1 [ticket=088400010020ff72] 2013-09-17 06:03:21 [ndbd] ALERT -- Node 2: Forced node shutdown completed. Occured during startphase 0. Initiated by signal 9. 2013-09-17 06:03:21 [ndbd] WARNING -- Unable to report shutdown reason to '127.0.0.1:1186'(error: Could not connect to socket - Unable to connect with connect string: nodeid=0,127.0.0.1:1186) 2013-09-17 06:27:14 [ndbd] INFO -- Angel pid: 1876 started child: 1877 2013-09-17 06:27:14 [ndbd] INFO -- Configuration fetched from '127.0.0.1:1186', generation: 1 NDBMT: non-mt 2013-09-17 06:27:14 [ndbd] INFO -- NDB Cluster -- DB node 2 2013-09-17 06:27:14 [ndbd] INFO -- mysql-5.6.11 ndb-7.3.2 -- 2013-09-17 06:27:14 [ndbd] INFO -- numa_set_interleave_mask(numa_all_nodes) : no numa support 2013-09-17 06:27:14 [ndbd] INFO -- Ndbd_mem_manager::init(1) min: 26732Mb initial: 26860Mb Adding 36Mb to ZONE_LO (1,1151) Instantiating DBSPJ instanceNo=0 2013-09-17 06:27:14 [ndbd] INFO -- Start initiated (mysql-5.6.11 ndb-7.3.2) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer Adding 8156Mb to ZONE_LO (1152,260991) Adding 8192Mb to ZONE_LO (262145,262142) Adding 8192Mb to ZONE_HI (524289,262142) Adding 2285Mb to ZONE_HI (786433,73095) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer WOPool::init(61, 9) RWPool::init(22, 14) 2013-09-17 06:27:43 [ndbd] INFO -- timerHandlingLab now: 1347510 sent: 1347344 diff: 166 RWPool::init(42, 75) blockSz: 800, wpp: 8188 -> 624 (76) RWPool::init(29, 624) RWPool::init(42, 16) RWPool::init(62, 12) RWPool::init(42, 83) RWPool::init(c2, 18) RWPool::init(e2, 16) WOPool::init(41, 8) RWPool::init(82, 12) RWPool::init(a2, 54) WOPool::init(21, 10) blockSz: 1024, wpp: 8188 -> 816 (28) RWPool::init(68, 816) 2013-09-17 06:27:45 [ndbd] INFO -- Start phase 0 completed Dbspj::execSTTOR() inst:0 phase=1 2013-09-17 06:27:45 [ndbd] INFO -- Start phase 1 completed 2013-09-17 06:27:46 [ndbd] INFO -- Start phase 2 completed 2013-09-17 06:27:46 [ndbd] INFO -- Start phase 3 completed Dbspj::execSTTOR() inst:0 phase=4 2013-09-17 06:27:46 [ndbd] INFO -- Start phase 4 completed Using 1 fragments per node 2013-09-17 06:27:48 [ndbd] INFO -- Start phase 5 completed 2013-09-17 06:27:48 [ndbd] INFO -- Start phase 6 completed 2013-09-17 06:27:48 [ndbd] INFO -- President restarts arbitration thread [state=1] 2013-09-17 06:27:48 [ndbd] WARNING -- Could not find an arbitrator, cluster is not partition-safe m_active_buckets.set(0) 2013-09-17 06:27:48 [ndbd] INFO -- Start phase 7 completed 2013-09-17 06:27:48 [ndbd] INFO -- Start phase 8 completed 2013-09-17 06:27:48 [ndbd] INFO -- Start phase 9 completed 2013-09-17 06:27:48 [ndbd] INFO -- Start phase 100 completed 2013-09-17 06:27:48 [ndbd] INFO -- Start phase 101 completed 2013-09-17 06:27:48 [ndbd] INFO -- Node started 2013-09-17 06:27:49 [ndbd] INFO -- Started arbitrator node 1 [ticket=075500010014a1f5] 2013-09-17 08:01:44 [ndbd] INFO -- Received signal 15. Performing stop. 2013-09-17 08:01:44 [ndbd] INFO -- Shutdown initiated 2013-09-17 08:01:44 [ndbd] INFO -- Shutdown completed - exiting 2013-09-17 08:01:46 [ndbd] INFO -- Angel shutting down 2013-09-17 08:01:46 [ndbd] INFO -- Node 2: Node shutdown completed. Initiated by signal 15. 2013-09-17 08:01:46 [ndbd] WARNING -- Unable to report shutdown reason to '127.0.0.1:1186'(error: Could not connect to socket - Unable to connect with connect string: nodeid=0,127.0.0.1:1186) 2013-09-17 09:14:16 [ndbd] INFO -- Angel pid: 2025 started child: 2026 2013-09-17 09:14:16 [ndbd] INFO -- Configuration fetched from '127.0.0.1:1186', generation: 1 NDBMT: non-mt 2013-09-17 09:14:16 [ndbd] INFO -- NDB Cluster -- DB node 2 2013-09-17 09:14:16 [ndbd] INFO -- mysql-5.6.11 ndb-7.3.2 -- 2013-09-17 09:14:16 [ndbd] INFO -- numa_set_interleave_mask(numa_all_nodes) : no numa support 2013-09-17 09:14:16 [ndbd] INFO -- Ndbd_mem_manager::init(1) min: 26732Mb initial: 26860Mb Adding 36Mb to ZONE_LO (1,1151) Instantiating DBSPJ instanceNo=0 2013-09-17 09:14:16 [ndbd] INFO -- Start initiated (mysql-5.6.11 ndb-7.3.2) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer Adding 8156Mb to ZONE_LO (1152,260991) Adding 8192Mb to ZONE_LO (262145,262142) Adding 8192Mb to ZONE_HI (524289,262142) Adding 2285Mb to ZONE_HI (786433,73095) NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer NDBFS/AsyncFile: Allocating 310392 for In/Deflate buffer WOPool::init(61, 9) RWPool::init(22, 14) 2013-09-17 09:14:45 [ndbd] INFO -- timerHandlingLab now: 304683 sent: 304466 diff: 217 RWPool::init(42, 75) blockSz: 800, wpp: 8188 -> 624 (76) RWPool::init(29, 624) RWPool::init(42, 16) RWPool::init(62, 12) RWPool::init(42, 83) RWPool::init(c2, 18) RWPool::init(e2, 16) WOPool::init(41, 8) RWPool::init(82, 12) RWPool::init(a2, 54) WOPool::init(21, 10) blockSz: 1024, wpp: 8188 -> 816 (28) RWPool::init(68, 816) 2013-09-17 09:14:47 [ndbd] INFO -- Start phase 0 completed Dbspj::execSTTOR() inst:0 phase=1 2013-09-17 09:14:47 [ndbd] INFO -- Start phase 1 completed 2013-09-17 09:14:48 [ndbd] INFO -- Start phase 2 completed 2013-09-17 09:14:48 [ndbd] INFO -- Start phase 3 completed Dbspj::execSTTOR() inst:0 phase=4 2013-09-17 09:14:48 [ndbd] INFO -- Start phase 4 completed Using 1 fragments per node 2013-09-17 09:14:49 [ndbd] INFO -- Start phase 5 completed 2013-09-17 09:14:49 [ndbd] INFO -- Start phase 6 completed 2013-09-17 09:14:49 [ndbd] INFO -- President restarts arbitration thread [state=1] 2013-09-17 09:14:49 [ndbd] WARNING -- Could not find an arbitrator, cluster is not partition-safe m_active_buckets.set(0) 2013-09-17 09:14:49 [ndbd] INFO -- Start phase 7 completed 2013-09-17 09:14:49 [ndbd] INFO -- Start phase 8 completed 2013-09-17 09:14:49 [ndbd] INFO -- Start phase 9 completed 2013-09-17 09:14:49 [ndbd] INFO -- Start phase 100 completed 2013-09-17 09:14:49 [ndbd] INFO -- Start phase 101 completed 2013-09-17 09:14:49 [ndbd] INFO -- Node started 2013-09-17 09:14:50 [ndbd] INFO -- Started arbitrator node 1 [ticket=07ea00010004b7e2]