Project

General

Profile

Actions

Bug #44197

closed

read_until returns Operation not permitted in a mixed arch client MON session

Added by Tony Davies about 4 years ago. Updated about 3 years ago.

Status:
Resolved
Priority:
Normal
Assignee:
Category:
-
Target version:
-
% Done:

0%

Source:
Tags:
Backport:
Regression:
No
Severity:
3 - minor
Reviewed:
Affected Versions:
ceph-qa-suite:
Pull request ID:
Crash signature (v1):
Crash signature (v2):

Description

debug 2020-02-19 02:19:14.678 7f95fa7d00 20 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=0 rx=0 tx=0).handle_read_frame_epilogue_main message integrity check success:  expected_crc=3814917087 calculated_crc=3814917087
debug 2020-02-19 02:19:14.678 7f95fa7d00 10 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=0 rx=0 tx=0).handle_read_frame_dispatch tag=1
debug 2020-02-19 02:19:14.678 7f95fa7d00 30 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=0 rx=0 tx=0).handle_frame_payload
00000000  08 01 01 01 1c 00 00 00  02 00 00 00 00 00 00 00  |................|
00000010  10 00 00 00 02 00 0c e4  0a 69 1c c7 00 00 00 00  |.........i......|
00000020  00 00 00 00                                       |....|
00000024

debug 2020-02-19 02:19:14.678 7f95fa7d00 20 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=0 rx=0 tx=0).handle_hello payload.length()=36
debug 2020-02-19 02:19:14.678 7f95fa7d00  5 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=0 rx=0 tx=0).handle_hello received hello: peer_type=8 peer_addr_for_me=v2:10.105.28.199:3300/0
debug 2020-02-19 02:19:14.678 7f95fa7d00  5 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=0 rx=0 tx=0).handle_hello getsockname says I am 10.244.1.192:3300 when talking to v2:10.244.7.134:56850/0
debug 2020-02-19 02:19:14.678 7f95fa7d00 10 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=1 rx=0 tx=0).handle_hello accept of host_type 8, policy.lossy=1 policy.server=1 policy.standby=0 policy.resetcheck=0
debug 2020-02-19 02:19:14.678 7f95fa7d00 20 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=HELLO_ACCEPTING pgs=0 cs=0 l=1 rx=0 tx=0).post_server_banner_exchange
debug 2020-02-19 02:19:14.678 7f95fa7d00 20 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=AUTH_ACCEPTING pgs=0 cs=0 l=1 rx=0 tx=0).read_frame
debug 2020-02-19 02:19:14.678 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 msgr2=0x55d60d75c0 unknown :-1 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=32
debug 2020-02-19 02:19:14.678 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 msgr2=0x55d60d75c0 unknown :-1 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 32 state_offset is 0
debug 2020-02-19 02:19:14.678 7f95fa7d00  1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 msgr2=0x55d60d75c0 unknown :-1 s=STATE_CONNECTION_ESTABLISHED l=1).read_bulk peer close file descriptor 15
debug 2020-02-19 02:19:14.678 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 msgr2=0x55d60d75c0 unknown :-1 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 32 got -1
debug 2020-02-19 02:19:14.678 7f95fa7d00  1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 msgr2=0x55d60d75c0 unknown :-1 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read failed
debug 2020-02-19 02:19:14.678 7f95fa7d00 20 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=AUTH_ACCEPTING pgs=0 cs=0 l=1 rx=0 tx=0).handle_read_frame_preamble_main r=-1
debug 2020-02-19 02:19:14.678 7f95fa7d00  1 --2- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >>  conn(0x55d1273860 0x55d60d75c0 unknown :-1 s=AUTH_ACCEPTING pgs=0 cs=0 l=1 rx=0 tx=0).handle_read_frame_preamble_main read frame length and tag failed r=-1 ((1) Operation not permitted)

Fails when using armv7/armhf, successful on aarch64 (same host/node)

OS: Alpine Linux v3.11
Packages built per https://github.com/PhoenixMage/aports/tree/master/community/ceph
Ceph: 14.2.6

Ceph mon ip: 10.105.28.199
Rados client ip: 10.244.3.182

Command executed: rados --debug_ms 30 --debug_auth 20 --no-mon-config lspools -m 10.105.28.199 > ~/rados_debug-mon-c.log 2>&1


Files

server_2268322028.log (55.1 KB) server_2268322028.log Tony Davies, 02/19/2020 03:14 AM
client_2268322028.log (307 KB) client_2268322028.log Tony Davies, 02/19/2020 03:14 AM

Related issues 1 (0 open1 closed)

Related to RADOS - Bug #39039: mon connection reset, command not resentDuplicateSage Weil

Actions
Actions

Also available in: Atom PDF