[DRBD-user] Testing new DRBD9 dedicated repo for PVE

Michele Rossetti rossetti at sardi.it
Sat Jan 14 16:43:54 CET 2017

Note: "permalinks" may not be as permanent as we would like,
direct links of old sources may well be a few messages off.


You can find the syslog about yesterday drbdmanaged startup at boot here:

Jan 13 18:31:40 mpve1 pve-manager[3347]: <root at pam> starting task 
UPID:mpve1:00000D39:00000EEB:58790EFC:startall::root at pam:
Jan 13 18:31:44 mpve1 drbdmanaged[3394]: INFO       DRBDmanage 
server, version 0.98.2 -- initializing on node 'mpve1'
Jan 13 18:31:44 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm -vvv adjust .drbdctrl
Jan 13 18:31:45 mpve1 kernel: [   42.730409] drbd: initialized. 
Version: 9.0.5-1 (api:2/proto:86-112)
Jan 13 18:31:45 mpve1 kernel: [   42.730414] drbd: GIT-hash: 
8d53d3e73f85f8d98f100a0127ccb574a97c5f64 build by root at nora, 
2017-01-09 10:27:04
Jan 13 18:31:45 mpve1 kernel: [   42.730415] drbd: registered as 
block device major 147
Jan 13 18:31:45 mpve1 kernel: [   42.734672] drbd .drbdctrl: Starting 
worker thread (from drbdsetup [3406])
Jan 13 18:31:45 mpve1 kernel: [   42.758954] drbd .drbdctrl mpve3: 
Starting sender thread (from drbdsetup [3417])
Jan 13 18:31:45 mpve1 kernel: [   42.760154] drbd .drbdctrl mpve2: 
Starting sender thread (from drbdsetup [3423])
Jan 13 18:31:45 mpve1 kernel: [   42.776155] drbd .drbdctrl/0 drbd0: 
disk( Diskless -> Attaching )
Jan 13 18:31:45 mpve1 kernel: [   42.776186] drbd .drbdctrl/0 drbd0: 
Maximum number of peer devices = 31
Jan 13 18:31:45 mpve1 kernel: [   42.776357] drbd .drbdctrl: Method 
to ensure write ordering: flush
Jan 13 18:31:45 mpve1 kernel: [   42.776360] drbd .drbdctrl/0 drbd0: 
my node_id: 0
Jan 13 18:31:45 mpve1 kernel: [   42.776363] drbd .drbdctrl/0 drbd0 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x11 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.776367] drbd .drbdctrl/0 drbd0 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.776371] drbd .drbdctrl/0 drbd0: 
my node_id: 0
Jan 13 18:31:45 mpve1 kernel: [   42.776374] drbd .drbdctrl/0 drbd0 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x11 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.776377] drbd .drbdctrl/0 drbd0 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.776380] drbd .drbdctrl/0 drbd0: 
drbd_bm_resize called with capacity == 8112
Jan 13 18:31:45 mpve1 kernel: [   42.776393] drbd .drbdctrl/0 drbd0: 
resync bitmap: bits=1014 words=496 pages=1
Jan 13 18:31:45 mpve1 kernel: [   42.776396] drbd .drbdctrl/0 drbd0: 
size = 4056 KB (4056 KB)
Jan 13 18:31:45 mpve1 kernel: [   42.776553] drbd .drbdctrl/0 drbd0: 
recounting of set bits took additional 0ms
Jan 13 18:31:45 mpve1 kernel: [   42.776578] drbd .drbdctrl/0 drbd0: 
disk( Attaching -> UpToDate )
Jan 13 18:31:45 mpve1 kernel: [   42.776585] drbd .drbdctrl/0 drbd0: 
attached to current UUID: 656B5BB65FF58B2C
Jan 13 18:31:45 mpve1 kernel: [   42.781653] drbd .drbdctrl/1 drbd1: 
disk( Diskless -> Attaching )
Jan 13 18:31:45 mpve1 kernel: [   42.781682] drbd .drbdctrl/1 drbd1: 
Maximum number of peer devices = 31
Jan 13 18:31:45 mpve1 kernel: [   42.781954] drbd .drbdctrl/1 drbd1: 
my node_id: 0
Jan 13 18:31:45 mpve1 kernel: [   42.781958] drbd .drbdctrl/1 drbd1 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x11 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.781961] drbd .drbdctrl/1 drbd1 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.781973] drbd .drbdctrl/1 drbd1: 
my node_id: 0
Jan 13 18:31:45 mpve1 kernel: [   42.781976] drbd .drbdctrl/1 drbd1 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x11 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.781979] drbd .drbdctrl/1 drbd1 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:31:45 mpve1 kernel: [   42.781982] drbd .drbdctrl/1 drbd1: 
drbd_bm_resize called with capacity == 8112
Jan 13 18:31:45 mpve1 kernel: [   42.781995] drbd .drbdctrl/1 drbd1: 
resync bitmap: bits=1014 words=496 pages=1
Jan 13 18:31:45 mpve1 kernel: [   42.781998] drbd .drbdctrl/1 drbd1: 
size = 4056 KB (4056 KB)
Jan 13 18:31:45 mpve1 kernel: [   42.782147] drbd .drbdctrl/1 drbd1: 
recounting of set bits took additional 0ms
Jan 13 18:31:45 mpve1 kernel: [   42.782171] drbd .drbdctrl/1 drbd1: 
disk( Attaching -> UpToDate )
Jan 13 18:31:45 mpve1 kernel: [   42.782178] drbd .drbdctrl/1 drbd1: 
attached to current UUID: 827CBF781CE402F0
Jan 13 18:31:45 mpve1 kernel: [   42.784645] drbd .drbdctrl mpve3: 
conn( StandAlone -> Unconnected )
Jan 13 18:31:45 mpve1 kernel: [   42.784837] drbd .drbdctrl mpve3: 
Starting receiver thread (from drbd_w_.drbdctr [3407])
Jan 13 18:31:45 mpve1 kernel: [   42.784969] drbd .drbdctrl mpve3: 
conn( Unconnected -> Connecting )
Jan 13 18:31:45 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: External 
command 'drbdadm': Exit code 0
Jan 13 18:31:45 mpve1 drbdmanaged[3394]: INFO       DRBDManage 
starting as potential leader node
Jan 13 18:31:45 mpve1 kernel: [   42.785752] drbd .drbdctrl mpve2: 
conn( StandAlone -> Unconnected )
Jan 13 18:31:45 mpve1 kernel: [   42.785786] drbd .drbdctrl mpve2: 
Starting receiver thread (from drbd_w_.drbdctr [3407])
Jan 13 18:31:45 mpve1 kernel: [   42.785906] drbd .drbdctrl mpve2: 
conn( Unconnected -> Connecting )
Jan 13 18:31:45 mpve1 drbdmanaged[3394]: INFO       Reading initial 
DRBD control volume status
Jan 13 18:31:45 mpve1 drbdmanaged[3394]: INFO       Finished reading 
initial DRBD control volume status
Jan 13 18:31:45 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:45 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:31:47 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:47 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:31:49 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:49 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:31:51 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:51 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:31:53 mpve1 dataeng: dsm_sa_datamgrd startup succeeded
Jan 13 18:31:53 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:53 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:31:54 mpve1 dataeng: dsm_sa_eventmgrd startup succeeded
Jan 13 18:31:55 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:55 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:31:57 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:57 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:31:57 mpve1 dataeng: dsm_sa_snmpd startup succeeded
Jan 13 18:31:59 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:31:59 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:32:01 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:32:01 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:32:03 mpve1 drbdmanaged[3394]: INFO       Leader election 
by quorum (3 Nodes)
Jan 13 18:32:03 mpve1 drbdmanaged[3394]: INFO       Leader election: 
no quorum (1/3) nodes
Jan 13 18:32:37 mpve1 pve-manager[3347]: <root at pam> end task 
UPID:mpve1:00000D39:00000EEB:58790EFC:startall::root at pam: OK
Jan 13 18:32:48 mpve1 kernel: [  106.523602] drbd .drbdctrl mpve3: 
Handshake to peer 2 successful: Agreed network protocol version 112
Jan 13 18:32:48 mpve1 kernel: [  106.523608] drbd .drbdctrl mpve3: 
Feature flags enabled on protocol level: 0x7 TRIM THIN_RESYNC 
WRITE_SAME.
Jan 13 18:32:48 mpve1 kernel: [  106.523826] drbd .drbdctrl mpve3: 
Peer authenticated using 32 bytes HMAC
Jan 13 18:32:48 mpve1 kernel: [  106.523840] drbd .drbdctrl mpve3: 
Starting ack_recv thread (from drbd_r_.drbdctr [3438])
Jan 13 18:32:48 mpve1 kernel: [  106.705801] drbd .drbdctrl: 
Preparing cluster-wide state change 1397766355 (0->2 499/146)
Jan 13 18:32:48 mpve1 kernel: [  106.706010] drbd .drbdctrl: State 
change 1397766355: primary_nodes=0, weak_nodes=0
Jan 13 18:32:48 mpve1 kernel: [  106.706015] drbd .drbdctrl: 
Committing cluster-wide state change 1397766355 (0ms)
Jan 13 18:32:48 mpve1 kernel: [  106.706039] drbd .drbdctrl mpve3: 
conn( Connecting -> Connected ) peer( Unknown -> Secondary )
Jan 13 18:32:48 mpve1 kernel: [  106.717735] drbd .drbdctrl/0 drbd0: 
current_size: 8112
Jan 13 18:32:48 mpve1 kernel: [  106.717739] drbd .drbdctrl/0 drbd0 
mpve3: c_size: 8112 u_size: 0 d_size: 8112 max_size: 8112
Jan 13 18:32:48 mpve1 kernel: [  106.717743] drbd .drbdctrl/0 drbd0 
mpve3: la_size: 8112 my_usize: 0 my_max_size: 8112
Jan 13 18:32:48 mpve1 kernel: [  106.717746] drbd .drbdctrl/0 drbd0: 
my node_id: 0
Jan 13 18:32:48 mpve1 kernel: [  106.717750] drbd .drbdctrl/0 drbd0 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.717753] drbd .drbdctrl/0 drbd0 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 8112 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.717758] drbd .drbdctrl/0 drbd0 
mpve3: calling drbd_determine_dev_size()
Jan 13 18:32:48 mpve1 kernel: [  106.717761] drbd .drbdctrl/0 drbd0: 
my node_id: 0
Jan 13 18:32:48 mpve1 kernel: [  106.717764] drbd .drbdctrl/0 drbd0 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.717767] drbd .drbdctrl/0 drbd0 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 8112 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.717779] drbd .drbdctrl/0 drbd0 
mpve3: drbd_sync_handshake:
Jan 13 18:32:48 mpve1 kernel: [  106.717783] drbd .drbdctrl/0 drbd0 
mpve3: self 
656B5BB65FF58B2C:0000000000000000:A1DE1296158EC566:07E35D16B5A71760 
bits:0 flags:20
Jan 13 18:32:48 mpve1 kernel: [  106.717787] drbd .drbdctrl/0 drbd0 
mpve3: peer 
656B5BB65FF58B2C:0000000000000000:A1DE1296158EC566:07E35D16B5A71760 
bits:0 flags:20
Jan 13 18:32:48 mpve1 kernel: [  106.717789] drbd .drbdctrl/0 drbd0 
mpve3: uuid_compare()=0 by rule 40
Jan 13 18:32:48 mpve1 kernel: [  106.717808] drbd .drbdctrl/0 drbd0 
mpve3: pdsk( DUnknown -> UpToDate ) repl( Off -> Established )
Jan 13 18:32:48 mpve1 kernel: [  106.729717] drbd .drbdctrl/1 drbd1: 
current_size: 8112
Jan 13 18:32:48 mpve1 kernel: [  106.729722] drbd .drbdctrl/1 drbd1 
mpve3: c_size: 8112 u_size: 0 d_size: 8112 max_size: 8112
Jan 13 18:32:48 mpve1 kernel: [  106.729725] drbd .drbdctrl/1 drbd1 
mpve3: la_size: 8112 my_usize: 0 my_max_size: 8112
Jan 13 18:32:48 mpve1 kernel: [  106.729727] drbd .drbdctrl/1 drbd1: 
my node_id: 0
Jan 13 18:32:48 mpve1 kernel: [  106.729731] drbd .drbdctrl/1 drbd1 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.729734] drbd .drbdctrl/1 drbd1 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 8112 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.729737] drbd .drbdctrl/1 drbd1 
mpve3: calling drbd_determine_dev_size()
Jan 13 18:32:48 mpve1 kernel: [  106.729740] drbd .drbdctrl/1 drbd1: 
my node_id: 0
Jan 13 18:32:48 mpve1 kernel: [  106.729743] drbd .drbdctrl/1 drbd1 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x10 max_size: 0 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.729746] drbd .drbdctrl/1 drbd1 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x10 max_size: 8112 
(DUnknown)
Jan 13 18:32:48 mpve1 kernel: [  106.729763] drbd .drbdctrl/1 drbd1 
mpve3: drbd_sync_handshake:
Jan 13 18:32:48 mpve1 kernel: [  106.729767] drbd .drbdctrl/1 drbd1 
mpve3: self 
827CBF781CE402F0:0000000000000000:827CBF781CE402F0:D562E40FC3122D56 
bits:1006 flags:20
Jan 13 18:32:48 mpve1 kernel: [  106.729771] drbd .drbdctrl/1 drbd1 
mpve3: peer 
827CBF781CE402F0:0000000000000000:D562E40FC3122D56:40F53CE0681916B2 
bits:0 flags:20
Jan 13 18:32:48 mpve1 kernel: [  106.729773] drbd .drbdctrl/1 drbd1 
mpve3: uuid_compare()=0 by rule 40
Jan 13 18:32:48 mpve1 kernel: [  106.729776] drbd .drbdctrl/1 drbd1: 
No resync, but 1006 bits in bitmap!
Jan 13 18:32:48 mpve1 kernel: [  106.729793] drbd .drbdctrl/1 drbd1 
mpve3: pdsk( DUnknown -> UpToDate ) repl( Off -> Established )
Jan 13 18:32:50 mpve1 kernel: [  108.143033] drbd .drbdctrl mpve3: 
Preparing remote state change 1792403589 (primary_nodes=0, 
weak_nodes=0)
Jan 13 18:32:50 mpve1 kernel: [  108.143754] drbd .drbdctrl mpve3: 
Committing remote state change 1792403589
Jan 13 18:32:50 mpve1 kernel: [  108.143782] drbd .drbdctrl mpve3: 
peer( Secondary -> Primary )
Jan 13 18:32:50 mpve1 kernel: [  108.689422] drbd .drbdctrl/0 drbd0 
mpve3: received new current UUID: 8B3823991C2396FD
Jan 13 18:32:53 mpve1 kernel: [  111.765715] drbd .drbdctrl mpve3: 
Preparing remote state change 2721654213 (primary_nodes=0, 
weak_nodes=0)
Jan 13 18:32:53 mpve1 kernel: [  111.766184] drbd .drbdctrl mpve3: 
Committing remote state change 2721654213
Jan 13 18:32:55 mpve1 kernel: [  113.617602] drbd .drbdctrl mpve2: 
sock_recvmsg returned -11
Jan 13 18:32:55 mpve1 kernel: [  113.617626] drbd .drbdctrl mpve2: 
conn( Connecting -> BrokenPipe )
Jan 13 18:32:55 mpve1 kernel: [  113.617655] drbd .drbdctrl mpve2: 
short read (expected size 8)
Jan 13 18:32:55 mpve1 kernel: [  113.658608] drbd .drbdctrl mpve2: 
Connection closed
Jan 13 18:32:55 mpve1 kernel: [  113.658635] drbd .drbdctrl mpve2: 
conn( BrokenPipe -> Unconnected )
Jan 13 18:32:56 mpve1 drbdmanaged[3394]: INFO       starting resource 
'vm-101-disk-1'
Jan 13 18:32:56 mpve1 kernel: [  114.661594] drbd .drbdctrl mpve2: 
conn( Unconnected -> Connecting )
Jan 13 18:32:56 mpve1 org.drbd.drbdmanaged[2753]: 11 logical 
volume(s) in volume group "drbdpool" now active
Jan 13 18:32:57 mpve1 kernel: [  115.273565] drbd .drbdctrl/1 drbd1 
mpve3: received new current UUID: 77A9A0B42BBABE67
Jan 13 18:32:57 mpve1 kernel: [  115.646904] drbd .drbdctrl mpve2: 
Handshake to peer 1 successful: Agreed network protocol version 112
Jan 13 18:32:57 mpve1 kernel: [  115.646909] drbd .drbdctrl mpve2: 
Feature flags enabled on protocol level: 0x7 TRIM THIN_RESYNC 
WRITE_SAME.
Jan 13 18:32:57 mpve1 kernel: [  115.647063] drbd .drbdctrl mpve2: 
Peer authenticated using 32 bytes HMAC
Jan 13 18:32:57 mpve1 kernel: [  115.647075] drbd .drbdctrl mpve2: 
Starting ack_recv thread (from drbd_r_.drbdctr [3440])
Jan 13 18:32:57 mpve1 kernel: [  115.647477] drbd .drbdctrl mpve2: 
conn( Connecting -> NetworkFailure )
Jan 13 18:32:57 mpve1 kernel: [  115.647512] drbd .drbdctrl mpve2: 
ack_receiver terminated
Jan 13 18:32:57 mpve1 kernel: [  115.647515] drbd .drbdctrl mpve2: 
Terminating ack_recv thread
Jan 13 18:32:57 mpve1 kernel: [  115.678168] drbd .drbdctrl mpve2: 
Connection closed
Jan 13 18:32:57 mpve1 kernel: [  115.678194] drbd .drbdctrl mpve2: 
conn( NetworkFailure -> Unconnected )
Jan 13 18:32:57 mpve1 kernel: [  115.678221] drbd .drbdctrl mpve2: 
Restarting receiver thread
Jan 13 18:32:57 mpve1 kernel: [  115.678243] drbd .drbdctrl mpve2: 
conn( Unconnected -> Connecting )
Jan 13 18:32:57 mpve1 org.drbd.drbdmanaged[2753]: Logical volume 
"vm-101-disk-1_00" changed.
Jan 13 18:32:58 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm --config-to-test 
/var/lib/drbd.d/drbdmanage_vm-101-disk-1.res.tmp --config-to-exclude 
/var/lib/drbd.d/drbdmanage_vm-101-disk-1.res sh-nop
Jan 13 18:32:58 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: External 
command 'drbdadm': Exit code 0
Jan 13 18:32:58 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm -c 
/var/lib/drbd.d/drbdmanage_vm-101-disk-1.res.tmp -d up vm-101-disk-1
Jan 13 18:32:58 mpve1 drbdmanaged[3394]: INFO       Resource file 
/var/lib/drbd.d/drbdmanage_vm-101-disk-1.res.tmp not valid
Jan 13 18:32:58 mpve1 kernel: [  116.185546] drbd .drbdctrl mpve2: 
Handshake to peer 1 successful: Agreed network protocol version 112
Jan 13 18:32:58 mpve1 kernel: [  116.185551] drbd .drbdctrl mpve2: 
Feature flags enabled on protocol level: 0x7 TRIM THIN_RESYNC 
WRITE_SAME.
Jan 13 18:32:58 mpve1 kernel: [  116.185892] drbd .drbdctrl mpve2: 
Peer authenticated using 32 bytes HMAC
Jan 13 18:32:58 mpve1 kernel: [  116.185905] drbd .drbdctrl mpve2: 
Starting ack_recv thread (from drbd_r_.drbdctr [3440])
Jan 13 18:32:58 mpve1 drbdmanaged[3394]: INFO       starting resource 
'vm-102-disk-1'
Jan 13 18:32:58 mpve1 kernel: [  116.341566] drbd .drbdctrl: 
Preparing cluster-wide state change 110492127 (0->1 499/146)
Jan 13 18:32:58 mpve1 kernel: [  116.341804] drbd .drbdctrl: State 
change 110492127: primary_nodes=4, weak_nodes=FFFFFFFFFFFFFFF8
Jan 13 18:32:58 mpve1 kernel: [  116.341808] drbd .drbdctrl: 
Committing cluster-wide state change 110492127 (0ms)
Jan 13 18:32:58 mpve1 kernel: [  116.341836] drbd .drbdctrl mpve2: 
conn( Connecting -> Connected ) peer( Unknown -> Secondary )
Jan 13 18:32:58 mpve1 kernel: [  116.353491] drbd .drbdctrl/0 drbd0: 
current_size: 8112
Jan 13 18:32:58 mpve1 kernel: [  116.353495] drbd .drbdctrl/0 drbd0 
mpve2: c_size: 8112 u_size: 0 d_size: 8112 max_size: 8112
Jan 13 18:32:58 mpve1 kernel: [  116.353499] drbd .drbdctrl/0 drbd0 
mpve2: la_size: 8112 my_usize: 0 my_max_size: 8112
Jan 13 18:32:58 mpve1 kernel: [  116.353502] drbd .drbdctrl/0 drbd0: 
my node_id: 0
Jan 13 18:32:58 mpve1 kernel: [  116.353505] drbd .drbdctrl/0 drbd0 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x10 max_size: 8112 
(DUnknown)
Jan 13 18:32:58 mpve1 kernel: [  116.353509] drbd .drbdctrl/0 drbd0 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x11 max_size: 8112 
(UpToDate)
Jan 13 18:32:58 mpve1 kernel: [  116.353514] drbd .drbdctrl/0 drbd0 
mpve2: calling drbd_determine_dev_size()
Jan 13 18:32:58 mpve1 kernel: [  116.353516] drbd .drbdctrl/0 drbd0: 
my node_id: 0
Jan 13 18:32:58 mpve1 kernel: [  116.353519] drbd .drbdctrl/0 drbd0 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x0 flags: 0x10 max_size: 8112 
(DUnknown)
Jan 13 18:32:58 mpve1 kernel: [  116.353522] drbd .drbdctrl/0 drbd0 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x11 max_size: 8112 
(UpToDate)
Jan 13 18:32:58 mpve1 kernel: [  116.353534] drbd .drbdctrl/0 drbd0 
mpve2: drbd_sync_handshake:
Jan 13 18:32:58 mpve1 kernel: [  116.353538] drbd .drbdctrl/0 drbd0 
mpve2: self 
8B3823991C2396FC:0000000000000000:656B5BB65FF58B2C:A1DE1296158EC566 
bits:0 flags:0
Jan 13 18:32:58 mpve1 kernel: [  116.353542] drbd .drbdctrl/0 drbd0 
mpve2: peer 
8B3823991C2396FC:0000000000000000:656B5BB65FF58B2C:A1DE1296158EC566 
bits:0 flags:0
Jan 13 18:32:58 mpve1 kernel: [  116.353544] drbd .drbdctrl/0 drbd0 
mpve2: uuid_compare()=0 by rule 40
Jan 13 18:32:58 mpve1 kernel: [  116.353562] drbd .drbdctrl/0 drbd0 
mpve2: pdsk( DUnknown -> UpToDate ) repl( Off -> Established )
Jan 13 18:32:58 mpve1 kernel: [  116.365484] drbd .drbdctrl/1 drbd1: 
current_size: 8112
Jan 13 18:32:58 mpve1 kernel: [  116.365489] drbd .drbdctrl/1 drbd1 
mpve2: c_size: 8112 u_size: 0 d_size: 8112 max_size: 8112
Jan 13 18:32:58 mpve1 kernel: [  116.365492] drbd .drbdctrl/1 drbd1 
mpve2: la_size: 8112 my_usize: 0 my_max_size: 8112
Jan 13 18:32:58 mpve1 kernel: [  116.365494] drbd .drbdctrl/1 drbd1: 
my node_id: 0
Jan 13 18:32:58 mpve1 kernel: [  116.365498] drbd .drbdctrl/1 drbd1 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x827cbf781ce402f0 flags: 0x10 
max_size: 8112 (DUnknown)
Jan 13 18:32:58 mpve1 kernel: [  116.365501] drbd .drbdctrl/1 drbd1 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x11 max_size: 8112 
(UpToDate)
Jan 13 18:32:58 mpve1 kernel: [  116.365505] drbd .drbdctrl/1 drbd1 
mpve2: calling drbd_determine_dev_size()
Jan 13 18:32:58 mpve1 kernel: [  116.365507] drbd .drbdctrl/1 drbd1: 
my node_id: 0
Jan 13 18:32:58 mpve1 kernel: [  116.365511] drbd .drbdctrl/1 drbd1 
mpve2: node_id: 1 idx: 0 bm-uuid: 0x827cbf781ce402f0 flags: 0x10 
max_size: 8112 (DUnknown)
Jan 13 18:32:58 mpve1 kernel: [  116.365514] drbd .drbdctrl/1 drbd1 
mpve3: node_id: 2 idx: 1 bm-uuid: 0x0 flags: 0x11 max_size: 8112 
(UpToDate)
Jan 13 18:32:58 mpve1 kernel: [  116.365524] drbd .drbdctrl/1 drbd1 
mpve2: drbd_sync_handshake:
Jan 13 18:32:58 mpve1 kernel: [  116.365528] drbd .drbdctrl/1 drbd1 
mpve2: self 
77A9A0B42BBABE66:827CBF781CE402F0:827CBF781CE402F0:D562E40FC3122D56 
bits:0 flags:0
Jan 13 18:32:58 mpve1 kernel: [  116.365531] drbd .drbdctrl/1 drbd1 
mpve2: peer 
827CBF781CE402F0:0000000000000000:D562E40FC3122D56:F5D3E91BB5E363B4 
bits:1006 flags:6
Jan 13 18:32:58 mpve1 kernel: [  116.365534] drbd .drbdctrl/1 drbd1 
mpve2: uuid_compare()=2 by rule 70
Jan 13 18:32:58 mpve1 kernel: [  116.365552] drbd .drbdctrl/1 drbd1 
mpve2: pdsk( DUnknown -> Inconsistent ) repl( Off -> WFBitMapS ) 
resync-susp( no -> peer )
Jan 13 18:32:58 mpve1 kernel: [  116.365737] drbd .drbdctrl/1 drbd1 
mpve2: send bitmap stats [Bytes(packets)]: plain 0(0), RLE 20(1), 
total 20; compression: 87.5%
Jan 13 18:32:58 mpve1 kernel: [  116.366087] drbd .drbdctrl/1 drbd1 
mpve2: receive bitmap stats [Bytes(packets)]: plain 0(0), RLE 21(1), 
total 21; compression: 86.9%
Jan 13 18:32:58 mpve1 kernel: [  116.366105] drbd .drbdctrl/1 drbd1 
mpve2: helper command: /sbin/drbdadm before-resync-source
Jan 13 18:32:58 mpve1 kernel: [  116.367629] drbd .drbdctrl/1 drbd1 
mpve2: helper command: /sbin/drbdadm before-resync-source exit code 0 
(0x0)
Jan 13 18:32:58 mpve1 kernel: [  116.367662] drbd .drbdctrl/1 drbd1 
mpve2: repl( WFBitMapS -> PausedSyncS )
Jan 13 18:32:58 mpve1 kernel: [  116.367792] drbd .drbdctrl/1 drbd1 
mpve2: Began resync as PausedSyncS (will sync 4024 KB [1006 bits 
set]).
Jan 13 18:32:58 mpve1 kernel: [  116.666466] drbd .drbdctrl/1 drbd1 
mpve2: updated UUIDs 
77A9A0B42BBABE66:0000000000000000:827CBF781CE402F0:D562E40FC3122D56
Jan 13 18:32:58 mpve1 kernel: [  116.666678] drbd .drbdctrl/1 drbd1 
mpve2: Resync done (total 1 sec; paused 0 sec; 4024 K/sec)
Jan 13 18:32:58 mpve1 kernel: [  116.666697] drbd .drbdctrl/1 drbd1 
mpve2: pdsk( Inconsistent -> UpToDate ) repl( PausedSyncS -> 
Established )
Jan 13 18:32:58 mpve1 kernel: [  116.666956] drbd .drbdctrl/1 drbd1 
mpve2: resync-susp( peer -> no )
Jan 13 18:32:59 mpve1 org.drbd.drbdmanaged[2753]: 11 logical 
volume(s) in volume group "drbdpool" now active
Jan 13 18:32:59 mpve1 org.drbd.drbdmanaged[2753]: Logical volume 
"vm-102-disk-1_00" changed.
Jan 13 18:32:59 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm --config-to-test 
/var/lib/drbd.d/drbdmanage_vm-102-disk-1.res.tmp --config-to-exclude 
/var/lib/drbd.d/drbdmanage_vm-102-disk-1.res sh-nop
Jan 13 18:32:59 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: External 
command 'drbdadm': Exit code 0
Jan 13 18:32:59 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm -c 
/var/lib/drbd.d/drbdmanage_vm-102-disk-1.res.tmp -d up vm-102-disk-1
Jan 13 18:32:59 mpve1 drbdmanaged[3394]: INFO       Resource file 
/var/lib/drbd.d/drbdmanage_vm-102-disk-1.res.tmp not valid
Jan 13 18:32:59 mpve1 drbdmanaged[3394]: INFO       starting resource 
'vm-105-disk-1'
Jan 13 18:33:00 mpve1 org.drbd.drbdmanaged[2753]: 11 logical 
volume(s) in volume group "drbdpool" now active
Jan 13 18:33:00 mpve1 org.drbd.drbdmanaged[2753]: Logical volume 
"vm-105-disk-1_00" changed.
Jan 13 18:33:00 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm --config-to-test 
/var/lib/drbd.d/drbdmanage_vm-105-disk-1.res.tmp --config-to-exclude 
/var/lib/drbd.d/drbdmanage_vm-105-disk-1.res sh-nop
Jan 13 18:33:00 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: External 
command 'drbdadm': Exit code 0
Jan 13 18:33:00 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm -c 
/var/lib/drbd.d/drbdmanage_vm-105-disk-1.res.tmp -d up vm-105-disk-1
Jan 13 18:33:00 mpve1 drbdmanaged[3394]: INFO       Resource file 
/var/lib/drbd.d/drbdmanage_vm-105-disk-1.res.tmp not valid
Jan 13 18:33:00 mpve1 drbdmanaged[3394]: INFO       starting resource 
'vm-100-disk-1'
Jan 13 18:33:01 mpve1 org.drbd.drbdmanaged[2753]: 11 logical 
volume(s) in volume group "drbdpool" now active
Jan 13 18:33:01 mpve1 org.drbd.drbdmanaged[2753]: Logical volume 
"vm-100-disk-1_00" changed.
Jan 13 18:33:01 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm --config-to-test 
/var/lib/drbd.d/drbdmanage_vm-100-disk-1.res.tmp --config-to-exclude 
/var/lib/drbd.d/drbdmanage_vm-100-disk-1.res sh-nop
Jan 13 18:33:01 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: External 
command 'drbdadm': Exit code 0
Jan 13 18:33:01 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm -c 
/var/lib/drbd.d/drbdmanage_vm-100-disk-1.res.tmp -d up vm-100-disk-1
Jan 13 18:33:01 mpve1 drbdmanaged[3394]: INFO       Resource file 
/var/lib/drbd.d/drbdmanage_vm-100-disk-1.res.tmp not valid
Jan 13 18:33:01 mpve1 drbdmanaged[3394]: INFO       starting resource 
'vm-103-disk-1'
Jan 13 18:33:02 mpve1 org.drbd.drbdmanaged[2753]: 11 logical 
volume(s) in volume group "drbdpool" now active
Jan 13 18:33:03 mpve1 org.drbd.drbdmanaged[2753]: Logical volume 
"vm-103-disk-1_00" changed.
Jan 13 18:33:03 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm --config-to-test 
/var/lib/drbd.d/drbdmanage_vm-103-disk-1.res.tmp --config-to-exclude 
/var/lib/drbd.d/drbdmanage_vm-103-disk-1.res sh-nop
Jan 13 18:33:03 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: External 
command 'drbdadm': Exit code 0
Jan 13 18:33:03 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm -c 
/var/lib/drbd.d/drbdmanage_vm-103-disk-1.res.tmp -d up vm-103-disk-1
Jan 13 18:33:03 mpve1 drbdmanaged[3394]: INFO       Resource file 
/var/lib/drbd.d/drbdmanage_vm-103-disk-1.res.tmp not valid
Jan 13 18:33:03 mpve1 drbdmanaged[3394]: INFO       starting resource 
'vm-104-disk-1'
Jan 13 18:33:03 mpve1 org.drbd.drbdmanaged[2753]: 11 logical 
volume(s) in volume group "drbdpool" now active
Jan 13 18:33:04 mpve1 org.drbd.drbdmanaged[2753]: Logical volume 
"vm-104-disk-1_00" changed.
Jan 13 18:33:04 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm --config-to-test 
/var/lib/drbd.d/drbdmanage_vm-104-disk-1.res.tmp --config-to-exclude 
/var/lib/drbd.d/drbdmanage_vm-104-disk-1.res sh-nop
Jan 13 18:33:04 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: External 
command 'drbdadm': Exit code 0
Jan 13 18:33:04 mpve1 drbdmanaged[3394]: INFO       DrbdAdm: Running 
external command: drbdadm -c 
/var/lib/drbd.d/drbdmanage_vm-104-disk-1.res.tmp -d up vm-104-disk-1
Jan 13 18:33:04 mpve1 drbdmanaged[3394]: INFO       Resource file 
/var/lib/drbd.d/drbdmanage_vm-104-disk-1.res.tmp not valid
Jan 13 18:57:33 mpve1 pvedaemon[3320]: <root at pam> successful auth for 
user 'root at pam'

I'm not able to understand the problem.
Thank in advance for any further help.

Michele

>Il 13/01/2017 21:01, Michele Rossetti ha scritto:
>>  Stupid me, now all working, maybe the resources was gone down upgrading?
>
>No, simply they don't come up automatically upon reboot. An excerpt from
>your syslog abount drbdmanaged startup at boot time (in debug mode,
>possibly) could help tracking the problem.
>
>Bye the way, the same happens to me:
>http://lists.linbit.com/pipermail/drbd-user/2017-January/023510.html
>
>>  Thanks a lot,
>
>You're welcome.
>
>>  Michele
>
>bye,
>rob
>_______________________________________________
>drbd-user mailing list
>drbd-user at lists.linbit.com
>http://lists.linbit.com/mailman/listinfo/drbd-user





More information about the drbd-user mailing list