On Mon, Aug 31, 2020 at 6:21 PM Shain Miley <[email protected]> wrote:
>
> Hi,
> A few weeks ago several of our rdb images became unresponsive after a few of
> our OSDs reached a near full state.
>
> Another member of the team rebooted the server that the rbd images are
> mounted on in an attempt to resolve the issue.
> In the meantime I added several more nodes to the cluster in order to get
> additional space.
>
> Here are some cluster details:
>
> root@rbd1:/var/log# ceph -v
> ceph version 12.2.12 (1436006594665279fe734b4c15d7e08c13ebd777) luminous
> (stable)
>
> root@rbd1:/var/log# uname -va
> Linux rbd1 4.15.0-48-generic #51~16.04.1-Ubuntu SMP Fri Apr 5 12:01:12 UTC
> 2019 x86_64 x86_64 x86_64 GNU/Linux
>
> root@rbd1:/var/log# ceph -s
> cluster:
> id: 504b5794-34bd-44e7-a8c3-0494cf800c23
> health: HEALTH_ERR
> crush map has legacy tunables (require argonaut, min is firefly)
> full ratio(s) out of order
> 2091546/274437905 objects misplaced (0.762%)
> Reduced data availability: 114 pgs inactive
> 1 slow requests are blocked > 32 sec. Implicated osds 152
> 4 stuck requests are blocked > 4096 sec. Implicated osds 6,99
> services:
> mon: 3 daemons, quorum hqceph1,hqceph2,hqceph3
> mgr: hqceph2(active), standbys: hqceph3
> osd: 291 osds: 283 up, 265 in; 116 remapped pgs
> rgw: 1 daemon active
> data:
> pools: 17 pools, 8199 pgs
> objects: 91.48M objects, 292TiB
> usage: 880TiB used, 758TiB / 1.60PiB avail
> pgs: 1.390% pgs not active
> 2091546/274437905 objects misplaced (0.762%)
> 8040 active+clean
> 114 activating+remapped
> 40 active+clean+scrubbing+deep
> 3 active+clean+scrubbing
> 2 active+remapped+backfilling
> io:
> recovery: 41.4MiB/s, 12objects/s
>
> This morning I got on the server in order to map and mount the 5 or so RBD
> images that are shared out via samba on this server.
>
> After waiting about 10 minutes it was clear something was not 100%
> correct…here is what I found in the kern.log file:
>
>
> Aug 31 11:43:16 rbd1 kernel: [2158818.570948] libceph: mon0 10.35.1.201:6789
> session established
> Aug 31 11:43:16 rbd1 kernel: [2158818.576306] libceph: client182797617 fsid
> 504b5794-34bd-44e7-a8c3-0494cf800c23
> Aug 31 11:43:16 rbd1 kernel: [2158818.710199] rbd: rbd0: capacity
> 54975581388800 features 0x0
> Aug 31 11:43:22 rbd1 kernel: [2158824.685353] XFS (rbd0): Mounting V4
> Filesystem
> Aug 31 11:44:19 rbd1 kernel: [2158881.536794] XFS (rbd0): Starting recovery
> (logdev: internal)
> Aug 31 11:47:06 rbd1 kernel: [2159048.202835] INFO: task mount:33177 blocked
> for more than 120 seconds.
> Aug 31 11:47:06 rbd1 kernel: [2159048.203053] Not tainted
> 4.15.0-48-generic #51~16.04.1-Ubuntu
> Aug 31 11:47:06 rbd1 kernel: [2159048.203260] "echo 0 >
> /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Aug 31 11:47:06 rbd1 kernel: [2159048.203523] mount D 0 33177
> 33011 0x00000000
> Aug 31 11:47:06 rbd1 kernel: [2159048.203527] Call Trace:
> Aug 31 11:47:06 rbd1 kernel: [2159048.203538] __schedule+0x3d6/0x8b0
> Aug 31 11:47:06 rbd1 kernel: [2159048.203542] ? __switch_to_asm+0x34/0x70
> Aug 31 11:47:06 rbd1 kernel: [2159048.203546] schedule+0x36/0x80
> Aug 31 11:47:06 rbd1 kernel: [2159048.203551] schedule_timeout+0x1db/0x370
> Aug 31 11:47:06 rbd1 kernel: [2159048.203629] ?
> xfs_trans_read_buf_map+0xf8/0x330 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.203634] wait_for_completion+0xb4/0x140
> Aug 31 11:47:06 rbd1 kernel: [2159048.203637] ? wake_up_q+0x70/0x70
> Aug 31 11:47:06 rbd1 kernel: [2159048.203691] ?
> xfs_trans_read_buf_map+0xf8/0x330 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.203740] ? _xfs_buf_read+0x23/0x30 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.203787] xfs_buf_submit_wait+0x7f/0x220
> [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.203839] ?
> xfs_trans_read_buf_map+0xf8/0x330 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.203887] _xfs_buf_read+0x23/0x30 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.203933] xfs_buf_read_map+0x10a/0x190
> [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.203985]
> xfs_trans_read_buf_map+0xf8/0x330 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204022] xfs_read_agf+0x90/0x120 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204058] xfs_alloc_read_agf+0x49/0x1d0
> [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204094] xfs_alloc_pagf_init+0x29/0x60
> [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204141]
> xfs_initialize_perag_data+0x99/0x110 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204193] xfs_mountfs+0x79b/0x950 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204243] ?
> xfs_mru_cache_create+0x12b/0x170 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204294] xfs_fs_fill_super+0x428/0x5e0
> [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204300] mount_bdev+0x246/0x290
> Aug 31 11:47:06 rbd1 kernel: [2159048.204349] ?
> xfs_test_remount_options.isra.16+0x60/0x60 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204398] xfs_fs_mount+0x15/0x20 [xfs]
> Aug 31 11:47:06 rbd1 kernel: [2159048.204402] mount_fs+0x3d/0x150
> Aug 31 11:47:06 rbd1 kernel: [2159048.204406] vfs_kern_mount+0x67/0x110
> Aug 31 11:47:06 rbd1 kernel: [2159048.204410] do_mount+0x201/0xd00
> Aug 31 11:47:06 rbd1 kernel: [2159048.204414] ?
> __check_object_size+0xfc/0x1a0
> Aug 31 11:47:06 rbd1 kernel: [2159048.204418] SyS_mount+0x95/0xe0
> Aug 31 11:47:06 rbd1 kernel: [2159048.204423] do_syscall_64+0x73/0x130
> Aug 31 11:47:06 rbd1 kernel: [2159048.204426]
> entry_SYSCALL_64_after_hwframe+0x3d/0xa2
> Aug 31 11:47:06 rbd1 kernel: [2159048.204429] RIP: 0033:0x7f77a9a4fc5a
> Aug 31 11:47:06 rbd1 kernel: [2159048.204431] RSP: 002b:00007ffda3e83818
> EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
> Aug 31 11:47:06 rbd1 kernel: [2159048.204435] RAX: ffffffffffffffda RBX:
> 0000000002424030 RCX: 00007f77a9a4fc5a
> Aug 31 11:47:06 rbd1 kernel: [2159048.204436] RDX: 0000000002424210 RSI:
> 0000000002424310 RDI: 0000000002424230
> Aug 31 11:47:06 rbd1 kernel: [2159048.204438] RBP: 0000000000000000 R08:
> 0000000002424280 R09: 0000000000000012
> Aug 31 11:47:06 rbd1 kernel: [2159048.204440] R10: 00000000c0ed0c00 R11:
> 0000000000000206 R12: 0000000002424230
> Aug 31 11:47:06 rbd1 kernel: [2159048.204441] R13: 0000000002424210 R14:
> 0000000000000000 R15: 0000000000000003
>
> Any suggestions on what I can/should do next?
Hi Shain,
This is not a panic. XFS's mount process is waiting for some I/O to
complete, and I'm guessing it is one of those 5 stuck requests reported
under HEALTH_ERR. To confirm, you can take a look at
/sys/kernel/debug/ceph/504b5794-34bd-44e7-a8c3-0494cf800c23.client182797617/osdc
on "rbd1" node and OSD logs for OSDs 6, 99, and 152. The OSD logs
should tell you what exactly those requests are stuck on.
Thanks,
Ilya
_______________________________________________
ceph-users mailing list -- [email protected]
To unsubscribe send an email to [email protected]
