Jan 13 18:31:40 mpve1 pve-manager[3347]: 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]: 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]: successful auth for user 'root@pam'