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

[Michele Rossetti]

You can find the syslog about yesterday drbdmanaged startup at boot here:
Jan 13 18:31:40 mpve1 pve-manager[3347]: <root@pam> starting task 
UPID:mpve1:00000D39:00000EEB:58790EFC:startall::root@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@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@pam> end task 
UPID:mpve1:00000D39:00000EEB:58790EFC:startall::root@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@pam> successful auth for 
user 'root@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@lists.linbit.com
http://lists.linbit.com/mailman/listinfo/drbd-user


_______________________________________________
drbd-user mailing list
drbd-user@lists.linbit.com
http://lists.linbit.com/mailman/listinfo/drbd-user