Ceph » crimson

out/osd.0.stdout
/home/ceph/src/ceph-run /home/ceph/build/bin/crimson-osd --smp 1 --cpuset 0 --memory 4G --debug i 0 -c /home/ceph/build/ceph.conf -f &
INFO 2020-05-23 22:57:14,800 [shard 0] objclass - <cls> /home/ceph/src/cls/log/cls_log.cc:301: Loaded log class!
INFO 2020-05-23 22:57:14,801 [shard 0] objclass - <cls> /home/ceph/src/cls/cas/cls_cas.cc:237: Loaded cas class!
INFO 2020-05-23 22:57:14,801 [shard 0] objclass - <cls> /home/ceph/src/cls/version/cls_version.cc:214: Loaded version class!
INFO 2020-05-23 22:57:14,801 [shard 0] objclass - <cls> /home/ceph/src/cls/user/cls_user.cc:429: Loaded user class!
INFO 2020-05-23 22:57:14,802 [shard 0] objclass - <cls> /home/ceph/src/cls/timeindex/cls_timeindex.cc:242: Loaded timeindex class!
WARN 2020-05-23 22:57:14,802 [shard 0] objclass - <cls> /home/ceph/src/cls/sdk/cls_sdk.cc:120: loading cls_sdk
WARN 2020-05-23 22:57:14,802 [shard 0] objclass - <cls> /home/ceph/src/cls/hello/cls_hello.cc:312: loading cls_hello
WARN 2020-05-23 22:57:14,803 [shard 0] objclass - <cls> /home/ceph/src/cls/cephfs/cls_cephfs.cc:198: loading cephfs
WARN 2020-05-23 22:57:14,804 [shard 0] osd - _load_class could not open class /home/ceph/build/lib/libcls_queue.so (dlopen failed): /home/ceph/build/lib/libcls_queue.so: undefined symbol: _Z18cls_cxx_write_zeroPvii
WARN 2020-05-23 22:57:14,804 [shard 0] osd - OSD warning: got an error loading one or more classes: (5) Input/output error
INFO 2020-05-23 22:57:14,804 [shard 0] osd - start
DEBUG 2020-05-23 22:57:14,804 [shard 0] filestore - read meta #-1:7b3f43c4:::osd_superblock:0# 0~0
WARN 2020-05-23 22:57:14,804 [shard 0] osd - ignoring unrecognized collection: meta
ERROR 2020-05-23 22:57:14,804 [shard 0] none - Falling back to public interface
INFO 2020-05-23 22:57:14,804 [shard 0] osd - picked address v2:0.0.0.0:0/0
INFO 2020-05-23 22:57:14,804 [shard 0] osd - picked address v1:0.0.0.0:0/0
DEBUG 2020-05-23 22:57:14,804 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6800/15388] do_bind: try listen 0.0.0.0:6800...
INFO 2020-05-23 22:57:14,804 [shard 0] osd - picked address v2:0.0.0.0:0/0
INFO 2020-05-23 22:57:14,804 [shard 0] osd - picked address v1:0.0.0.0:0/0
DEBUG 2020-05-23 22:57:14,804 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6800/15388] do_bind: try listen 0.0.0.0:6800...
DEBUG 2020-05-23 22:57:14,804 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6801/15388] do_bind: try listen 0.0.0.0:6801...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6801/15388] do_bind: try listen 0.0.0.0:6801...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6802/15388] do_bind: try listen 0.0.0.0:6802...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6803/15388] do_bind: try listen 0.0.0.0:6803...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6804/15388] do_bind: try listen 0.0.0.0:6804...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6805/15388] do_bind: try listen 0.0.0.0:6805...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6802/15388] do_bind: try listen 0.0.0.0:6802...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6803/15388] do_bind: try listen 0.0.0.0:6803...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6804/15388] do_bind: try listen 0.0.0.0:6804...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6805/15388] do_bind: try listen 0.0.0.0:6805...
DEBUG 2020-05-23 22:57:14,805 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6806/15388] do_bind: try listen 0.0.0.0:6806...
DEBUG 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6807/15388] do_bind: try listen 0.0.0.0:6807...
DEBUG 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6808/15388] do_bind: try listen 0.0.0.0:6808...
INFO 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(cluster) v2:0.0.0.0:6808/15388] try_bind: done
DEBUG 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6806/15388] do_bind: try listen 0.0.0.0:6806...
DEBUG 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6807/15388] do_bind: try listen 0.0.0.0:6807...
DEBUG 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6808/15388] do_bind: try listen 0.0.0.0:6808...
DEBUG 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388] do_bind: try listen 0.0.0.0:6809...
INFO 2020-05-23 22:57:14,806 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388] try_bind: done
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding auth protocol: cephx
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding auth protocol: cephx
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding auth protocol: cephx
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: secure
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: crc
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: secure
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: crc
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: secure
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: crc
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: crc
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: secure
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: crc
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: secure
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: crc
DEBUG 2020-05-23 22:57:14,806 [shard 0] auth - AuthRegistry(0xe000000dc408) adding con mode: secure
INFO 2020-05-23 22:57:14,806 [shard 0] auth - auth: KeyRing::load: loaded key file /home/ceph/build/dev/osd0/keyring
INFO 2020-05-23 22:57:14,807 [shard 0] monc - reopen_session to mon.-1
INFO 2020-05-23 22:57:14,807 [shard 0] monc - connecting to mon.0
INFO 2020-05-23 22:57:14,807 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] ProtocolV2::start_connect(): peer_addr=v2:90.90.26.251:40412/0, peer_type=mon, cc=4412757272530161755 policy(lossy=1, server=0, standby=0, resetcheck=0)
DEBUG 2020-05-23 22:57:14,807 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] TRIGGER CONNECTING, was NONE
DEBUG 2020-05-23 22:57:14,807 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] UPDATE: gs=1 for connect
INFO 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] write_event: delay ...
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] socket connected
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] SEND banner: len_payload=16, supported=0, required=0, banner="ceph v2
"
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] RECV banner: "ceph v2
"
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] GOT banner: payload_len=16
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] RECV banner features: supported=0 required=0
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] WRITE HelloFrame: my_type=osd, peer_addr=v2:90.90.26.251:40412/0
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:0.0.0.0:6809/15388 >> mon.? v2:90.90.26.251:40412/0] GOT HelloFrame: my_type=mon peer_addr=v2:90.90.26.251:56837/0
INFO 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:90.90.26.251:6809/15388@56837 >> mon.? v2:90.90.26.251:40412/0] learned myaddr=v2:90.90.26.251:6809/15388 (blank IP) from v2:90.90.26.251:56837/0
INFO 2020-05-23 22:57:14,808 [shard 0] monc - get_auth_request(con=[osd.0(client) v2:90.90.26.251:6809/15388@56837 >> mon.? v2:90.90.26.251:40412/0], auth_method=0)
INFO 2020-05-23 22:57:14,808 [shard 0] monc - method 2 preferred_modes {2, 1}
DEBUG 2020-05-23 22:57:14,808 [shard 0] ms - [osd.0(client) v2:90.90.26.251:6809/15388@56837 >> mon.? v2:90.90.26.251:40412/0] WRITE AuthRequestFrame: method=2, preferred_modes={2, 1}, payload_len=18
DEBUG 2020-05-23 22:57:14,853 [shard 0] ms - [osd.0(client) v2:90.90.26.251:6809/15388@56837 >> mon.? v2:90.90.26.251:40412/0] GOT AuthReplyMoreFrame: payload_len=9
INFO 2020-05-23 22:57:14,853 [shard 0] monc - responding with 36 bytes
DEBUG 2020-05-23 22:57:14,853 [shard 0] ms - [osd.0(client) v2:90.90.26.251:6809/15388@56837 >> mon.? v2:90.90.26.251:40412/0] WRITE AuthRequestMoreFrame: payload_len=36
DEBUG 2020-05-23 22:57:14,853 [shard 0] ms - [osd.0(client) v2:90.90.26.251:6809/15388@56837 >> mon.? v2:90.90.26.251:40412/0] GOT AuthDoneFrame: gid=4134, con_mode=secure, payload_len=995
ERROR 2020-05-23 22:57:14,853 [shard 0] none - /home/ceph/src/crimson/net/ProtocolV2.cc:671 : In function 'crimson::net::ProtocolV2::handle_auth_reply()::<lambda(ceph::msgr::v2::Tag)>::<lambda()>', ceph_assert(%s)
!auth_meta>is_mode_secure()
Aborting on shard 0.
Backtrace:
0x0000000000f48213
0x0000000000f0ecf3
0x0000000000f0edb7
0x0000000000f0eeff
0x000000000000066b
/lib64/libc.so.6+0x0000000000035237
/lib64/libc.so.6+0x00000000000368af
0x00000000007b9553
0x00000000007b956f
0x0000000000d1b85f
0x0000000000d2749b
0x0000000000d2822b
0x0000000000f0c677
0x0000000000f0c9e3
0x0000000000f325e7
0x0000000000ee470f
0x00000000007bc413
/lib64/libc.so.6+0x0000000000021723
0x00000000007ecfcb
/home/ceph/src/ceph-run: line 21: 15388 Aborted "$@"
ceph-run: /home/ceph/build/bin/crimson-osd dumped core, restarting in 5 seconds...

Feel free to re-open if relevant