Description: rbd/mirror-thrash/{base/install clients/mirror cluster/{2-node openstack} msgr-failures/few objectstore/bluestore-comp-lz4 policy/none rbd-mirror/four-per-cluster supported-random-distro$/{centos_8} workloads/rbd-mirror-fsx-workunit}

Log: http://qa-proxy.ceph.com/teuthology/jdillaman-2020-08-18_21:41:34-rbd-wip-jd-testing-distro-basic-smithi/5358052/teuthology.log

Failure Reason:

"2020-08-19T02:14:49.561372+0000 osd.2 (osd.2) 3 : cluster [WRN] slow request osd_op(client.4276.0:14391 3.26 3:67ccf2d9:::rbd_mirror_instance.4276:head [watch ping cookie 94858456057984 gen 22] snapc 0=[] ondisk+write+known_if_redirected e297) initiated 2020-08-19T02:14:18.965878+0000 currently delayed" in cluster log

  • log_href: http://qa-proxy.ceph.com/teuthology/jdillaman-2020-08-18_21:41:34-rbd-wip-jd-testing-distro-basic-smithi/5358052/teuthology.log
  • archive_path: /home/teuthworker/archive/jdillaman-2020-08-18_21:41:34-rbd-wip-jd-testing-distro-basic-smithi/5358052
  • description: rbd/mirror-thrash/{base/install clients/mirror cluster/{2-node openstack} msgr-failures/few objectstore/bluestore-comp-lz4 policy/none rbd-mirror/four-per-cluster supported-random-distro$/{centos_8} workloads/rbd-mirror-fsx-workunit}
  • duration: 1:26:01
  • email:
  • failure_reason: "2020-08-19T02:14:49.561372+0000 osd.2 (osd.2) 3 : cluster [WRN] slow request osd_op(client.4276.0:14391 3.26 3:67ccf2d9:::rbd_mirror_instance.4276:head [watch ping cookie 94858456057984 gen 22] snapc 0=[] ondisk+write+known_if_redirected e297) initiated 2020-08-19T02:14:18.965878+0000 currently delayed" in cluster log
  • flavor: basic
  • job_id: 5358052
  • kernel:
    • sha1: distro
    • kdb: True
  • last_in_suite: False
  • machine_type: smithi
  • name: jdillaman-2020-08-18_21:41:34-rbd-wip-jd-testing-distro-basic-smithi
  • nuke_on_error: True
  • os_type: centos
  • os_version: 8.1
  • overrides:
    • ceph-deploy:
      • conf:
        • client:
          • log file: /var/log/ceph/ceph-$name.$pid.log
        • mon:
          • osd default pool size: 2
    • selinux:
      • whitelist:
        • scontext=system_u:system_r:logrotate_t:s0
    • workunit:
      • sha1: dec8292a17f4d7543a4e22c293886363c97068b5
      • branch: wip-47007
    • ceph:
      • log-whitelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • \(OSD_SLOW_PING_TIME
      • fs: xfs
      • conf:
        • client.mirror.1:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.0:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.3:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.2:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • global:
          • ms inject socket failures: 5000
          • mon client directed command retry: 5
        • mgr:
          • debug ms: 1
          • debug mgr: 20
        • client:
          • debug rbd: 20
          • rbd mirror image policy type: none
          • rbd default features: 125
          • debug rbd_mirror: 15
          • log to stderr: False
        • mon:
          • debug paxos: 20
          • debug mon: 20
          • debug ms: 1
        • osd:
          • mon osd full ratio: 0.9
          • bluestore compression algorithm: lz4
          • debug ms: 20
          • bluestore fsck on mount: True
          • debug osd: 25
          • bluestore compression mode: aggressive
          • debug bluestore: 20
          • debug bluefs: 20
          • osd objectstore: bluestore
          • mon osd backfillfull_ratio: 0.85
          • bluestore block size: 96636764160
          • debug filestore: 20
          • debug rocksdb: 10
          • mon osd nearfull ratio: 0.8
          • osd failsafe full ratio: 0.95
          • debug journal: 20
      • sha1: ca28f79e8ca1db3d8a72e6f8969498222b2a220d
    • install:
      • ceph:
        • sha1: ca28f79e8ca1db3d8a72e6f8969498222b2a220d
    • admin_socket:
      • branch: wip-jd-testing
    • thrashosds:
      • bdev_inject_crash_probability: 0.5
      • bdev_inject_crash: 2
  • owner: scheduled_jdillaman@dillaman-rh
  • pid:
  • roles:
    • ['cluster1.mon.a', 'cluster1.mgr.x', 'cluster2.mgr.x', 'cluster1.osd.0', 'cluster1.osd.1', 'cluster1.osd.2', 'cluster1.client.0', 'cluster2.client.0']
    • ['cluster2.mon.a', 'cluster2.osd.0', 'cluster2.osd.1', 'cluster2.osd.2', 'cluster1.client.mirror', 'cluster1.client.mirror.0', 'cluster1.client.mirror.1', 'cluster1.client.mirror.2', 'cluster1.client.mirror.3', 'cluster1.client.mirror.4', 'cluster1.client.mirror.5', 'cluster1.client.mirror.6', 'cluster2.client.mirror', 'cluster2.client.mirror.0', 'cluster2.client.mirror.1', 'cluster2.client.mirror.2', 'cluster2.client.mirror.3', 'cluster2.client.mirror.4', 'cluster2.client.mirror.5', 'cluster2.client.mirror.6']
  • sentry_event:
  • status: fail
  • success: False
  • branch: wip-jd-testing
  • seed:
  • sha1: ca28f79e8ca1db3d8a72e6f8969498222b2a220d
  • subset:
  • suite:
  • suite_branch: wip-47007
  • suite_path:
  • suite_relpath:
  • suite_repo:
  • suite_sha1: dec8292a17f4d7543a4e22c293886363c97068b5
  • targets:
    • smithi148.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDneHjFWFAtUZW4htekV5xmucKYu4RqNIJW7Kh1PSUL3nPs+i4QmqcYbzNCQVb5R1uvGUAAd8McfjgWT76djPEQAZM6YJt3dpbCpVz1PBTZjFBeYm/0sZYlp/2O4fGW4eZIodkRN5ZJkqAVbSrswVrWC0JXX9zwQor1vfA2X4JWer+9lZDbzxSl6tqT0QZnQz/QyrD6TWHGHXV3NE14+fy2RuREjVjvj0p7HNssGiIR3bTdAl4WeSODU4NQMKQSIWMgDFFf3S5c7GrCWHkLB/SHNiRpFBO0ssLKNl1n9JHVuQWdCGIWr7ZV8fy3v8x8ssgJQZfKgWK8o1lH0PWX23dV/I5zj2ZXS86rrL79nH7IiyNzSXN2CjzdcQ5WRXZaf+OWWAYe6ldh3DWMPs6WMESnCeh5tsJqaei8c2ScupshAGYv+B/BfHCRtZEHI/DFNx2vYzu8Hn1T54DA++qMrlBwp/xAnqPrU0AkuURz6QMGsvGX/tSRztD+nU1wfPmVCmc=
    • smithi079.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQCdnGEHVz6xLEm3REkb9+i/x4oRPrWVOS212guE9Dw7Ha7IrWTFNT7knGm/GyKduXRtFCI+ykUWvP07K2bq8/lSUy2Z1G4v0r+yTSKA8sgT9zmuRSZff+M0Q/vp/PkKYS+UbLlcxLJbVApv3hg8SOza2nXmPNOA8Pjycc/EGVzNN5iyrFS6li7bd20qhnk6OC/VUQ8d1C83+jQhJjxpJZwuqUmeYAuCaSJS+dTQjR1wS1mh7p5bTkhzALGO3jy9/jcCudJpWRgtX4HXxRWOm5PoH17EHcYPXZQe1AcmZVJFjlABZEgAtSN6RmKt6YdD55Mr8ouC/amDN8b6NuH/vvm8PCA0EkS7YJe6Hhek+53qu2Ac71YtNERO/aIJkvI+0KztuUCauKvmgEfh5e/1p6i+HLStjyyncd0k8qZ0Zwg6yH6OFJCGOcpjnpcXGkTSdyHlGlCwIHLMcQWGnqpXcVTLvzie/gjFUwzlQhc7VD6vGMlQE9CIQGBElokLmLpmQuU=
  • tasks:
    • internal.check_packages:
    • internal.buildpackages_prep:
    • internal.lock_machines:
      • 2
      • smithi
    • internal.save_config:
    • internal.check_lock:
    • internal.add_remotes:
    • console_log:
    • internal.connect:
    • internal.push_inventory:
    • internal.serialize_remote_roles:
    • internal.check_conflict:
    • internal.check_ceph_data:
    • internal.vm_setup:
    • kernel:
      • sha1: distro
      • kdb: True
    • internal.base:
    • internal.archive_upload:
    • internal.archive:
    • internal.coredump:
    • internal.sudo:
    • internal.syslog:
    • internal.timer:
    • pcp:
    • selinux:
    • ansible.cephlab:
    • clock:
    • install:
      • sha1: ca28f79e8ca1db3d8a72e6f8969498222b2a220d
      • extra_packages:
        • rbd-mirror
    • ceph:
      • log-whitelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • \(OSD_SLOW_PING_TIME
      • cluster: cluster1
      • fs: xfs
      • conf:
        • client.mirror.1:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.0:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.3:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.2:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • global:
          • ms inject socket failures: 5000
          • mon client directed command retry: 5
        • mgr:
          • debug ms: 1
          • debug mgr: 20
        • client:
          • debug rbd: 20
          • rbd mirror image policy type: none
          • rbd default features: 125
          • debug rbd_mirror: 15
          • log to stderr: False
        • mon:
          • debug paxos: 20
          • debug mon: 20
          • debug ms: 1
        • osd:
          • mon osd full ratio: 0.9
          • bluestore compression algorithm: lz4
          • debug ms: 20
          • bluestore fsck on mount: True
          • debug osd: 25
          • bluestore compression mode: aggressive
          • debug bluestore: 20
          • debug bluefs: 20
          • osd objectstore: bluestore
          • mon osd backfillfull_ratio: 0.85
          • bluestore block size: 96636764160
          • debug filestore: 20
          • debug rocksdb: 10
          • mon osd nearfull ratio: 0.8
          • osd failsafe full ratio: 0.95
          • debug journal: 20
      • sha1: ca28f79e8ca1db3d8a72e6f8969498222b2a220d
    • ceph:
      • log-whitelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • \(OSD_SLOW_PING_TIME
      • cluster: cluster2
      • fs: xfs
      • conf:
        • client.mirror.1:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.0:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.3:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • client.mirror.2:
          • admin socket: /var/run/ceph/rbd-mirror.$cluster-$name.asok
          • pid file: /var/run/ceph/rbd-mirror.$cluster-$name.pid
        • global:
          • ms inject socket failures: 5000
          • mon client directed command retry: 5
        • mgr:
          • debug ms: 1
          • debug mgr: 20
        • client:
          • debug rbd: 20
          • rbd mirror image policy type: none
          • rbd default features: 125
          • debug rbd_mirror: 15
          • log to stderr: False
        • mon:
          • debug paxos: 20
          • debug mon: 20
          • debug ms: 1
        • osd:
          • mon osd full ratio: 0.9
          • bluestore compression algorithm: lz4
          • debug ms: 20
          • bluestore fsck on mount: True
          • debug osd: 25
          • bluestore compression mode: aggressive
          • debug bluestore: 20
          • debug bluefs: 20
          • osd objectstore: bluestore
          • mon osd backfillfull_ratio: 0.85
          • bluestore block size: 96636764160
          • debug filestore: 20
          • debug rocksdb: 10
          • mon osd nearfull ratio: 0.8
          • osd failsafe full ratio: 0.95
          • debug journal: 20
      • sha1: ca28f79e8ca1db3d8a72e6f8969498222b2a220d
    • exec:
      • cluster1.client.mirror.0:
        • sudo ceph --cluster cluster1 auth caps client.mirror mon 'profile rbd-mirror-peer' osd 'profile rbd'
        • sudo ceph --cluster cluster1 auth caps client.mirror.0 mon 'profile rbd-mirror' osd 'profile rbd'
        • sudo ceph --cluster cluster1 auth caps client.mirror.1 mon 'profile rbd-mirror' osd 'profile rbd'
        • sudo ceph --cluster cluster1 auth caps client.mirror.2 mon 'profile rbd-mirror' osd 'profile rbd'
        • sudo ceph --cluster cluster1 auth caps client.mirror.3 mon 'profile rbd-mirror' osd 'profile rbd'
        • sudo ceph --cluster cluster2 auth caps client.mirror mon 'profile rbd-mirror-peer' osd 'profile rbd'
        • sudo ceph --cluster cluster2 auth caps client.mirror.0 mon 'profile rbd-mirror' osd 'profile rbd'
        • sudo ceph --cluster cluster2 auth caps client.mirror.1 mon 'profile rbd-mirror' osd 'profile rbd'
        • sudo ceph --cluster cluster2 auth caps client.mirror.2 mon 'profile rbd-mirror' osd 'profile rbd'
        • sudo ceph --cluster cluster2 auth caps client.mirror.3 mon 'profile rbd-mirror' osd 'profile rbd'
    • rbd-mirror:
      • thrash: True
      • client: cluster1.client.mirror.0
    • rbd-mirror:
      • thrash: True
      • client: cluster1.client.mirror.1
    • rbd-mirror:
      • thrash: True
      • client: cluster1.client.mirror.2
    • rbd-mirror:
      • thrash: True
      • client: cluster1.client.mirror.3
    • rbd-mirror:
      • thrash: True
      • client: cluster2.client.mirror.0
    • rbd-mirror:
      • thrash: True
      • client: cluster2.client.mirror.1
    • rbd-mirror:
      • thrash: True
      • client: cluster2.client.mirror.2
    • rbd-mirror:
      • thrash: True
      • client: cluster2.client.mirror.3
    • rbd-mirror-thrash:
      • cluster: cluster1
    • rbd-mirror-thrash:
      • cluster: cluster2
    • workunit:
      • clients:
        • cluster1.client.mirror:
          • rbd/rbd_mirror_fsx_prepare.sh
      • branch: wip-47007
      • env:
        • CEPH_ARGS:
        • RBD_MIRROR_USE_RBD_MIRROR: 1
        • RBD_MIRROR_NOCLEANUP: 1
        • RBD_MIRROR_USE_EXISTING_CLUSTER: 1
      • sha1: dec8292a17f4d7543a4e22c293886363c97068b5
    • rbd_fsx:
      • pool_name: mirror
      • ops: 6000
      • clients:
        • cluster1.client.mirror.0
        • cluster1.client.mirror.1
        • cluster1.client.mirror.2
        • cluster1.client.mirror.3
        • cluster1.client.mirror.4
        • cluster1.client.mirror.5
      • keep_images: True
    • workunit:
      • sha1: dec8292a17f4d7543a4e22c293886363c97068b5
      • clients:
        • cluster1.client.mirror:
          • rbd/rbd_mirror_fsx_compare.sh
      • timeout: 6h
      • env:
        • CEPH_ARGS:
        • RBD_MIRROR_USE_RBD_MIRROR: 1
        • RBD_MIRROR_USE_EXISTING_CLUSTER: 1
      • branch: wip-47007
  • teuthology_branch: master
  • verbose: False
  • pcp_grafana_url:
  • priority:
  • user:
  • queue:
  • posted: 2020-08-19 01:42:22
  • started: 2020-08-19 01:53:30
  • updated: 2020-08-19 03:27:32
  • status_class: danger
  • runtime: 1:34:02
  • wait_time: 0:08:01