# A node doesn't start normally **URL:** \ **Category:** Percona XtraDB Cluster 5.x\ **Created:** [May 14, 2012, 9:24am UTC](https://forums.percona.com/t/a-node-doesnt-start-normally/2422 "2012-05-14T09:24:07Z")\ **Posts on this page:** 3\ **Page:** 1
**Author:** ![azigui](https://avatars.discourse-cdn.com/v4/letter/a/cab0a1/32.png) [@azigui](https://forums.percona.com/u/azigui)\ **Post date:** [May 14, 2012, 9:24am UTC](https://forums.percona.com/t/a-node-doesnt-start-normally/2422/1 "2012-05-14T09:24:07Z")
Hello, I have an another problem. I can’t start my node 3, i have always this error message : ERROR 1047 (08S01) at line 1: Unknown command So why ? with this problem, i can’t stop mysql normally, i must execute kill -9 Thx a lot ---
**Author:** ![ayurchen](https://avatars.discourse-cdn.com/v4/letter/a/35a633/32.png) [@ayurchen](https://forums.percona.com/u/ayurchen)\ **Post date:** [May 14, 2012, 2:10pm UTC](https://forums.percona.com/t/a-node-doesnt-start-normally/2422/2 "2012-05-14T14:10:11Z")
There must be some problem with state snapshot transfer. It is impossible to say anything more without seeing the logs. ---
**Author:** ![azigui](https://avatars.discourse-cdn.com/v4/letter/a/cab0a1/32.png) [@azigui](https://forums.percona.com/u/azigui)\ **Post date:** [May 15, 2012, 10:42am UTC](https://forums.percona.com/t/a-node-doesnt-start-normally/2422/3 "2012-05-15T10:42:24Z")
Thx for your help. Here the log : 120514 16:49:22 mysqld\_safe Starting mysqld daemon with databases from /var/lib/mysql 120514 16:49:22 [Warning] option ‘wsrep\_max\_ws\_rows’: unsigned value 67108864 adjusted to 1048576 120514 16:49:22 [Note] Flashcache bypass: disabled 120514 16:49:22 [Note] Flashcache setup error is : ioctl failed 120514 16:49:22 [Note] Plugin ‘FEDERATED’ is disabled. 120514 16:49:23 InnoDB: The InnoDB memory heap is disabled 120514 16:49:23 InnoDB: Mutexes and rw\_locks use GCC atomic builtins 120514 16:49:23 InnoDB: Compressed tables use zlib 1.2.3 120514 16:49:23 InnoDB: Using Linux native AIO 120514 16:49:23 InnoDB: Initializing buffer pool, size = 1.0G 120514 16:49:23 InnoDB: Completed initialization of buffer pool 120514 16:49:23 InnoDB: highest supported file format is Barracuda. InnoDB: The log sequence number in ibdata files does not match InnoDB: the log sequence number in the ib\_logfiles! 120514 16:49:24 InnoDB: Database was not shut down normally! InnoDB: Starting crash recovery. InnoDB: Reading tablespace information from the .ibd files… InnoDB: Restoring possible half-written data pages from the doublewrite InnoDB: buffer… 120514 16:49:27 InnoDB: Waiting for the background threads to start 120514 16:49:28 Percona XtraDB ([http://www.percona.com](http://www.percona.com)) 1.1.8-20.1 started; log sequence number 370811796 120514 16:49:28 [Warning] ‘user’ entry ‘root@percona-1’ ignored in --skip-name-resolve mode. 120514 16:49:28 [Warning] ‘proxies\_priv’ entry ‘@ root@percona-1’ ignored in --skip-name-resolve mode. 120514 16:49:28 [Note] Event Scheduler: Loaded 0 events 120514 16:49:28 [Note] WSREP: wsrep\_load(): loading provider library ‘/usr/lib64/libgalera\_smm.so’ 120514 16:49:29 [Note] WSREP: wsrep\_load(): Galera 2.1dev(r109) by Codership Oy \<[info@codership.com](mailto:info@codership.com)\> loaded succesfully. 120514 16:49:29 [Note] WSREP: Reusing existing ‘/var/lib/mysql//galera.cache’. 120514 16:49:29 [Note] WSREP: Passing config to GCS: gcache.dir = /var/lib/mysql/; gcache.keep\_pages\_size = 0; gcache.mem\_size = 0; gcache.name = /var/lib/mysql//galera.cache; gcache.page\_size = 128M; gcache.size = 128M; gcs.fc\_debug = 0; gcs.fc\_factor = 0.5; gcs.fc\_limit = 16; gcs.fc\_master\_slave = NO; gcs.max\_packet\_size = 64500; gcs.max\_throttle = 0.25; gcs.recv\_q\_hard\_limit = 9223372036854775807; gcs.recv\_q\_soft\_limit = 0.25; replicator.causal\_read\_timeout = PT30S; replicator.commit\_order = 3 120514 16:49:29 [Note] WSREP: Start replication 120514 16:49:29 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1 120514 16:49:29 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 120514 16:49:29 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 120514 16:49:29 [Note] WSREP: protonet asio version 0 120514 16:49:29 [Note] WSREP: backend: asio 120514 16:49:29 [Note] WSREP: GMCast version 0 120514 16:49:29 [Note] WSREP: (02230a2a-9dd4-11e1-0800-37cde4c9bd30, ‘tcp://0.0.0.0:4567’) listening at tcp://0.0.0.0:4567 120514 16:49:29 [Note] WSREP: (02230a2a-9dd4-11e1-0800-37cde4c9bd30, ‘tcp://0.0.0.0:4567’) multicast: , ttl: 1 120514 16:49:29 [Note] WSREP: EVS version 0 120514 16:49:29 [Note] WSREP: PC version 0 120514 16:49:29 [Note] WSREP: gcomm: connecting to group ‘my\_wsrep\_cluster’, peer ‘10.7.110.3:’ 120514 16:49:29 [Note] WSREP: (02230a2a-9dd4-11e1-0800-37cde4c9bd30, ‘tcp://0.0.0.0:4567’) turning message relay requesting on, nonlive peers: tcp://10.7.110.4:4567 120514 16:49:29 [Note] WSREP: (02230a2a-9dd4-11e1-0800-37cde4c9bd30, ‘tcp://0.0.0.0:4567’) turning message relay requesting off 120514 16:49:29 [Note] WSREP: declaring e039838f-9ab6-11e1-0800-ee89c2714ab0 stable 120514 16:49:29 [Note] WSREP: declaring e137a28e-9ab6-11e1-0800-0427149fd658 stable 120514 16:49:29 [Note] WSREP: view(view\_id(PRIM,02230a2a-9dd4-11e1-0800-37cde4c9bd30,7) memb { 02230a2a-9dd4-11e1-0800-37cde4c9bd30, e039838f-9ab6-11e1-0800-ee89c2714ab0, e137a28e-9ab6-11e1-0800-0427149fd658, } joined { } left { } partitioned { }) 120514 16:49:30 [Note] WSREP: gcomm: connected 120514 16:49:30 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 120514 16:49:30 [Note] WSREP: Shifting CLOSED → OPEN (TO: 0) 120514 16:49:30 [Note] WSREP: Opened channel ‘my\_wsrep\_cluster’ 120514 16:49:30 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my\_idx = 0, memb\_num = 3 120514 16:49:30 [Note] /usr/sbin/mysqld: ready for connections. Version: ‘5.5.20-55-log’ socket: ‘/var/run/mysqld/mysqld.sock’ port: 3306 Percona XtraDB Cluster (GPL), wsrep\_23.4.r3748 120514 16:49:30 [Note] WSREP: STATE\_EXCHANGE: sent state UUID: 02c311df-9dd4-11e1-0800-4eb11325e27f 120514 16:49:30 [Note] WSREP: STATE EXCHANGE: sent state msg: 02c311df-9dd4-11e1-0800-4eb11325e27f 120514 16:49:30 [Note] WSREP: STATE EXCHANGE: got state msg: 02c311df-9dd4-11e1-0800-4eb11325e27f from 0 (percona-3) 120514 16:49:30 [Note] WSREP: STATE EXCHANGE: got state msg: 02c311df-9dd4-11e1-0800-4eb11325e27f from 1 (percona1) 120514 16:49:30 [Note] WSREP: STATE EXCHANGE: got state msg: 02c311df-9dd4-11e1-0800-4eb11325e27f from 2 (percona-2) 120514 16:49:30 [Note] WSREP: Quorum results: version = 2, component = PRIMARY, conf\_id = 6, members = 2/3 (joined/total), act\_id = 61951, last\_appl. = -1, protocols = 0/3/1 (gcs/repl/appl), group UUID = 134487b3-8f74-11e1-0800-14b30cfd2465 120514 16:49:30 [Note] WSREP: Flow-control interval: [14, 28] 120514 16:49:30 [Note] WSREP: Shifting OPEN → PRIMARY (TO: 61951) 120514 16:49:30 [Note] WSREP: State transfer required: Group state: 134487b3-8f74-11e1-0800-14b30cfd2465:61951 Local state: 00000000-0000-0000-0000-000000000000:-1 120514 16:49:30 [Note] WSREP: New cluster view: global state: 134487b3-8f74-11e1-0800-14b30cfd2465:61951, view# 7: Primary, number of nodes: 3, my index: 0, protocol version 1 120514 16:49:30 [Warning] WSREP: Gap in state sequence. Need state transfer. 120514 16:49:32 [Note] WSREP: SST kill local trx: 10 120514 16:49:32 [Note] WSREP: Prepared SST request: mysqldump|10.7.110.5:3306 120514 16:49:32 [Note] WSREP: wsrep\_notify\_cmd is not defined, skipping notification. 120514 16:49:32 [Note] WSREP: Assign initial position for certification: 61951, protocol version: 2 120514 16:49:32 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (134487b3-8f74-11e1-0800-14b30cfd2465): 1 (Operation not permitted) at galera/src/replicator\_str.cpp:prepare\_for\_IST():429. IST will be unavailable. 120514 16:49:32 [Note] WSREP: Node 0 (percona-3) requested state transfer from ‘_any_’. Selected 1 (percona1)(SYNCED) as donor. 120514 16:49:32 [Note] WSREP: Shifting PRIMARY → JOINER (TO: 61951) 120514 16:49:32 [Note] WSREP: Requesting state transfer: success, donor: 1 120514 16:51:03 [Note] /usr/sbin/mysqld: Normal shutdown 120514 16:51:03 [Note] WSREP: Stop replication 120514 16:51:03 [Note] WSREP: Closing send monitor… 120514 16:51:03 [Note] WSREP: Closed send monitor. 120514 16:51:03 [Note] WSREP: gcomm: terminating thread 120514 16:51:03 [Note] WSREP: gcomm: joining thread 120514 16:51:03 [Note] WSREP: gcomm: closing backend 120514 16:51:03 [Note] WSREP: view(view\_id(NON\_PRIM,02230a2a-9dd4-11e1-0800-37cde4c9bd30,7 ) memb { 02230a2a-9dd4-11e1-0800-37cde4c9bd30, } joined { } left { } partitioned { e039838f-9ab6-11e1-0800-ee89c2714ab0, e137a28e-9ab6-11e1-0800-0427149fd658, }) 120514 16:51:03 [Note] WSREP: view((empty)) 120514 16:51:03 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my\_idx = 0, memb\_num = 1 120514 16:51:03 [Note] WSREP: gcomm: closed 120514 16:51:03 [Note] WSREP: Flow-control interval: [8, 16] 120514 16:51:03 [Note] WSREP: Received NON-PRIMARY. 120514 16:51:03 [Note] WSREP: Shifting JOINER → OPEN (TO: 61951) 120514 16:51:03 [Note] WSREP: Received self-leave message. 120514 16:51:03 [Note] WSREP: Flow-control interval: [0, 0] 120514 16:51:03 [Note] WSREP: Received SELF-LEAVE. Closing connection. 120514 16:51:03 [Note] WSREP: Shifting OPEN → CLOSED (TO: 61951) 120514 16:51:03 [Note] WSREP: RECV thread exiting 0: Success 120514 16:51:03 [Note] WSREP: recv\_thread() joined. 120514 16:51:03 [Note] WSREP: Closing slave action queue. 120514 16:51:05 [Note] WSREP: SST kill local trx: 85 120514 16:51:58 mysqld\_safe Starting mysqld daemon with databases from /var/lib/mysql 120514 16:51:58 [Warning] option ‘wsrep\_max\_ws\_rows’: unsigned value 67108864 adjusted to 1048576 120514 16:51:58 [Note] Flashcache bypass: disabled 120514 16:51:58 [Note] Flashcache setup error is : ioctl failed 120514 16:51:58 [Note] Plugin ‘FEDERATED’ is disabled. 120514 16:51:58 InnoDB: The InnoDB memory heap is disabled 120514 16:51:58 InnoDB: Mutexes and rw\_locks use GCC atomic builtins 120514 16:51:58 InnoDB: Compressed tables use zlib 1.2.3 120514 16:51:58 InnoDB: Using Linux native AIO 120514 16:51:58 InnoDB: Initializing buffer pool, size = 1.0G 120514 16:51:58 InnoDB: Completed initialization of buffer pool 120514 16:51:58 InnoDB: highest supported file format is Barracuda. InnoDB: The log sequence number in ibdata files does not match InnoDB: the log sequence number in the ib\_logfiles! 120514 16:51:58 InnoDB: Database was not shut down normally! InnoDB: Starting crash recovery. InnoDB: Reading tablespace information from the .ibd files… InnoDB: Restoring possible half-written data pages from the doublewrite InnoDB: buffer… 120514 16:51:59 InnoDB: Waiting for the background threads to start 120514 16:52:00 Percona XtraDB ([http://www.percona.com](http://www.percona.com)) 1.1.8-20.1 started; log sequence number 741028919 120514 16:52:00 [Warning] ‘user’ entry ‘root@percona-1’ ignored in --skip-name-resolve mode. 120514 16:52:00 [Warning] ‘proxies\_priv’ entry ‘@ root@percona-1’ ignored in --skip-name-resolve mode. 120514 16:52:00 [Note] Event Scheduler: Loaded 0 events 120514 16:52:00 [Note] WSREP: wsrep\_load(): loading provider library ‘/usr/lib64/libgalera\_smm.so’ 120514 16:52:00 [Note] WSREP: wsrep\_load(): Galera 2.1dev(r109) by Codership Oy \<[info@codership.com](mailto:info@codership.com)\> loaded succesfully. 120514 16:52:01 [Note] WSREP: Reusing existing ‘/var/lib/mysql//galera.cache’. 120514 16:52:01 [Note] WSREP: Passing config to GCS: gcache.dir = /var/lib/mysql/; gcache.keep\_pages\_size = 0; gcache.mem\_size = 0; gcache.name = /var/lib/mysql//galera.cache; gcache.page\_size = 128M; gcache.size = 128M; gcs.fc\_debug = 0; gcs.fc\_factor = 0.5; gcs.fc\_limit = 16; gcs.fc\_master\_slave = NO; gcs.max\_packet\_size = 64500; gcs.max\_throttle = 0.25; gcs.recv\_q\_hard\_limit = 9223372036854775807; gcs.recv\_q\_soft\_limit = 0.25; replicator.causal\_read\_timeout = PT30S; replicator.commit\_order = 3 120514 16:52:01 [Note] WSREP: Start replication 120514 16:52:01 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1 120514 16:52:01 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 120514 16:52:01 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 120514 16:52:01 [Note] WSREP: protonet asio version 0 120514 16:52:01 [Note] WSREP: backend: asio 120514 16:52:01 [Note] WSREP: GMCast version 0 120514 16:52:01 [Note] WSREP: (5c9ad119-9dd4-11e1-0800-dea8fc9be39a, ‘tcp://0.0.0.0:4567’) listening at tcp://0.0.0.0:4567 120514 16:52:01 [Note] WSREP: (5c9ad119-9dd4-11e1-0800-dea8fc9be39a, ‘tcp://0.0.0.0:4567’) multicast: , ttl: 1 120514 16:52:01 [Note] WSREP: EVS version 0 120514 16:52:01 [Note] WSREP: PC version 0 120514 16:52:01 [Note] WSREP: gcomm: connecting to group ‘my\_wsrep\_cluster’, peer ‘10.7.110.3:’ 120514 16:52:01 [Note] WSREP: (5c9ad119-9dd4-11e1-0800-dea8fc9be39a, ‘tcp://0.0.0.0:4567’) turning message relay requesting on, nonlive peers: tcp://10.7.110.4:4567 120514 16:52:01 [Note] WSREP: (5c9ad119-9dd4-11e1-0800-dea8fc9be39a, ‘tcp://0.0.0.0:4567’) turning message relay requesting off 120514 16:52:01 [Note] WSREP: declaring e039838f-9ab6-11e1-0800-ee89c2714ab0 stable 120514 16:52:01 [Note] WSREP: declaring e137a28e-9ab6-11e1-0800-0427149fd658 stable 120514 16:52:01 [Note] WSREP: view(view\_id(PRIM,5c9ad119-9dd4-11e1-0800-dea8fc9be39a,9) memb { 5c9ad119-9dd4-11e1-0800-dea8fc9be39a, e039838f-9ab6-11e1-0800-ee89c2714ab0, e137a28e-9ab6-11e1-0800-0427149fd658, } joined { } left { } partitioned { }) 120514 16:52:01 [Note] WSREP: gcomm: connected 120514 16:52:01 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 120514 16:52:01 [Note] WSREP: Shifting CLOSED → OPEN (TO: 0) 120514 16:52:01 [Note] WSREP: Opened channel ‘my\_wsrep\_cluster’ 120514 16:52:01 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my\_idx = 0, memb\_num = 3 120514 16:52:01 [Note] /usr/sbin/mysqld: ready for connections. Version: ‘5.5.20-55-log’ socket: ‘/var/run/mysqld/mysqld.sock’ port: 3306 Percona XtraDB Cluster (GPL), wsrep\_23.4.r3748 120514 16:52:01 [Note] WSREP: STATE\_EXCHANGE: sent state UUID: 5ce79cdb-9dd4-11e1-0800-1742273f8cf8 120514 16:52:01 [Note] WSREP: STATE EXCHANGE: sent state msg: 5ce79cdb-9dd4-11e1-0800-1742273f8cf8 120514 16:52:01 [Note] WSREP: STATE EXCHANGE: got state msg: 5ce79cdb-9dd4-11e1-0800-1742273f8cf8 from 0 (percona-3) 120514 16:52:01 [Note] WSREP: STATE EXCHANGE: got state msg: 5ce79cdb-9dd4-11e1-0800-1742273f8cf8 from 1 (percona1) 120514 16:52:01 [Note] WSREP: STATE EXCHANGE: got state msg: 5ce79cdb-9dd4-11e1-0800-1742273f8cf8 from 2 (percona-2) 120514 16:52:01 [Note] WSREP: Quorum results: version = 2, component = PRIMARY, conf\_id = 8, members = 2/3 (joined/total), act\_id = 61951, last\_appl. = -1, protocols = 0/3/1 (gcs/repl/appl), group UUID = 134487b3-8f74-11e1-0800-14b30cfd2465 120514 16:52:01 [Note] WSREP: Flow-control interval: [14, 28] 120514 16:52:01 [Note] WSREP: Shifting OPEN → PRIMARY (TO: 61951) 120514 16:52:01 [Note] WSREP: State transfer required: Group state: 134487b3-8f74-11e1-0800-14b30cfd2465:61951 Local state: 00000000-0000-0000-0000-000000000000:-1 120514 16:52:01 [Note] WSREP: New cluster view: global state: 134487b3-8f74-11e1-0800-14b30cfd2465:61951, view# 9: Primary, number of nodes: 3, my index: 0, protocol version 1 120514 16:52:01 [Warning] WSREP: Gap in state sequence. Need state transfer. 120514 16:52:03 [Note] WSREP: Prepared SST request: mysqldump|10.7.110.5:3306 120514 16:52:03 [Note] WSREP: wsrep\_notify\_cmd is not defined, skipping notification. 120514 16:52:03 [Note] WSREP: Assign initial position for certification: 61951, protocol version: 2 120514 16:52:03 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (134487b3-8f74-11e1-0800-14b30cfd2465): 1 (Operation not permitted) at galera/src/replicator\_str.cpp:prepare\_for\_IST():429. IST will be unavailable. 120514 16:52:03 [Note] WSREP: Node 0 (percona-3) requested state transfer from ‘_any_’. Selected 1 (percona1)(SYNCED) as donor. 120514 16:52:03 [Note] WSREP: Shifting PRIMARY → JOINER (TO: 61951) 120514 16:52:03 [Note] WSREP: Requesting state transfer: success, donor: 1