Log: http://qa-proxy.ceph.com/teuthology/teuthology-2018-02-13_02:25:13-upgrade:luminous-x-master-distro-basic-smithi/2185108/teuthology.log

Failure Reason:

"2018-02-13 03:20:52.244860 mon.a mon.0 172.21.15.51:6789/0 2396 : cluster [WRN] Health check failed: 8 slow ops, oldest one blocked for 44 sec (SLOW_OPS)" 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: 13738cd7522a6eea8038660ba7120ca89f34eacb
      • branch: luminous
    • print: **** done install luminous
    • ceph:
    • exec:
      • osd.0:
        • ceph osd require-osd-release luminous
        • ceph osd set-require-min-compat-client luminous
    • print: **** done ceph
    • install.upgrade:
      • osd.0:
    • print: **** done install.upgrade osd.0
    • ceph.restart:
      • daemons:
        • mon.a
        • mon.b
        • mon.c
        • mgr.x
        • osd.0
        • osd.1
        • osd.2
    • print: **** done ceph.restart 1st half
    • parallel:
      • stress-tasks
    • install.upgrade:
      • osd.3:
      • client.0:
    • ceph.restart:
      • daemons:
        • osd.3
        • osd.4
        • osd.5
      • wait-for-healthy: False
      • wait-for-osds-up: True
    • workunit:
      • clients:
        • client.0:
          • rbd/test_librbd_python.sh
      • branch: luminous
    • 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: True
  • pid:
  • duration: 2:28:09
  • owner: scheduled_teuthology@teuthology
  • flavor: basic
  • status_class: danger
  • targets:
    • smithi051.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9niMwPOY3yqO6QwKxykyBnEzH/KmKMaO47A6C+j6LVDzwxuYWOaePf1PvUEQ+Z59W5O1JpkCjobvg0BGLBofwraGI4IH2TEMfqNLPyZKc17YWxdhSDC83+GakLpDLLadVkPA3CnE7K/6SYXwmcwwmzeQqen2p/UtTf9I7+Vw5O/FZA+q4pcXowatcYW+FMcUhM/J5Qk3/5pDR5T2r0GB1NWExpCrLwMuSVRy5eJ4EHyqWh/mOg3Ncl/BiblUrnQ3zy4Ip0098Np01s2PuUc6AyxY95vTt+bygF+6tNy6j3dC2iOpHVpBOSlyL3YhOImJrzepjkmqFruEtb61Y/w4l
    • smithi167.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDNsNZclAWBUKo+/EKCV/A2V76rkofAmXts36XSaMXcenTerKBgyXxNOEx5jqHyOe6lSu7VfYicYICzm1Ha9xjOkBV5b1CXgYU7WzKWZdIeVsBIvSnAO2UgGdWPmUg9wJ2R4vWKO3OFPrejnjUjLOPoGTN3gYbxw4GmKfNQTYKV6wYjgdYx1MRFmS9WiIeCS7+s0uhLprqO1lKy612SH06RJevqt81R2ohKc3CiBBE0G++L7SHZbzfi7a0peEBmovM/Ro4lGXXDg6GS7Y/Wa2Iek3G93g7UCTbMo5+0eVhqckmSloylkQNMFH85hYwrHGlF1fb+H5NOrTlTa7iULdQH
    • smithi027.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCuyh7aC8WY/yb/1kbxz7FWd3GLuLnDHtLuO4L8aOJK31E4ALk/NABwcRDh6dXUK4XI0OBs4nNpu9iMt6YuSNF1pXvBQivl4c+TX8cAjN27BDs5hTRkny05uFU3jFKsgbqjWkA/OwIi/+6xrY19U/xxZ5YL43ZVaf8EBrG0I9QR5L3BV/0L4nQTfhlpl6HJcv/99olIZrgCEDSSJ0BltHZyfWSv7ZFy4MaYidKpACFWPjzyFR0lVsLM1QCsSu/uds+mR/UduM9pCyAMS7we0l5Erg79BP7S0oKOSaXsFd3hN/uHfO7HaPe0UMu64OwPhgIP7LppuAfeXltZbc20YAU/
  • job_id: 2185108
  • log_href: http://qa-proxy.ceph.com/teuthology/teuthology-2018-02-13_02:25:13-upgrade:luminous-x-master-distro-basic-smithi/2185108/teuthology.log
  • suite_branch: luminous
  • wait_time: 0:26:47
  • os_version: 16.04
  • branch: master
  • pcp_grafana_url:
  • email: ceph-qa@ceph.com
  • archive_path: /home/teuthworker/archive/teuthology-2018-02-13_02:25:13-upgrade:luminous-x-master-distro-basic-smithi/2185108
  • updated: 2018-02-13 05:21:44
  • description: upgrade:luminous-x/stress-split/{0-cluster/{openstack.yaml start.yaml} 1-ceph-install/luminous.yaml 2-partial-upgrade/firsthalf.yaml 3-thrash/default.yaml 4-workload/{radosbench.yaml rbd-cls.yaml rbd-import-export.yaml rbd_api.yaml readwrite.yaml snaps-few-objects.yaml} 5-finish-upgrade.yaml 7-final-workload/{rbd-python.yaml rgw-swift.yaml snaps-many-objects.yaml} distros/ubuntu_latest.yaml objectstore/bluestore.yaml thrashosds-health.yaml}
  • started: 2018-02-13 02:26:48
  • last_in_suite: False
  • machine_type: smithi
  • sentry_event:
  • posted: 2018-02-13 02:25:54
  • teuthology_branch: master
  • sha1: 13738cd7522a6eea8038660ba7120ca89f34eacb
  • name: teuthology-2018-02-13_02:25:13-upgrade:luminous-x-master-distro-basic-smithi
  • roles:
    • [u'mon.a', u'mon.b', u'mon.c', u'mgr.x', u'osd.0', u'osd.1', u'osd.2']
    • [u'osd.3', u'osd.4', u'osd.5']
    • [u'client.0']
  • overrides:
    • ceph-deploy:
      • fs: xfs
      • conf:
        • client:
          • log file: /var/log/ceph/ceph-$name.$pid.log
        • mon:
          • osd default pool size: 2
        • osd:
          • mon osd full ratio: 0.9
          • mon osd backfillfull_ratio: 0.85
          • bluestore fsck on mount: True
          • mon osd nearfull ratio: 0.8
          • debug bluestore: 20
          • debug bluefs: 20
          • osd objectstore: bluestore
          • bluestore block size: 96636764160
          • debug rocksdb: 10
          • osd failsafe full ratio: 0.95
      • bluestore: True
    • workunit:
      • sha1: 22c6b1d3d6bbc7ee466fddd3af13bcdb7f0c9a55
      • branch: luminous
    • ceph:
      • log-whitelist:
        • slow request
        • overall HEALTH_
        • \(MON_DOWN\)
        • \(MGR_DOWN\)
        • but it is still running
        • wrongly marked me down
        • objects unfound and apparently lost
        • log bound mismatch
        • overall HEALTH_
        • \(OSDMAP_FLAGS\)
        • \(OSD_
        • \(PG_
        • \(POOL_
        • \(CACHE_POOL_
        • \(SMALLER_PGP_NUM\)
        • \(OBJECT_
        • \(REQUEST_SLOW\)
        • \(TOO_FEW_PGS\)
        • \(MON_DOWN\)
      • fs: xfs
      • conf:
        • global:
          • enable experimental unrecoverable data corrupting features: *
        • mon:
          • debug mon: 20
          • debug paxos: 20
          • mon warn on osd down out interval zero: False
          • debug ms: 1
        • osd:
          • mon osd full ratio: 0.9
          • debug ms: 1
          • debug journal: 20
          • debug osd: 25
          • 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
          • bluestore fsck on mount: True
      • sha1: 13738cd7522a6eea8038660ba7120ca89f34eacb
    • install:
      • ceph:
        • sha1: 13738cd7522a6eea8038660ba7120ca89f34eacb
    • admin_socket:
      • branch: master
    • thrashosds:
      • bdev_inject_crash_probability: 0.5
      • bdev_inject_crash: 2
  • success: False
  • failure_reason: "2018-02-13 03:20:52.244860 mon.a mon.0 172.21.15.51:6789/0 2396 : cluster [WRN] Health check failed: 8 slow ops, oldest one blocked for 44 sec (SLOW_OPS)" in cluster log
  • status: fail
  • nuke_on_error: True
  • os_type: ubuntu
  • runtime: 2:54:56
  • suite_sha1: 22c6b1d3d6bbc7ee466fddd3af13bcdb7f0c9a55