Log: http://qa-proxy.ceph.com/teuthology/sage-2019-01-11_15:59:52-rados-wip-sage2-testing-2019-01-10-1820-distro-basic-smithi/3448403/teuthology.log

Failure Reason:

"2019-01-12 14:17:06.917238 osd.5 (osd.5) 785 : cluster [WRN] slow request 30.694912 seconds old, received at 2019-01-12 14:16:36.211546: osd_op(client.83055.0:61562 9.f 9:ff4a294d:::benchmark_data_smithi168_34295_object1040:head [delete] snapc 0=[] ondisk+write+known_if_redirected e1530) currently waiting for peered" in cluster log

  • kernel:
    • sha1: distro
    • kdb: True
  • tasks:
    • internal.check_packages:
    • internal.buildpackages_prep:
    • internal.lock_machines:
      • 3
      • 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: 5b7bae9b2ffc4ac18b9bb852fb7366a4557438dc
      • extra_packages:
        • librados2
      • exclude_packages:
        • librados3
      • branch: luminous
    • print: **** done install luminous
    • ceph:
    • print: **** done ceph
    • install.upgrade:
      • osd.0:
    • print: **** done install.upgrade osd.0
    • ceph.restart:
      • daemons:
        • mon.a
        • mon.c
      • wait-for-healthy: False
      • mon-health-to-clog: False
    • ceph.restart:
      • daemons:
        • osd.0
        • osd.1
        • osd.2
      • wait-for-healthy: False
    • print: **** done ceph.restart 1st half
    • parallel:
      • split_tasks
    • install.upgrade:
      • osd.3:
    • ceph.restart:
      • daemons:
        • mon.b
        • mgr.x
        • mgr.y
      • wait-for-healthy: False
      • wait-for-up: True
    • ceph.restart:
      • daemons:
        • osd.3
        • osd.4
        • osd.5
      • wait-for-healthy: False
      • wait-for-up: True
    • ceph.restart:
      • daemons:
        • mds.a
      • wait-for-healthy: False
      • wait-for-up: True
    • install.upgrade:
      • client.0:
    • exec:
      • osd.0:
        • ceph osd require-osd-release nautilus
        • ceph osd set-require-min-compat-client nautilus
    • ceph.healthy:
    • workunit:
      • clients:
        • client.0:
          • rbd/test_librbd_python.sh
      • branch: wip-sage2-testing-2019-01-10-1820
      • sha1: 5b7bae9b2ffc4ac18b9bb852fb7366a4557438dc
    • print: **** done rbd/test_librbd_python.sh 9-workload
    • rgw:
      • client.0:
    • print: **** done rgw 9-workload
    • swift:
      • client.0:
        • rgw_server: client.0
    • print: **** done swift 9-workload
    • rados:
      • op_weights:
        • snap_remove: 50
        • snap_create: 50
        • rollback: 50
        • read: 100
        • write: 100
        • delete: 50
      • clients:
        • client.0
      • write_append_excl: False
      • objects: 500
      • ops: 4000
  • verbose: False
  • pid:
  • duration: 3:20:46
  • owner: scheduled_sage@teuthology
  • flavor: basic
  • status_class: danger
  • targets:
    • smithi156.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCeQRhCy7nRuCWNBarJl7UfLY1HZEy2tEiw/FFVRpkz+Qy6aZmMHOkPvsLuDnqKJ57UrH1s7lSvlLqFkgsvUTRHhgnWsEc9Torf2Yuj0ZQmMNTeFY8PkMp6CsSjjRGEdWa3eVwD03JGJ9GPZmZzMVadrcR83znq3+8GR6nCQdapCuKwd52j0a+2WaNjCuli72R4mLBWiCJ/+NlsEcepaoIbR8PHuIMD/hBd8QQm8j+3Rl0CTbZVhg4R8DSAvGWm48QjADPxRf9+kgjluTkvSXK6SlTLiw85ZPXszycKhhym8WP4pmGsG9LG9lWniWUOoXBhy5rlSS8DbGvPk7XHhAOT
    • smithi174.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9xCJRwrONJ22563fGomkr8pZ4JdYXoXSMJGcHnB0wCDGiU7/CvFG106QrGymRVKmj3rCcuwG/IwrrOOhHpVWbOveXYKnqSL0jmr0ajUqgT5VeOQyrY2+adX5PhTwwC5SNk4jwaf+K+WfFieIE33BPRDiuTvBWWEkg7xmFM172B/ofFmxDKn1Wjg0SvyQygU4sly61fr+qsiyGaGVsOQWd/pt9moHCX+4v2UQ65+vhJwEfHr7N7BJEzw6QRR33XDTnA4I4FNoqE/Yl41a1j2qCodBHhrv5NGqSZzGZVbZWgucwbbzfqjOVXSnMW29hbYuOqrGdPEdjbaaci9T+rSvN
    • smithi168.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDgdyP7GlYWRkttodiepR+pfphqYkyIco2kLW70HOaSfOZCmK2Wggg5tOnDCNqlsgXGo6fUfc9T2hLnPkmgjBtLrKD4oihuuCG3XJmhOuYCgOMVKuNLwFGuDITXSpmZEfXuezO3ncwuas9vCJYUpGxPh7YBA+lST6j4RdAxVa/fT87ybtJ0D6yyrx0K93q5HQm/W5woxLhU3qc94/1tp1cAeSp4Ypem1P0nkyDdQ6HgL+l/CSodnO/XD0ODt6Gw3hBxcdtPqFpXV7Fe1DMC8abx5rMY3B7AunGh/57QzREuepQKhYisMmaPdqJSCyZ4/A/ObldddIIUeREOBUxQ5Wa/
  • job_id: 3448403
  • log_href: http://qa-proxy.ceph.com/teuthology/sage-2019-01-11_15:59:52-rados-wip-sage2-testing-2019-01-10-1820-distro-basic-smithi/3448403/teuthology.log
  • suite_branch: wip-sage2-testing-2019-01-10-1820
  • wait_time: 0:25:18
  • os_version: 7.5
  • branch: wip-sage2-testing-2019-01-10-1820
  • pcp_grafana_url:
  • email:
  • archive_path: /home/teuthworker/archive/sage-2019-01-11_15:59:52-rados-wip-sage2-testing-2019-01-10-1820-distro-basic-smithi/3448403
  • updated: 2019-01-12 16:47:36
  • description: rados/upgrade/luminous-x-singleton/{0-cluster/{openstack.yaml start.yaml} 1-install/luminous.yaml 2-partial-upgrade/firsthalf.yaml 3-thrash/default.yaml 4-workload/{rbd-cls.yaml rbd-import-export.yaml readwrite.yaml snaps-few-objects.yaml} 5-workload/{radosbench.yaml rbd_api.yaml} 6-finish-upgrade.yaml 7-nautilus.yaml 8-workload/{rbd-python.yaml rgw-swift.yaml snaps-many-objects.yaml} supported-random-distro$/{centos_latest.yaml} thrashosds-health.yaml}
  • started: 2019-01-12 13:01:32
  • last_in_suite: False
  • machine_type: smithi
  • sentry_event:
  • posted: 2019-01-11 16:03:32
  • teuthology_branch: master
  • sha1: 5b7bae9b2ffc4ac18b9bb852fb7366a4557438dc
  • name: sage-2019-01-11_15:59:52-rados-wip-sage2-testing-2019-01-10-1820-distro-basic-smithi
  • roles:
    • [u'mon.a', u'mon.c', u'mgr.x', u'mgr.y', u'mds.a', u'osd.0', u'osd.1', u'osd.2']
    • [u'mon.b', u'osd.3', u'osd.4', u'osd.5']
    • [u'client.0']
  • overrides:
    • ceph:
      • mon_bind_addrvec: False
      • log-whitelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • \(MON_DOWN\)
        • \(MGR_DOWN\)
        • but it is still running
        • objects unfound and apparently lost
        • log bound mismatch
        • overall HEALTH_
        • \(FS_DEGRADED\)
        • \(MDS_
        • overall HEALTH_
        • \(OSDMAP_FLAGS\)
        • \(OSD_
        • \(PG_
        • \(POOL_
        • \(CACHE_POOL_
        • \(SMALLER_PGP_NUM\)
        • \(OBJECT_
        • \(SLOW_OPS\)
        • \(REQUEST_SLOW\)
        • \(TOO_FEW_PGS\)
        • slow requests
      • sha1: 5b7bae9b2ffc4ac18b9bb852fb7366a4557438dc
      • fs: xfs
      • mon_bind_msgr2: False
      • conf:
        • mds:
          • debug ms: 1
          • debug mds: 20
        • global:
          • ms dump corrupt message level: 0
        • mon:
          • debug mon: 20
          • debug paxos: 20
          • debug ms: 20
        • osd:
          • debug osd: 25
          • debug filestore: 20
          • debug journal: 20
          • debug ms: 20
    • ceph-deploy:
      • conf:
        • client:
          • log file: /var/log/ceph/ceph-$name.$pid.log
        • mon:
          • osd default pool size: 2
    • workunit:
      • sha1: 5b7bae9b2ffc4ac18b9bb852fb7366a4557438dc
      • branch: wip-sage2-testing-2019-01-10-1820
    • install:
      • ceph:
        • sha1: 5b7bae9b2ffc4ac18b9bb852fb7366a4557438dc
    • admin_socket:
      • branch: wip-sage2-testing-2019-01-10-1820
  • success: False
  • failure_reason: "2019-01-12 14:17:06.917238 osd.5 (osd.5) 785 : cluster [WRN] slow request 30.694912 seconds old, received at 2019-01-12 14:16:36.211546: osd_op(client.83055.0:61562 9.f 9:ff4a294d:::benchmark_data_smithi168_34295_object1040:head [delete] snapc 0=[] ondisk+write+known_if_redirected e1530) currently waiting for peered" in cluster log
  • status: fail
  • nuke_on_error: True
  • os_type: centos
  • runtime: 3:46:04
  • suite_sha1: 5b7bae9b2ffc4ac18b9bb852fb7366a4557438dc