Mar 23 13:05:21 jerry kernel: drbd2: disk( Diskless -> Attaching ) Mar 23 13:05:21 jerry kernel: drbd2: Found 6 transactions (162 active extents) in activity log. Mar 23 13:05:21 jerry kernel: drbd2: max_segment_size ( = BIO size ) = 32768 Mar 23 13:05:21 jerry kernel: drbd2: drbd_bm_resize called with capacity == 26672264 Mar 23 13:05:21 jerry kernel: drbd2: resync bitmap: bits=3334033 words=104190 Mar 23 13:05:21 jerry kernel: drbd2: size = 12 GB (13336132 KB) Mar 23 13:05:21 jerry kernel: drbd2: reading of bitmap took 2 jiffies Mar 23 13:05:21 jerry kernel: drbd2: recounting of set bits took additional 0 jiffies Mar 23 13:05:21 jerry kernel: drbd2: 0 KB marked out-of-sync by on disk bit-map. Mar 23 13:05:21 jerry kernel: drbd2: Marked additional 644 MB as out-of-sync based on AL. Mar 23 13:05:21 jerry kernel: drbd2: disk( Attaching -> UpToDate ) Mar 23 13:05:21 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:05:23 jerry kernel: drbd2: aftr_isp( 0 -> 1 ) Mar 23 13:05:24 jerry kernel: drbd2: conn( StandAlone -> Unconnected ) Mar 23 13:05:24 jerry kernel: drbd2: receiver (re)started Mar 23 13:05:24 jerry kernel: drbd2: conn( Unconnected -> WFConnection ) Mar 23 13:05:24 jerry kernel: drbd2: conn( WFConnection -> WFReportParams ) Mar 23 13:05:24 jerry kernel: drbd2: Handshake successful: DRBD Network Protocol version 86 Mar 23 13:05:26 jerry kernel: drbd2: drbd_sync_handshake: Mar 23 13:05:26 jerry kernel: drbd2: self 8A0F3A9F9E1A77C7:0000000000000000:B450183638BDD68A:F6CE1616F9917C9F Mar 23 13:05:26 jerry kernel: drbd2: peer 6B0B1DA5CB20689D:8A0F3A9F9E1A77C6:B450183638BDD68A:F6CE1616F9917C9F Mar 23 13:05:27 jerry kernel: drbd2: uuid_compare()=-1 by rule 5 Mar 23 13:05:28 jerry.sn.stratus.com login: Mar 23 13:05:28 jerry kernel: drbd2: uuid[History_start] now 8A0F3A9F9E1A77C7 Mar 23 13:05:28 jerry kernel: drbd2: uuid[Current] now 8A0F3A9F9E1A77C6 Mar 23 13:05:28 jerry kernel: drbd2: peer( Unknown -> Primary ) conn( WFReportParams -> WFBitMapT ) pdsk( DUnknown -> UpToDate ) peer_isp( 0 -> 1 ) Mar 23 13:05:30 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:05:35 jerry kernel: drbd2: conn( WFBitMapT -> WFSyncUUID ) Mar 23 13:05:35 jerry kernel: drbd2: uuid[Current] now CB02C14FA3081C52 Mar 23 13:05:35 jerry kernel: drbd2: uuid[Bitmap] now 0000000000000000 Mar 23 13:05:36 jerry kernel: drbd2: conn( WFSyncUUID -> PausedSyncT ) disk( UpToDate -> Inconsistent ) Mar 23 13:05:36 jerry kernel: drbd2: Began resync as PausedSyncT (will sync 659464 KB [164866 bits set]). Mar 23 13:05:36 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:06:22 jerry kernel: drbd2: aftr_isp( 1 -> 0 ) Mar 23 13:06:22 jerry kernel: drbd2: conn( PausedSyncT -> SyncTarget ) peer_isp( 1 -> 0 ) Mar 23 13:06:22 jerry kernel: drbd2: Syncer continues. Mar 23 13:06:47 jerry kernel: drbd2: Resync done (total 88 sec; paused 63 sec; 26376 K/sec) Mar 23 13:06:47 jerry kernel: drbd2: uuid[Bitmap] now 8A0F3A9F9E1A77C6 Mar 23 13:06:47 jerry kernel: drbd2: uuid[History_start] now B450183638BDD68A Mar 23 13:06:47 jerry kernel: drbd2: uuid[History_end] now F6CE1616F9917C9F Mar 23 13:06:47 jerry kernel: drbd2: uuid[History_start] now 8A0F3A9F9E1A77C6 Mar 23 13:06:47 jerry kernel: drbd2: uuid[Bitmap] now CB02C14FA3081C52 Mar 23 13:06:47 jerry kernel: drbd2: uuid[Current] now 6B0B1DA5CB20689C Mar 23 13:06:47 jerry kernel: drbd2: uuid[History_start] now CB02C14FA3081C52 Mar 23 13:06:47 jerry kernel: drbd2: uuid[Bitmap] now 0000000000000000 Mar 23 13:06:47 jerry kernel: drbd2: conn( SyncTarget -> Connected ) disk( Inconsistent -> UpToDate ) Mar 23 13:06:47 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:08:13 drbd2: PingAck did not arrive in time. Mar 23 13:08:13 drbd2: short read expecting header on sock: r=-512 Mar 23 13:08:13 drbd2: tl_clear() Mar 23 13:08:13 jerry kernel: drbd2: PingAck did not arrive in time. Mar 23 13:08:13 jerry kernel: drbd2: peer( Primary -> Unknown ) conn( Connected -> NetworkFailure ) pdsk( UpToDate -> DUnknown ) Mar 23 13:08:13 jerry kernel: drbd2: asender terminated Mar 23 13:08:13 jerry kernel: drbd2: short read expecting header on sock: r=-512 Mar 23 13:08:13 jerry kernel: drbd2: tl_clear() Mar 23 13:08:13 jerry kernel: drbd2: Connection closed Mar 23 13:08:13 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:08:13 jerry kernel: drbd2: conn( NetworkFailure -> Unconnected ) Mar 23 13:08:13 jerry kernel: drbd2: receiver terminated Mar 23 13:08:13 jerry kernel: drbd2: receiver (re)started Mar 23 13:08:13 jerry kernel: drbd2: conn( Unconnected -> WFConnection ) Mar 23 13:09:27 jerry kernel: drbd2: role( Secondary -> Primary ) Mar 23 13:09:27 jerry kernel: drbd2: Creating new current UUID Mar 23 13:09:27 jerry kernel: drbd2: uuid[Bitmap] now 6B0B1DA5CB20689C Mar 23 13:09:27 jerry kernel: drbd2: uuid[Current] now F71E503A8179BC5D Mar 23 13:09:27 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:10:56 jerry kernel: drbd2: aftr_isp( 0 -> 1 ) Mar 23 13:10:56 jerry kernel: drbd2: conn( WFConnection -> WFReportParams ) Mar 23 13:10:56 jerry kernel: drbd2: Handshake successful: DRBD Network Protocol version 86 Mar 23 13:10:56 jerry kernel: drbd2: drbd_sync_handshake: Mar 23 13:10:56 jerry kernel: drbd2: self F71E503A8179BC5D:6B0B1DA5CB20689C:CB02C14FA3081C52:8A0F3A9F9E1A77C6 Mar 23 13:10:56 jerry kernel: drbd2: peer 6B0B1DA5CB20689D:0000000000000000:CB02C14FA3081C52:8A0F3A9F9E1A77C6 Mar 23 13:10:56 jerry kernel: drbd2: uuid_compare()=1 by rule 7 Mar 23 13:10:56 jerry kernel: drbd2: peer( Unknown -> Secondary ) conn( WFReportParams -> WFBitMapS ) pdsk( DUnknown -> UpToDate ) peer_isp( 0 -> 1 ) Mar 23 13:10:56 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:10:57 jerry kernel: drbd2: uuid[History_start] now 6B0B1DA5CB20689C Mar 23 13:10:57 jerry kernel: drbd2: uuid[Bitmap] now 3F430D4E1D59C3EA Mar 23 13:10:57 jerry kernel: drbd2: conn( WFBitMapS -> PausedSyncS ) pdsk( UpToDate -> Inconsistent ) Mar 23 13:10:57 jerry kernel: drbd2: Began resync as PausedSyncS (will sync 1052696 KB [263174 bits set]). Mar 23 13:10:57 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:12:09 jerry kernel: drbd2: aftr_isp( 1 -> 0 ) Mar 23 13:12:09 jerry kernel: drbd2: conn( PausedSyncS -> SyncSource ) peer_isp( 1 -> 0 ) Mar 23 13:12:09 jerry kernel: drbd2: Syncer continues. Mar 23 13:12:47 jerry kernel: drbd2: Resync done (total 111 sec; paused 74 sec; 28448 K/sec) Mar 23 13:12:47 jerry kernel: drbd2: uuid[History_start] now 3F430D4E1D59C3EA Mar 23 13:12:47 jerry kernel: drbd2: uuid[Bitmap] now 0000000000000000 Mar 23 13:12:47 jerry kernel: drbd2: conn( SyncSource -> Connected ) pdsk( Inconsistent -> UpToDate ) Mar 23 13:12:47 jerry kernel: drbd2: Writing meta data super block now. <<<<<<<<<<<<<<<<<<<<<<<<<<<<<>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Mar 23 13:16:46 drbd2: rct = 0 in /test_logs/builds/SuperNova/trunk/070323/platform/drbd/src/drbd/drbd_receiver.c:1878 Mar 23 13:16:48 jerry kernel: drbd2: disk( Diskless -> Attaching ) Mar 23 13:16:49 jerry kernel: drbd2: Found 6 transactions (168 active extents) in activity log. Mar 23 13:16:49 jerry kernel: drbd2: max_segment_size ( = BIO size ) = 32768 Mar 23 13:16:49 jerry kernel: drbd2: drbd_bm_resize called with capacity == 26672264 Mar 23 13:16:49 jerry kernel: drbd2: resync bitmap: bits=3334033 words=104190 Mar 23 13:16:49 jerry kernel: drbd2: size = 12 GB (13336132 KB) Mar 23 13:16:49 jerry kernel: drbd2: reading of bitmap took 3 jiffies Mar 23 13:16:49 jerry kernel: drbd2: recounting of set bits took additional 0 jiffies Mar 23 13:16:49 jerry kernel: drbd2: 0 KB marked out-of-sync by on disk bit-map. Mar 23 13:16:49 jerry kernel: drbd2: Marked additional 652 MB as out-of-sync based on AL. Mar 23 13:16:49 jerry kernel: drbd2: disk( Attaching -> UpToDate ) Mar 23 13:16:49 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:16:52 jerry kernel: drbd2: conn( StandAlone -> Unconnected ) Mar 23 13:16:52 jerry kernel: drbd2: receiver (re)started Mar 23 13:16:52 jerry kernel: drbd2: conn( Unconnected -> WFConnection ) Mar 23 13:16:52 jerry kernel: drbd2: conn( WFConnection -> WFReportParams ) Mar 23 13:16:52 jerry kernel: drbd2: Handshake successful: DRBD Network Protocol version 86 Mar 23 13:16:52 jerry kernel: drbd2: aftr_isp( 0 -> 1 ) Mar 23 13:16:52 jerry kernel: drbd2: peer( Unknown -> Secondary ) conn( WFReportParams -> Connected ) pdsk( DUnknown -> UpToDate ) peer_isp( 0 -> 1 ) Mar 23 13:16:52 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:16:53 jerry kernel: drbd2: rct = 0 in /test_logs/builds/SuperNova/trunk/070323/platform/drbd/src/drbd/drbd_receiver.c:1878 Mar 23 13:16:54 jerry kernel: drbd2: drbd_sync_handshake: Mar 23 13:16:54 jerry kernel: drbd2: self F71E503A8179BC5D:0000000000000000:3F430D4E1D59C3EA:6B0B1DA5CB20689C Mar 23 13:16:54 jerry kernel: drbd2: peer F71E503A8179BC5C:0000000000000000:3F430D4E1D59C3EA:6B0B1DA5CB20689C Mar 23 13:16:55 jerry kernel: drbd2: uuid_compare()=0 by rule 4 Mar 23 13:16:55 jerry kernel: drbd2: No resync, but bits in bitmap! Mar 23 13:17:00 jerry kernel: drbd2: drbd_sync_handshake: Mar 23 13:17:00 jerry kernel: drbd2: self F71E503A8179BC5D:0000000000000000:3F430D4E1D59C3EA:6B0B1DA5CB20689C Mar 23 13:17:00 jerry kernel: drbd2: peer 0000000000000000:0000000000000000:F71E503A8179BC5C:3F430D4E1D59C3EA Mar 23 13:17:00 jerry kernel: drbd2: uuid_compare()=-2 by rule 6 Mar 23 13:17:00 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:17:01 jerry kernel: drbd2: writing of bitmap took 11 jiffies Mar 23 13:17:01 jerry kernel: drbd2: 12 GB marked out-of-sync by on disk bit-map. Mar 23 13:17:02 jerry kernel: drbd2: 13336132 KB now marked out-of-sync by on disk bit-map. Mar 23 13:17:02 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:17:02 jerry kernel: drbd2: uuid[History_start] now F71E503A8179BC5D Mar 23 13:17:02 jerry kernel: drbd2: uuid[Current] now 0000000000000000 Mar 23 13:17:03 jerry kernel: drbd2: conn( Connected -> WFBitMapT ) <<<<<<<>>>>>>>> Mar 23 13:17:03 jerry kernel: drbd2: Writing meta data super block now. Mar 23 13:17:42 jerry kernel: drbd2: aftr_isp( 1 -> 0 ) Mar 23 13:17:42 jerry kernel: drbd2: peer_isp( 1 -> 0 )