[MDEV-11486] Slave IO thread failed with Error reading packet from server: event read from binlog did not pass crc check Created: 2016-12-05  Updated: 2023-04-27

Status: Open
Project: MariaDB Server
Component/s: Tests
Affects Version/s: 10.1
Fix Version/s: 10.4, 10.5

Type: Bug Priority: Minor
Reporter: Elena Stepanova Assignee: Alice Sherepa
Resolution: Unresolved Votes: 1
Labels: None


 Description   

2016-12-05 14:39:56 139701594364672 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16020',replication started in log 'master-bin.000001' at position 4
2016-12-05 14:39:56 139701559401216 [Note] Error reading relay log event: slave SQL thread was killed
2016-12-05 14:39:56 139701594364672 [Note] Slave I/O thread exiting, read up to log 'master-bin.000002', position 249
2016-12-05 14:39:56 139701594364672 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 1418, relay log './slave-relay-bin.000002' position: 1671
2016-12-05 14:39:56 139701559401216 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16020',replication started in log 'master-bin.000002' at position 249
2016-12-05 14:39:56 139701559401216 [ERROR] Error reading packet from server: event read from binlog did not pass crc check; the first event 'master-bin.000002' at 249, the last event read from 'master-bin.000002' at 249, the last byte read from 'master-bin.000002' at 289. (server_errno=1236)
2016-12-05 14:39:56 139701559401216 [ERROR] Slave I/O: Got fatal error 1236 from master when reading data from binary log: 'event read from binlog did not pass crc check; the first event 'master-bin.000002' at 249, the last event read from 'master-bin.000002' at 249, the last byte read from 'master-bin.000002' at 289.', Internal MariaDB error code: 1236
2016-12-05 14:39:56 139701559401216 [Note] Slave I/O thread exiting, read up to log 'master-bin.000002', position 249

The test failed on a development tree in buildbot:
http://buildbot.askmonty.org/buildbot/builders/kvm-deb-precise-amd64/builds/7541/steps/test_5/logs/stdio

binlog_encryption.rpl_checksum_cache 'stmt,xtradb' w1 [ fail ]
        Test ended at 2016-12-05 14:45:00
 
CURRENT_TEST: binlog_encryption.rpl_checksum_cache
mysqltest: In included file "./include/wait_for_slave_param.inc": 
included from ./include/wait_for_slave_io_to_start.inc at line 40:
included from ./include/wait_for_slave_to_start.inc at line 26:
included from ./include/start_slave.inc at line 35:
included from ./extra/rpl_tests/rpl_checksum_cache.inc at line 26:
included from /usr/share/mysql/mysql-test/suite/binlog_encryption/rpl_checksum_cache.test at line 2:
At line 115: Timeout in include/wait_for_slave_param.inc
 
The result from queries just before the failure was:
< snip >
**** SHOW BINARY LOGS on server_1 ****
SHOW BINARY LOGS;
Log_name	File_size
master-bin.000001	1462
master-bin.000002	420
 
**** SHOW BINLOG EVENTS on server_1 ****
binlog_name = 'master-bin.000002'
SHOW BINLOG EVENTS IN 'master-bin.000002';
Log_name	Pos	Event_type	Server_id	End_log_pos	Info
master-bin.000002	4	Format_desc	1	249	Server ver: 10.1.20-MariaDB-1~precise, Binlog ver: 4
master-bin.000002	249	Start_encryption	1	289	
master-bin.000002	289	Gtid_list	1	332	[0-1-2]
master-bin.000002	332	Binlog_checkpoint	1	376	master-bin.000001
master-bin.000002	376	Binlog_checkpoint	1	420	master-bin.000002
 
**** SHOW RELAYLOG EVENTS on server_1 ****
relaylog_name = 'No such row'
SHOW RELAYLOG EVENTS IN 'No such row';
Log_name	Pos	Event_type	Server_id	End_log_pos	Info
 
More results from queries before failure can be found in /run/shm/var/1/log/rpl_checksum_cache.log
 
 - saving '/run/shm/var/1/log/binlog_encryption.rpl_checksum_cache-stmt,xtradb/' to '/run/shm/var/log/binlog_encryption.rpl_checksum_cache-stmt,xtradb/'
 
Retrying test binlog_encryption.rpl_checksum_cache, attempt(2/3)...

Could not reproduce locally so far.
The test is disabled in the main tree



 Comments   
Comment by Elena Stepanova [ 2021-01-24 ]

alice,

Please feel free whatever you prefer with it (try to reproduce, or just re-enable the test, or remove the test completely), as long as it doesn't stay in disabled.def for another 5 years.

Comment by Alice Sherepa [ 2021-01-25 ]

I tried to enable it on 10.2, in my branch, it fails the same way. I will investigate it later.

Generated at Thu Feb 08 07:50:19 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.