Description: rados/thrash/{0-size-min-size-overrides/2-size-2-min-size 1-pg-log-overrides/normal_pg_log 2-recovery-overrides/{more-partial-recovery} 3-scrub-overrides/{default} backoff/peering ceph clusters/{fixed-2 openstack} crc-failures/bad_map_crc_failure d-balancer/off msgr-failures/few msgr/async-v2only objectstore/bluestore-comp-zstd rados supported-random-distro$/{centos_8} thrashers/morepggrow thrashosds-health workloads/cache-agent-big}

Log: http://qa-proxy.ceph.com/teuthology/yuriw-2022-02-28_21:24:59-rados-wip-yuri11-testing-2022-02-28-0803-octopus-distro-default-smithi/6712054/teuthology.log

Failure Reason:

timed out waiting for mon to be updated with osd.0: 55834574859 < 55834574860

  • log_href: http://qa-proxy.ceph.com/teuthology/yuriw-2022-02-28_21:24:59-rados-wip-yuri11-testing-2022-02-28-0803-octopus-distro-default-smithi/6712054/teuthology.log
  • archive_path: /home/teuthworker/archive/yuriw-2022-02-28_21:24:59-rados-wip-yuri11-testing-2022-02-28-0803-octopus-distro-default-smithi/6712054
  • description: rados/thrash/{0-size-min-size-overrides/2-size-2-min-size 1-pg-log-overrides/normal_pg_log 2-recovery-overrides/{more-partial-recovery} 3-scrub-overrides/{default} backoff/peering ceph clusters/{fixed-2 openstack} crc-failures/bad_map_crc_failure d-balancer/off msgr-failures/few msgr/async-v2only objectstore/bluestore-comp-zstd rados supported-random-distro$/{centos_8} thrashers/morepggrow thrashosds-health workloads/cache-agent-big}
  • duration: 0:26:29
  • email: ceph-qa@ceph.io
  • failure_reason: timed out waiting for mon to be updated with osd.0: 55834574859 < 55834574860
  • flavor: default
  • job_id: 6712054
  • kernel:
    • kdb: True
    • sha1: distro
  • last_in_suite: False
  • machine_type: smithi
  • name: yuriw-2022-02-28_21:24:59-rados-wip-yuri11-testing-2022-02-28-0803-octopus-distro-default-smithi
  • nuke_on_error: True
  • os_type: centos
  • os_version: 8.stream
  • overrides:
    • admin_socket:
      • branch: wip-yuri11-testing-2022-02-28-0803-octopus
    • ceph:
      • conf:
        • global:
          • mon client directed command retry: 5
          • ms bind msgr1: False
          • ms bind msgr2: True
          • ms inject socket failures: 5000
          • ms type: async
          • osd_object_clean_region_max_num_intervals: 1000
          • osd_pool_default_min_size: 2
          • osd_pool_default_size: 2
        • mgr:
          • debug mgr: 20
          • debug ms: 1
        • mon:
          • debug mon: 20
          • debug ms: 1
          • debug paxos: 20
          • mon scrub interval: 300
        • osd:
          • bluestore block size: 96636764160
          • bluestore compression algorithm: zstd
          • bluestore compression mode: aggressive
          • bluestore fsck on mount: True
          • debug bluefs: 20
          • debug bluestore: 20
          • debug ms: 1
          • debug osd: 20
          • debug rocksdb: 10
          • filestore queue throttle high multiple: 2
          • filestore queue throttle max multiple: 10
          • journal throttle high multiple: 2
          • journal throttle max multiple: 10
          • mon osd backfillfull_ratio: 0.85
          • mon osd full ratio: 0.9
          • mon osd nearfull ratio: 0.8
          • osd backoff on peering: True
          • osd debug verify cached snaps: True
          • osd debug verify missing on start: True
          • osd failsafe full ratio: 0.95
          • osd heartbeat use min delay socket: True
          • osd inject bad map crc probability: 0.1
          • osd max backfills: 9
          • osd max markdown count: 1000
          • osd objectstore: bluestore
          • osd op queue: debug_random
          • osd op queue cut off: debug_random
          • osd scrub max interval: 120
          • osd scrub min interval: 60
          • osd shutdown pgref assert: True
          • osd_max_pg_log_entries: 3000
          • osd_min_pg_log_entries: 3000
      • flavor: default
      • fs: xfs
      • log-ignorelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • \(REQUEST_STUCK\)
        • failed to encode map
        • \(OSD_SLOW_PING_TIME
        • but it is still running
        • objects unfound and apparently lost
        • overall HEALTH_
        • \(OSDMAP_FLAGS\)
        • \(OSD_
        • \(PG_
        • \(POOL_
        • \(CACHE_POOL_
        • \(SMALLER_PGP_NUM\)
        • \(OBJECT_
        • \(SLOW_OPS\)
        • \(REQUEST_SLOW\)
        • \(TOO_FEW_PGS\)
        • slow request
        • must scrub before tier agent can activate
      • log-whitelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
      • sha1: 45ece157fc29b55a5ee952c81c0c23abdb89bf98
    • ceph-deploy:
      • conf:
        • client:
          • log file: /var/log/ceph/ceph-$name.$pid.log
        • mon:
          • osd default pool size: 2
    • install:
      • ceph:
        • flavor: default
        • sha1: 45ece157fc29b55a5ee952c81c0c23abdb89bf98
    • selinux:
      • whitelist:
        • scontext=system_u:system_r:logrotate_t:s0
    • thrashosds:
      • bdev_inject_crash: 2
      • bdev_inject_crash_probability: 0.5
    • workunit:
      • branch: wip-yuri11-testing-2022-02-28-0803-octopus
      • sha1: 45ece157fc29b55a5ee952c81c0c23abdb89bf98
  • owner: scheduled_yuriw@teuthology
  • pid:
  • roles:
    • ['mon.a', 'mon.c', 'mgr.y', 'osd.0', 'osd.1', 'osd.2', 'osd.3', 'client.0', 'node-exporter.a']
    • ['mon.b', 'mgr.x', 'osd.4', 'osd.5', 'osd.6', 'osd.7', 'client.1', 'prometheus.a', 'node-exporter.b']
  • sentry_event:
  • status: fail
  • success: False
  • branch: wip-yuri11-testing-2022-02-28-0803-octopus
  • seed:
  • sha1: 45ece157fc29b55a5ee952c81c0c23abdb89bf98
  • subset:
  • suite:
  • suite_branch: wip-yuri11-testing-2022-02-28-0803-octopus
  • suite_path:
  • suite_relpath:
  • suite_repo:
  • suite_sha1: 45ece157fc29b55a5ee952c81c0c23abdb89bf98
  • targets:
    • smithi002.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQCsWKQWOXxdpNQAqony34cO+m7NIEp/nFfbDI/1ZWd+NXWrajE0kgi5baSqAZj6XdHoN5p7V7LdSZY4xCtmIzHLEJKOkFxs41bbIeAMtxzUy3L2ejTV4UM4vLegXJ5zNlMP4FZ4pGYEXGbIPG7uLWQ+O1xlu04olMuWc1tb78YnXOiWjKsiteLfCCRokKxkvFmPG2JI4IAXmBdLk69c+BQO9RW04HB0f9Z94fu5MSuv/peh4/c6SpQEHUz1LQEzcD14QM3YGxzwvEqOXQGRv+HsV9WhyU42dZW2wyrtWbWV4r2SiMpBNrdwFNYrtzkJo5aEDhTNLbqwQzQwbNItJTLGwYAq83un09sxfBzINTs4zxxeecVl+wkvmLoD+JcZl2v+Vm/duha6aAd/OsaWsaovWiu9VyWRGYiooPz78zgF8eBHYMOmbhCZNtVuNYtlLkLhVPl++tOg/AmOLtG6xpJwj3NraKGfbfOAKNrXROfq2XYw2uh20G+BJynZOV7L+i8=
    • smithi189.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDpelLeRw1pGDXSApGyHuW6egL4IXskLkcGaqsNUrn6+qW5jp7iEe4BLztv1V6j3ZL7odr0zyaJkjPxjPrXy5v3v+VtOZjTlvCuEoW4Ikk0j/49xRMh4ARfT8eAZb2DV0pbl6EPtDMnR4VsPULf0Zl0/eNwnaGqKZhERUIUZN/MHUJwpeZa4YN7fKBUWrQTXzN8gd0ZrNXMjPIJz6O5jP96WX2vF1ugWYvPBc6de3zZCV9tp7zLxF/SPqIJZlA4rAQXpnsdUalO1oskD9BsQVM/ykz1SeNompx++mUblDwQjM9IZSeBgQys9ye+SoHCLAXaUa4bSs5tdO6PnsOnM8YqKH9phLnYkVptSsoUeI1cZ+tkQfYic3WCOTd33Sm+94CsjMd0J0MA5uBjq6BKlEyFOgw9ZS92Dmi3R5Zm4ox1LVwyjHRwCNZmpf/FwJoLPsXHAk58SU+hVy9WZ0TjK4kYFOD2v5gdmcyH01wn2/D52d00rCtN31Fdae4pwfT0jh8=
  • tasks:
    • internal.check_packages:
    • internal.buildpackages_prep:
    • 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:
      • kdb: True
      • sha1: distro
    • internal.base:
    • internal.archive_upload:
    • internal.archive:
    • internal.coredump:
    • internal.sudo:
    • internal.syslog:
    • internal.timer:
    • pcp:
    • selinux:
    • ansible.cephlab:
    • clock:
    • install:
    • ceph:
      • conf:
        • osd:
          • debug monc: 20
          • bluestore block size: 96636764160
          • bluestore compression algorithm: zstd
          • bluestore compression mode: aggressive
          • bluestore fsck on mount: True
          • debug bluefs: 20
          • debug bluestore: 20
          • debug ms: 1
          • debug osd: 20
          • debug rocksdb: 10
          • filestore queue throttle high multiple: 2
          • filestore queue throttle max multiple: 10
          • journal throttle high multiple: 2
          • journal throttle max multiple: 10
          • mon osd backfillfull_ratio: 0.85
          • mon osd full ratio: 0.9
          • mon osd nearfull ratio: 0.8
          • osd backoff on peering: True
          • osd debug verify cached snaps: True
          • osd debug verify missing on start: True
          • osd failsafe full ratio: 0.95
          • osd heartbeat use min delay socket: True
          • osd inject bad map crc probability: 0.1
          • osd max backfills: 9
          • osd max markdown count: 1000
          • osd objectstore: bluestore
          • osd op queue: debug_random
          • osd op queue cut off: debug_random
          • osd scrub max interval: 120
          • osd scrub min interval: 60
          • osd shutdown pgref assert: True
          • osd_max_pg_log_entries: 3000
          • osd_min_pg_log_entries: 3000
        • global:
          • mon client directed command retry: 5
          • ms bind msgr1: False
          • ms bind msgr2: True
          • ms inject socket failures: 5000
          • ms type: async
          • osd_object_clean_region_max_num_intervals: 1000
          • osd_pool_default_min_size: 2
          • osd_pool_default_size: 2
        • mgr:
          • debug mgr: 20
          • debug ms: 1
        • mon:
          • debug mon: 20
          • debug ms: 1
          • debug paxos: 20
          • mon scrub interval: 300
      • flavor: default
      • fs: xfs
      • log-ignorelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • \(REQUEST_STUCK\)
        • failed to encode map
        • \(OSD_SLOW_PING_TIME
        • but it is still running
        • objects unfound and apparently lost
        • overall HEALTH_
        • \(OSDMAP_FLAGS\)
        • \(OSD_
        • \(PG_
        • \(POOL_
        • \(CACHE_POOL_
        • \(SMALLER_PGP_NUM\)
        • \(OBJECT_
        • \(SLOW_OPS\)
        • \(REQUEST_SLOW\)
        • \(TOO_FEW_PGS\)
        • slow request
        • must scrub before tier agent can activate
      • log-whitelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
      • sha1: 45ece157fc29b55a5ee952c81c0c23abdb89bf98
      • cluster: ceph
    • thrashosds:
      • chance_pgnum_grow: 3
      • chance_pgpnum_fix: 1
      • timeout: 1200
      • sighup_delay: 0.1
      • optrack_toggle_delay: 2.0
      • dump_ops_enable: true
      • noscrub_toggle_delay: 2.0
      • random_eio: 0.0
      • bdev_inject_crash: 2
      • bdev_inject_crash_probability: 0.5
    • exec:
      • client.0:
        • sudo ceph osd erasure-code-profile set myprofile crush-failure-domain=osd m=2 k=2
        • sudo ceph osd pool create base 4 4 erasure myprofile
        • sudo ceph osd pool application enable base rados
        • sudo ceph osd pool set base min_size 2
        • sudo ceph osd pool create cache 4
        • sudo ceph osd tier add base cache
        • sudo ceph osd tier cache-mode cache writeback
        • sudo ceph osd tier set-overlay base cache
        • sudo ceph osd pool set cache hit_set_type bloom
        • sudo ceph osd pool set cache hit_set_count 8
        • sudo ceph osd pool set cache hit_set_period 60
        • sudo ceph osd pool set cache target_max_objects 5000
    • rados:
      • clients:
        • client.0
      • max_seconds: 1200
      • objects: 6600
      • op_weights:
        • copy_from: 50
        • delete: 50
        • read: 100
        • write: 100
      • ops: 10000
      • pools: []
      • size: 1024
  • teuthology_branch: master
  • verbose: True
  • pcp_grafana_url:
  • priority:
  • user:
  • queue:
  • posted: 2022-02-28 21:26:27
  • started: 2022-03-01 05:44:11
  • updated: 2022-03-01 06:21:30
  • status_class: danger
  • runtime: 0:37:19
  • wait_time: 0:10:50