Attachment 448005 Details for Bug 714510

Log of primary and secondary server.

drbd crash.txt (text/plain), 11.24 KB, created by Valerio Granato on 2011-08-26 21:14:41 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Valerio Granato

Created: 2011-08-26 21:14:41 UTC

Size: 11.24 KB

patch

obsolete

>Aug 26 22:04:54 primaryserver kernel: [121494.328079] block drbd0: BAD! BarrierAck #1092185342 received, expected #1092185341!
>Aug 26 22:04:54 primaryserver kernel: [121494.328087] block drbd0: peer( Secondary -> Unknown ) conn( Connected -> ProtocolError ) pdsk( UpToDate -> DUnknown )
>Aug 26 22:04:54 primaryserver kernel: [121494.328097] block drbd0: process_done_ee() = NOT_OK
>Aug 26 22:04:54 primaryserver kernel: [121494.328099] block drbd0: asender terminated
>Aug 26 22:04:54 primaryserver kernel: [121494.328101] block drbd0: Terminating drbd0_asender
>Aug 26 22:04:54 primaryserver kernel: [121494.328129] block drbd0: Creating new current UUID
>Aug 26 22:04:54 primaryserver kernel: [121494.328169] block drbd0: short read expecting header on sock: r=-512
>Aug 26 22:04:54 primaryserver kernel: [121494.328401] block drbd0: Connection closed
>Aug 26 22:04:54 primaryserver kernel: [121494.328407] block drbd0: conn( ProtocolError -> Unconnected )
>Aug 26 22:04:54 primaryserver kernel: [121494.328417] block drbd0: receiver terminated
>Aug 26 22:04:54 primaryserver kernel: [121494.328419] block drbd0: Restarting drbd0_receiver
>Aug 26 22:04:54 primaryserver kernel: [121494.328422] block drbd0: receiver (re)started
>Aug 26 22:04:54 primaryserver kernel: [121494.328426] block drbd0: conn( Unconnected -> WFConnection )
>Aug 26 22:04:56 primaryserver kernel: [121496.001037] block drbd0: Handshake successful: Agreed network protocol version 92
>Aug 26 22:04:56 primaryserver kernel: [121496.001347] block drbd0: Peer authenticated using 20 bytes of 'sha1' HMAC
>Aug 26 22:04:56 primaryserver kernel: [121496.001355] block drbd0: conn( WFConnection -> WFReportParams )
>Aug 26 22:04:56 primaryserver kernel: [121496.001394] block drbd0: Starting asender thread (from drbd0_receiver [13987])
>Aug 26 22:04:56 primaryserver kernel: [121496.001682] block drbd0: data-integrity-alg: <not-used>
>Aug 26 22:04:56 primaryserver kernel: [121496.001755] block drbd0: drbd_sync_handshake:
>Aug 26 22:04:56 primaryserver kernel: [121496.001760] block drbd0: self EA44572AEC17BB4B:1B39F50C6385047B:031139826B6A0BA7:F48B1E2E9879B0BF bits:825 flags:0
>Aug 26 22:04:56 primaryserver kernel: [121496.001764] block drbd0: peer 1B39F50C6385047A:0000000000000000:031139826B6A0BA6:F48B1E2E9879B0BF bits:0 flags:0
>Aug 26 22:04:56 primaryserver kernel: [121496.001768] block drbd0: uuid_compare()=1 by rule 70
>Aug 26 22:04:56 primaryserver kernel: [121496.001773] block drbd0: peer( Unknown -> Secondary ) conn( WFReportParams -> WFBitMapS ) pdsk( DUnknown -> UpToDate )
>Aug 26 22:04:56 primaryserver kernel: [121496.059392] block drbd0: conn( WFBitMapS -> SyncSource ) pdsk( UpToDate -> Inconsistent )
>Aug 26 22:04:56 primaryserver kernel: [121496.059444] block drbd0: Began resync as SyncSource (will sync 3300 KB [825 bits set]).
>Aug 26 22:04:56 primaryserver kernel: [121496.883244] block drbd0: Resync done (total 1 sec; paused 0 sec; 3300 K/sec)
>Aug 26 22:04:56 primaryserver kernel: [121496.883252] block drbd0: conn( SyncSource -> Connected ) pdsk( Inconsistent -> UpToDate )
>
>
>
>Aug 26 22:04:43 secondaryserver-ng kernel: [31591.972845] block drbd0: sock was shut down by peer
>Aug 26 22:04:43 secondaryserver-ng kernel: [31591.972853] block drbd0: peer( Primary -> Unknown ) conn( Connected -> BrokenPipe ) pdsk( UpToDate -> DUnknown )
>Aug 26 22:04:43 secondaryserver-ng kernel: [31591.972862] block drbd0: short read expecting header on sock: r=0
>Aug 26 22:04:43 secondaryserver-ng kernel: [31591.972937] block drbd0: sock_recvmsg returned -104
>Aug 26 22:04:43 secondaryserver-ng kernel: [31591.972941] block drbd0: asender terminated
>Aug 26 22:04:43 secondaryserver-ng kernel: [31591.972943] block drbd0: Terminating drbd0_asender
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.548711] block drbd0: Connection closed
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.548718] block drbd0: conn( BrokenPipe -> Unconnected )
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.548727] block drbd0: receiver terminated
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.548729] block drbd0: Restarting drbd0_receiver
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.548732] block drbd0: receiver (re)started
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.548736] block drbd0: conn( Unconnected -> WFConnection )
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.645755] block drbd0: Handshake successful: Agreed network protocol version 92
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646048] block drbd0: Peer authenticated using 20 bytes of 'sha1' HMAC
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646055] block drbd0: conn( WFConnection -> WFReportParams )
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646199] block drbd0: Starting asender thread (from drbd0_receiver [6749])
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646249] block drbd0: max_segment_size ( = BIO size ) = 32768
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646282] block drbd0: data-integrity-alg: <not-used>
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646293] block drbd0: drbd_sync_handshake:
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646297] block drbd0: self 1B39F50C6385047A:0000000000000000:031139826B6A0BA6:F48B1E2E9879B0BF bits:0 flags:0
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646301] block drbd0: peer EA44572AEC17BB4B:1B39F50C6385047B:031139826B6A0BA7:F48B1E2E9879B0BF bits:825 flags:0
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646304] block drbd0: uuid_compare()=-1 by rule 50
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.646312] block drbd0: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) pdsk( DUnknown -> UpToDate )
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.701031] block drbd0: conn( WFBitMapT -> WFSyncUUID )
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.704132] block drbd5: peer_isp( 0 -> 1 )
>Aug 26 22:04:44 secondaryserver-ng kernel: [31593.704383] block drbd0: helper command: /sbin/drbdadm before-resync-target minor-0
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: invoked for winserver_c
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: DRBD module version: 8.3.9
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]:    userland version: 8.3.8
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: you should upgrade your drbd tools!
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 3 (/) leaked on lvdisplay invocation. Parent PID 32594: /bin/bash
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 4 (/etc) leaked on lvdisplay invocation. Parent PID 32594: /bin/bash
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 6 (/etc) leaked on lvdisplay invocation. Parent PID 32594: /bin/bash
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 7 (/etc) leaked on lvdisplay invocation. Parent PID 32594: /bin/bash
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: DRBD module version: 8.3.9
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]:    userland version: 8.3.8
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: you should upgrade your drbd tools!
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 3 (/) leaked on lvcreate invocation. Parent PID 32604: /bin/bash
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 4 (/etc) leaked on lvcreate invocation. Parent PID 32604: /bin/bash
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 6 (/etc) leaked on lvcreate invocation. Parent PID 32604: /bin/bash
>Aug 26 22:04:44 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]: File descriptor 7 (/etc) leaked on lvcreate invocation. Parent PID 32604: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng lvm[5768]: Monitoring snapshot secondaryserver-winserver_c--before--resync
>Aug 26 22:04:45 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]:   Rounding up size to full physical extent 29.60 GiB
>Aug 26 22:04:45 secondaryserver-ng snapshot-resync-target-lvm.sh[32594]:   Logical volume "winserver_c-before-resync" created
>Aug 26 22:04:45 secondaryserver-ng kernel: [31594.258388] block drbd0: helper command: /sbin/drbdadm before-resync-target minor-0 exit code 0 (0x0)
>Aug 26 22:04:45 secondaryserver-ng kernel: [31594.258396] block drbd0: conn( WFSyncUUID -> SyncTarget ) disk( UpToDate -> Inconsistent )
>Aug 26 22:04:45 secondaryserver-ng kernel: [31594.258402] block drbd0: Began resync as SyncTarget (will sync 3300 KB [825 bits set]).
>Aug 26 22:04:45 secondaryserver-ng kernel: [31594.528116] block drbd5: peer_isp( 1 -> 0 )
>Aug 26 22:04:45 secondaryserver-ng kernel: [31594.576946] block drbd0: Resync done (total 1 sec; paused 0 sec; 3300 K/sec)
>Aug 26 22:04:45 secondaryserver-ng kernel: [31594.576955] block drbd0: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate )
>Aug 26 22:04:45 secondaryserver-ng kernel: [31594.600126] block drbd0: helper command: /sbin/drbdadm after-resync-target minor-0
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: invoked for winserver_c
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: DRBD module version: 8.3.9
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]:    userland version: 8.3.8
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: you should upgrade your drbd tools!
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 3 (/) leaked on lvdisplay invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 4 (/etc) leaked on lvdisplay invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 6 (/etc) leaked on lvdisplay invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 7 (/etc) leaked on lvdisplay invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 3 (/) leaked on lvremove invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 4 (/etc) leaked on lvremove invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 6 (/etc) leaked on lvremove invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]: File descriptor 7 (/etc) leaked on lvremove invocation. Parent PID 32673: /bin/bash
>Aug 26 22:04:45 secondaryserver-ng lvm[5768]: No longer monitoring snapshot secondaryserver-winserver_c--before--resync
>Aug 26 22:04:50 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]:   give up on open_count
>Aug 26 22:04:50 secondaryserver-ng unsnapshot-resync-target-lvm.sh[32673]:   Logical volume "winserver_c-before-resync" successfully removed
>Aug 26 22:04:50 secondaryserver-ng kernel: [31599.798976] block drbd0: helper command: /sbin/drbdadm after-resync-target minor-0 exit code 0 (0x0)

Actions: View

Attachments on bug 714510: 448005