wsrep_sst_rsync fails in PXC 5.6
Affects | Status | Importance | Assigned to | Milestone | ||
---|---|---|---|---|---|---|
MySQL patches by Codership |
Fix Released
|
High
|
Teemu Ollakka | |||
Percona XtraDB Cluster moved to https://jira.percona.com/projects/PXC | Status tracked in 5.6 | |||||
5.5 |
Invalid
|
Undecided
|
Raghavendra D Prabhu | |||
5.6 |
Fix Released
|
Undecided
|
Unassigned |
Bug Description
Log:
=======
CURRENT_TEST: main.1st
2013-09-20 16:28:30 0 [Warning] WSREP: wsrep_sst_
2013-09-20 16:28:30 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_
2013-09-20 16:28:30 15524 [Note] WSREP: Read nil XID from storage engines, skipping position init
2013-09-20 16:28:30 15524 [Note] WSREP: wsrep_load(): loading provider library '/home/
2013-09-20 16:28:30 15524 [Note] WSREP: wsrep_load(): Galera 3.0(r157) by Codership Oy <email address hidden> loaded successfully.
2013-09-20 16:28:30 15524 [Note] WSREP: CRC-32C: using "slicing-by-8" algorithm.
2013-09-20 16:28:30 15524 [Warning] WSREP: Could not open saved state file for reading: /home/jenkins/
2013-09-20 16:28:30 15524 [Note] WSREP: Found saved state: 00000000-
2013-09-20 16:28:30 15524 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 4567; cert.log_conflicts = no; gcache.dir = /home/jenkins/
2013-09-20 16:28:30 15524 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2013-09-20 16:28:30 15524 [Note] WSREP: wsrep_sst_grab()
2013-09-20 16:28:30 15524 [Note] WSREP: Start replication
2013-09-20 16:28:30 15524 [Note] WSREP: Setting initial position to 00000000-
2013-09-20 16:28:30 15524 [Note] WSREP: protonet asio version 0
2013-09-20 16:28:30 15524 [Note] WSREP: Using CRC-32C (optimized) for message checksums.
2013-09-20 16:28:30 15524 [Note] WSREP: backend: asio
2013-09-20 16:28:30 15524 [Note] WSREP: GMCast version 0
2013-09-20 16:28:30 15524 [Note] WSREP: (8a1ea6d2-
2013-09-20 16:28:30 15524 [Note] WSREP: (8a1ea6d2-
2013-09-20 16:28:30 15524 [Note] WSREP: EVS version 0
2013-09-20 16:28:30 15524 [Note] WSREP: PC version 0
2013-09-20 16:28:30 15524 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer ''
2013-09-20 16:28:30 15524 [Note] WSREP: Node 8a1ea6d2-
2013-09-20 16:28:30 15524 [Note] WSREP: view(view_
8a1ea6d2-
} joined {
} left {
} partitioned {
})
2013-09-20 16:28:30 15524 [Note] WSREP: gcomm: connected
2013-09-20 16:28:30 15524 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2013-09-20 16:28:30 15524 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2013-09-20 16:28:30 15524 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2013-09-20 16:28:30 15524 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2013-09-20 16:28:30 15524 [Note] WSREP: Waiting for SST to complete.
2013-09-20 16:28:30 15524 [Note] WSREP: Starting new group from scratch: 8a1f3c02-
2013-09-20 16:28:30 15524 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 8a1f4d77-
2013-09-20 16:28:30 15524 [Note] WSREP: STATE EXCHANGE: sent state msg: 8a1f4d77-
2013-09-20 16:28:30 15524 [Note] WSREP: STATE EXCHANGE: got state msg: 8a1f4d77-
2013-09-20 16:28:30 15524 [Note] WSREP: Quorum results:
version = 3,
component = PRIMARY,
conf_id = 0,
members = 1/1 (joined/total),
act_id = 0,
last_appl. = -1,
protocols = 0/5/2 (gcs/repl/appl),
group UUID = 8a1f3c02-
2013-09-20 16:28:30 15524 [Note] WSREP: Flow-control interval: [16, 16]
2013-09-20 16:28:30 15524 [Note] WSREP: Restored state OPEN -> JOINED (0)
2013-09-20 16:28:30 15524 [Note] WSREP: Member 0 (vps-centos6-
2013-09-20 16:28:30 15524 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2013-09-20 16:28:30 15524 [Note] WSREP: New cluster view: global state: 8a1f3c02-
2013-09-20 16:28:30 15524 [Note] WSREP: SST complete, seqno: 0
2013-09-20 16:28:30 15524 [Note] Plugin 'FEDERATED' is disabled.
2013-09-20 16:28:30 7f97cd7d67e0 InnoDB: Warning: Using innodb_
2013-09-20 16:28:30 7f97cd7d67e0 InnoDB: Warning: Using innodb_
2013-09-20 16:28:30 15524 [Note] InnoDB: The InnoDB memory heap is disabled
2013-09-20 16:28:30 15524 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2013-09-20 16:28:30 15524 [Note] InnoDB: Compressed tables use zlib 1.2.3
2013-09-20 16:28:30 15524 [Note] InnoDB: Using Linux native AIO
2013-09-20 16:28:30 15524 [Note] InnoDB: Not using CPU crc32 instructions
2013-09-20 16:28:30 15524 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2013-09-20 16:28:30 15524 [Note] InnoDB: Completed initialization of buffer pool
2013-09-20 16:28:30 15524 [Note] InnoDB: Highest supported file format is Barracuda.
2013-09-20 16:28:30 15524 [Note] InnoDB: 128 rollback segment(s) are active.
2013-09-20 16:28:30 15524 [Note] InnoDB: Waiting for purge to start
2013-09-20 16:28:30 15524 [Note] InnoDB: Percona XtraDB (http://
2013-09-20 16:28:30 15524 [Warning] /home/jenkins/
2013-09-20 16:28:30 15524 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: 8a3f482e-
2013-09-20 16:28:30 15524 [Note] RSA private key file not found: /home/jenkins/
2013-09-20 16:28:30 15524 [Note] RSA public key file not found: /home/jenkins/
2013-09-20 16:28:30 15524 [Note] Server hostname (bind-address): '*'; port: 15001
2013-09-20 16:28:30 15524 [Note] IPv6 is available.
2013-09-20 16:28:30 15524 [Note] - '::' resolves to '::';
2013-09-20 16:28:30 15524 [Note] Server socket created on IP: '::'.
2013-09-20 16:28:30 15524 [Warning] 'user' entry '<email address hidden>' ignored in --skip-name-resolve mode.
2013-09-20 16:28:30 15524 [Warning] 'proxies_priv' entry '@ <email address hidden>' ignored in --skip-name-resolve mode.
2013-09-20 16:28:30 15524 [Note] Event Scheduler: Loaded 0 events
2013-09-20 16:28:30 15524 [Note] WSREP: inited wsrep sidno 1
2013-09-20 16:28:30 15524 [Note] /home/jenkins/
Version: '5.6.13-24.0-log' socket: '/home/
2013-09-20 16:28:30 15524 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2013-09-20 16:28:30 15524 [Note] WSREP: REPL Protocols: 5 (3, 1)
2013-09-20 16:28:30 15524 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2013-09-20 16:28:30 15524 [Note] WSREP: Service thread queue flushed.
2013-09-20 16:28:30 15524 [Note] WSREP: Synchronized with group, ready for connections
2013-09-20 16:28:30 15524 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2013-09-20 16:29:27 15524 [Note] WSREP: declaring abb8db09-
2013-09-20 16:29:27 15524 [Note] WSREP: Node 8a1ea6d2-
2013-09-20 16:29:27 15524 [Note] WSREP: view(view_
8a1ea6d2-
abb8db09-
} joined {
} left {
} partitioned {
})
2013-09-20 16:29:27 15524 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2
2013-09-20 16:29:27 15524 [Note] WSREP: STATE_EXCHANGE: sent state UUID: ac05c298-
2013-09-20 16:29:27 15524 [Note] WSREP: STATE EXCHANGE: sent state msg: ac05c298-
2013-09-20 16:29:27 15524 [Note] WSREP: STATE EXCHANGE: got state msg: ac05c298-
2013-09-20 16:29:27 15524 [Note] WSREP: STATE EXCHANGE: got state msg: ac05c298-
2013-09-20 16:29:27 15524 [Note] WSREP: Quorum results:
version = 3,
component = PRIMARY,
conf_id = 1,
members = 1/2 (joined/total),
act_id = 41,
last_appl. = 39,
protocols = 0/5/2 (gcs/repl/appl),
group UUID = 8a1f3c02-
2013-09-20 16:29:27 15524 [Note] WSREP: Flow-control interval: [23, 23]
2013-09-20 16:29:27 15524 [Note] WSREP: New cluster view: global state: 8a1f3c02-
2013-09-20 16:29:27 15524 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2013-09-20 16:29:27 15524 [Note] WSREP: REPL Protocols: 5 (3, 1)
2013-09-20 16:29:27 15524 [Note] WSREP: Assign initial position for certification: 41, protocol version: 3
2013-09-20 16:29:27 15524 [Note] WSREP: Service thread queue flushed.
2013-09-20 16:29:30 15524 [Note] WSREP: Node 1.0 (vps-centos6-
2013-09-20 16:29:30 15524 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 41)
2013-09-20 16:29:30 15524 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2013-09-20 16:29:30 15524 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'donor' --address '127.0.
2013-09-20 16:29:30 15524 [Note] WSREP: sst_donor_thread signaled with 0
2013-09-20 16:29:30 15524 [Note] WSREP: Flushing tables for SST...
2013-09-20 16:29:30 15524 [Note] WSREP: Provider paused at 8a1f3c02-
2013-09-20 16:29:30 15524 [Note] WSREP: Tables flushed.
tail: cannot open `0.index' for reading: No such file or directory
2013-09-20 16:29:30 15524 [ERROR] WSREP: Failed to read from: wsrep_sst_rsync --role 'donor' --address '127.0.
2013-09-20 16:29:30 15524 [Note] WSREP: Provider resumed.
2013-09-20 16:29:30 15524 [ERROR] WSREP: Process completed with error: wsrep_sst_rsync --role 'donor' --address '127.0.
2013-09-20 16:29:30 15524 [Warning] WSREP: 0.0 (vps-centos6-
2013-09-20 16:29:30 15524 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 41)
2013-09-20 16:29:30 15524 [Note] WSREP: Member 0 (vps-centos6-
2013-09-20 16:29:30 15524 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 41)
2013-09-20 16:29:30 15524 [Note] WSREP: Synchronized with group, ready for connections
2013-09-20 16:29:30 15524 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2013-09-20 16:29:56 15524 [Note] /home/jenkins/
2013-09-20 16:29:56 15524 [Note] WSREP: Stop replication
2013-09-20 16:29:56 15524 [Note] WSREP: Closing send monitor...
2013-09-20 16:29:56 15524 [Note] WSREP: Closed send monitor.
2013-09-20 16:29:56 15524 [Note] WSREP: gcomm: terminating thread
2013-09-20 16:29:56 15524 [Note] WSREP: gcomm: joining thread
2013-09-20 16:29:56 15524 [Note] WSREP: gcomm: closing backend
2013-09-20 16:29:56 15524 [Note] WSREP: (8a1ea6d2-
2013-09-20 16:29:56 15524 [Note] WSREP: (8a1ea6d2-
2013-09-20 16:29:56 15524 [Note] WSREP: Node 8a1ea6d2-
2013-09-20 16:29:56 15524 [Note] WSREP: view(view_
8a1ea6d2-
} joined {
} left {
} partitioned {
abb8db09-
})
2013-09-20 16:29:56 15524 [Note] WSREP: forgetting abb8db09-
2013-09-20 16:29:56 15524 [Note] WSREP: deleting entry tcp://127.
2013-09-20 16:29:56 15524 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2013-09-20 16:29:56 15524 [Note] WSREP: (8a1ea6d2-
2013-09-20 16:29:56 15524 [Note] WSREP: view((empty))
2013-09-20 16:29:56 15524 [Note] WSREP: gcomm: closed
2013-09-20 16:29:56 15524 [Warning] WSREP: 0x276a670 down context(s) not set
2013-09-20 16:29:56 15524 [Warning] WSREP: Failed to send state UUID: -107 (Transport endpoint is not connected)
2013-09-20 16:29:56 15524 [Note] WSREP: Received self-leave message.
2013-09-20 16:29:56 15524 [Note] WSREP: Flow-control interval: [0, 0]
2013-09-20 16:29:56 15524 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2013-09-20 16:29:56 15524 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 41)
2013-09-20 16:29:56 15524 [Note] WSREP: RECV thread exiting 0: Success
2013-09-20 16:29:56 15524 [Note] WSREP: New cluster view: global state: 8a1f3c02-
2013-09-20 16:29:56 15524 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2013-09-20 16:29:56 15524 [Note] WSREP: recv_thread() joined.
2013-09-20 16:29:56 15524 [Note] WSREP: Closing replication queue.
2013-09-20 16:29:56 15524 [Note] WSREP: applier thread exiting (code:0)
2013-09-20 16:29:56 15524 [Note] WSREP: Closing slave action queue.
2013-09-20 16:29:56 15524 [Note] WSREP: applier thread exiting (code:6)
2013-09-20 16:29:58 15524 [Note] WSREP: rollbacker thread exiting
2013-09-20 16:29:58 15524 [Note] Giving 0 client threads a chance to die gracefully
2013-09-20 16:29:58 15524 [Note] Event Scheduler: Purging the queue. 0 events
2013-09-20 16:29:58 15524 [Note] Shutting down slave threads
2013-09-20 16:29:58 15524 [Note] Forcefully disconnecting 0 remaining clients
2013-09-20 16:29:58 15524 [Note] WSREP: dtor state: CLOSED
2013-09-20 16:29:58 15524 [Note] WSREP: mon: entered 41 oooe fraction 0 oool fraction 0
2013-09-20 16:29:58 15524 [Note] WSREP: mon: entered 41 oooe fraction 0 oool fraction 0
2013-09-20 16:29:58 15524 [Note] WSREP: mon: entered 86 oooe fraction 0 oool fraction 0
2013-09-20 16:29:58 15524 [Note] WSREP: cert index usage at exit 0
2013-09-20 16:29:58 15524 [Note] WSREP: cert trx map usage at exit 0
2013-09-20 16:29:58 15524 [Note] WSREP: deps set usage at exit 0
2013-09-20 16:29:58 15524 [Note] WSREP: avg deps dist 0
2013-09-20 16:29:58 15524 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2013-09-20 16:29:58 15524 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 41)
2013-09-20 16:29:58 15524 [Note] WSREP: Flushing memory map to disk...
2013-09-20 16:29:58 15524 [Note] Binlog end
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'partition'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'ARCHIVE'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'PERFORMANCE_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_METRICS'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_CMPMEM'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_CMP'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_LOCKS'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'INNODB_TRX'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'XTRADB_RSEG'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'XTRADB_
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'XTRADB_READ_VIEW'
2013-09-20 16:29:58 15524 [Note] Shutting down plugin 'InnoDB'
2013-09-20 16:29:58 15524 [Note] InnoDB: FTS optimize thread exiting.
2013-09-20 16:29:58 15524 [Note] InnoDB: Starting shutdown...
2013-09-20 16:29:59 15524 [Note] InnoDB: Shutdown completed; log sequence number 28502435
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'BLACKHOLE'
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'MRG_MYISAM'
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'MEMORY'
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'CSV'
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'MyISAM'
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'sha256_password'
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'mysql_
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'mysql_
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'wsrep'
2013-09-20 16:29:59 15524 [Note] Shutting down plugin 'binlog'
2013-09-20 16:29:59 15524 [Note] /home/jenkins/
=======
The script used is https:/
Seems --binlog 0 is being passed even when binlogging is off.
Related branches
Changed in percona-xtradb-cluster: | |
status: | New → Fix Committed |
assignee: | nobody → Raghavendra D Prabhu (raghavendra-prabhu) |
Changed in codership-mysql: | |
assignee: | nobody → Teemu Ollakka (teemu-ollakka) |
importance: | Undecided → High |
milestone: | none → 5.6.14-24.1 |
status: | New → Confirmed |
Changed in percona-xtradb-cluster: | |
milestone: | none → 5.6.13-24.0 |
Changed in codership-mysql: | |
status: | Fix Committed → Fix Released |
Raghu, are you sure that binlogging is OFF? Specifically log_bin option?