Log: http://qa-proxy.ceph.com/teuthology/yuriw-2018-07-18_21:43:34-upgrade:luminous-x-mimic-distro-basic-smithi/2796242/teuthology.log

Failure Reason:

"2018-07-19 13:02:52.398730 mon.b mon.0 172.21.15.81:6789/0 112 : cluster [WRN] Health check failed: 1 slow requests are blocked > 32 sec (REQUEST_SLOW)" 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: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
      • branch: luminous
    • print: **** done installing luminous
    • ceph:
      • cluster: ceph
      • log-whitelist:
        • overall HEALTH_
        • \(FS_
        • \(MDS_
        • \(OSD_
        • \(MON_DOWN\)
        • \(CACHE_POOL_
        • \(POOL_
        • \(MGR_DOWN\)
        • \(PG_
        • \(SMALLER_PGP_NUM\)
        • Monitor daemon marked osd
        • Behind on trimming
        • Manager daemon
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • scrub mismatch
        • ScrubResult
        • wrongly marked
        • \(POOL_APP_NOT_ENABLED\)
        • \(SLOW_OPS\)
        • overall HEALTH_
        • reached quota
      • conf:
        • global:
          • mon warn on pool no app: False
          • 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:
          • debug ms: 1
          • debug journal: 20
          • osd_class_load_list: cephfs hello journal lock log numops rbd refcount replica_log rgw sdk statelog timeindex user version
          • debug osd: 25
          • osd objectstore: filestore
          • osd sloppy crc: True
          • osd_class_default_list: cephfs hello journal lock log numops rbd refcount replica_log rgw sdk statelog timeindex user version
          • debug filestore: 20
      • fs: xfs
      • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
    • exec:
      • osd.0:
        • ceph osd require-osd-release luminous
        • ceph osd set-require-min-compat-client luminous
    • print: **** done ceph
    • install.upgrade:
      • mon.a:
      • mon.b:
    • print: **** done install.upgrade both hosts
    • parallel:
      • workload
      • upgrade-sequence
    • print: **** done parallel
    • install.upgrade:
      • client.0:
    • print: **** done install.upgrade on client.0
    • sequential:
      • ceph-fuse:
      • print: **** done ceph-fuse 5-final-workload
      • workunit:
        • clients:
          • client.3:
            • suites/blogbench.sh
        • branch: mimic
        • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
      • print: **** done suites/blogbench.sh 5-final-workload
    • rados:
      • op_weights:
        • snap_remove: 50
        • snap_create: 50
        • rollback: 50
        • read: 100
        • write: 100
        • delete: 50
      • clients:
        • client.1
      • write_append_excl: False
      • objects: 50
      • ops: 4000
    • print: **** done rados 4-final-workload
    • workunit:
      • clients:
        • client.1:
          • rados/load-gen-mix.sh
      • branch: mimic
      • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
    • print: **** done rados/load-gen-mix.sh 4-final-workload
    • mon_thrash:
      • revive_delay: 20
      • thrash_delay: 1
    • print: **** done mon_thrash 4-final-workload
    • workunit:
      • clients:
        • client.1:
          • rados/test.sh
      • branch: luminous
    • print: **** done rados/test.sh 4-final-workload
    • workunit:
      • clients:
        • client.1:
          • cls/test_cls_rbd.sh
      • branch: mimic
      • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
    • print: **** done cls/test_cls_rbd.sh 4-final-workload
    • workunit:
      • clients:
        • client.1:
          • rbd/import_export.sh
      • env:
        • RBD_CREATE_ARGS: --new-format
      • branch: mimic
      • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
    • print: **** done rbd/import_export.sh 4-final-workload
    • sequential:
      • rgw:
        • client.1
      • print: **** done rgw 4-final-workload
      • rgw-final-workload
  • verbose: True
  • pid:
  • duration: 1:43:30
  • owner: scheduled_yuriw@teuthology
  • flavor: basic
  • status_class: danger
  • targets:
    • smithi198.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDfIjLj1FdSsODb2A+VyU7f6qOAeBq9Lz8PegCXxKUcNfFmpbTrU0aUlKyHEWYRm/03RgYEN+32Hgck8EhYU3n9MY7opZiuF6crNBHpHJPFJn3F/vlvpXB2DBbRlWp3Ef/GkFvoI+16nI5M7EvmI6KYFStgBRG612uIHwmTTnmALMeMXfPGtl19qW3U0uMUcPwQZz4PzZ6JgjjaOAEn5dXNkZQvrRALKvsBpkfMfyh9jRYq1pJBJFOICj426+UnbXoIvTa1PoJQXtTJ/m1Oam3Jw4ePfif8lmwQmXj3vgsywUzBcXOMhGTFB33pBmRmSH79jLNJbiZnZB+PRfmU4/0R
    • smithi081.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDNAiByuLAdJE9dSttNSUUtZUdLI7KMZx4h5/fCdzQmgjhmfHnpUDCGSHMtE0FSDFttB++ERwDyOIA4j+SBXOrBBorffUIt1sToRow8/887wt6Gd99hJ79RbsP8Qa8MNsIm/Ly2GdbgXcZkkxs4H5EapIZ2f8jSZW2r2Kucq+nlduJzyXrSHJLqOot5qfEhQTKlS42SXigl78GAbK9TClfYBqMCoipnlo3BdGdTfw9/wpNMlC/prgfE72KjLg6miunTvKNfK/M5EFfUXH5DvJeIi5O4lK4LSODRpRom+DiPkpNElLaVru6JB8m047MV4JaOltbSVAzysa6GUMDGT+bJ
    • smithi160.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDFPU5cfrV/Y4IpGNPH4m4LyaHJi+UUdjI1mn53AkR6W4zmNSqj6il/QHRirgHhSN614VLw3VubKUSix7hxc6+AwzBeIeg0jV/odh8H6cYyBhvJGcch8GlywrIbJhMdZDmFTn3IplsuqVMCqlXhKYgp7F+d0QfotGei3vZ2MTeZvZKIpsWDKDDqNaL45MEVwQuv7EcViFzgQvPflL3iaYSUcXqz+kTzzlaDwHclSTXNJyypG5shYQq4oWSqz8ExP0kwVV7bfsdEoJEMaTOLIr1rd4hiXd2/K8AwWcHq55S3h3gJMbSFrfYTptMP4HWWkPyliXtOmwpikK7HXwnJZrrJ
  • job_id: 2796242
  • log_href: http://qa-proxy.ceph.com/teuthology/yuriw-2018-07-18_21:43:34-upgrade:luminous-x-mimic-distro-basic-smithi/2796242/teuthology.log
  • suite_branch: mimic
  • wait_time: 0:18:32
  • os_version: 18.04
  • branch: mimic
  • pcp_grafana_url:
  • email: ceph-qa@ceph.com
  • archive_path: /home/teuthworker/archive/yuriw-2018-07-18_21:43:34-upgrade:luminous-x-mimic-distro-basic-smithi/2796242
  • updated: 2018-07-19 13:22:20
  • description: upgrade:luminous-x/parallel/{0-cluster/{openstack.yaml start.yaml} 1-ceph-install/luminous.yaml 2-workload/{blogbench.yaml ec-rados-default.yaml rados_api.yaml rados_loadgenbig.yaml rgw_ragweed_prepare.yaml test_rbd_api.yaml test_rbd_python.yaml} 3-upgrade-sequence/upgrade-mon-osd-mds.yaml 5-final-workload/{blogbench.yaml rados-snaps-few-objects.yaml rados_loadgenmix.yaml rados_mon_thrash.yaml rbd_cls.yaml rbd_import_export.yaml rgw.yaml rgw_ragweed_check.yaml rgw_swift.yaml} objectstore/filestore-xfs.yaml supported-all-distro/ubuntu_latest.yaml}
  • started: 2018-07-19 11:20:18
  • last_in_suite: False
  • machine_type: smithi
  • sentry_event:
  • posted: 2018-07-18 21:43:49
  • teuthology_branch: master
  • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
  • name: yuriw-2018-07-18_21:43:34-upgrade:luminous-x-mimic-distro-basic-smithi
  • roles:
    • [u'mon.a', u'mgr.x', u'mds.a', u'osd.0', u'osd.1']
    • [u'mon.b', u'mon.c', u'osd.2', u'osd.3']
    • [u'client.0', u'client.1', u'client.2', u'client.3']
  • overrides:
    • ceph-deploy:
      • fs: xfs
      • filestore: True
      • conf:
        • client:
          • log file: /var/log/ceph/ceph-$name.$pid.log
        • mon:
          • osd default pool size: 2
        • osd:
          • osd sloppy crc: True
          • osd objectstore: filestore
    • rgw:
      • frontend: civetweb
    • workunit:
      • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
      • branch: mimic
    • ceph:
      • log-whitelist:
        • \(MDS_ALL_DOWN\)
        • \(MDS_UP_LESS_THAN_MAX\)
        • scrub mismatch
        • ScrubResult
        • wrongly marked
        • \(POOL_APP_NOT_ENABLED\)
        • \(SLOW_OPS\)
        • overall HEALTH_
        • reached quota
      • 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:
          • debug ms: 1
          • debug journal: 20
          • osd_class_load_list: cephfs hello journal lock log numops rbd refcount replica_log rgw sdk statelog timeindex user version
          • debug osd: 25
          • osd objectstore: filestore
          • osd sloppy crc: True
          • osd_class_default_list: cephfs hello journal lock log numops rbd refcount replica_log rgw sdk statelog timeindex user version
          • debug filestore: 20
      • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
    • install:
      • ceph:
        • sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9
    • admin_socket:
      • branch: mimic
  • success: False
  • failure_reason: "2018-07-19 13:02:52.398730 mon.b mon.0 172.21.15.81:6789/0 112 : cluster [WRN] Health check failed: 1 slow requests are blocked > 32 sec (REQUEST_SLOW)" in cluster log
  • status: fail
  • nuke_on_error: True
  • os_type: ubuntu
  • runtime: 2:02:02
  • suite_sha1: bd3b97c02d495a98271287b1a7c2825d55bfd0f9