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}

Log: http://qa-proxy.ceph.com/teuthology/sage-2019-02-03_00:19:05-rados-wip-sage-testing-2019-02-02-1454-distro-basic-smithi/3542946/teuthology.log

Failure Reason:

"2019-02-03 04:11:10.223313 osd.11 (osd.11) 554 : cluster [WRN] slow request 30.628404 seconds old, received at 2019-02-03 04:10:39.584620: pg_notify((query:1759 sent:1759 10.9( v 1735'3655 lc 0'0 (0'0,1735'3655] local-lis/les=1725/1726 n=3655 ec=1705/1705 lis/c 1725/1705 les/c/f 1726/1706/0 1749/1749/1736))=([1705,1748] intervals=([1705,1724] acting 0,3),([1725,1735] acting 1,3),([1736,1748] acting 3,11)) epoch 1759) currently started" in cluster log

  • log_href: http://qa-proxy.ceph.com/teuthology/sage-2019-02-03_00:19:05-rados-wip-sage-testing-2019-02-02-1454-distro-basic-smithi/3542946/teuthology.log
  • archive_path: /home/teuthworker/archive/sage-2019-02-03_00:19:05-rados-wip-sage-testing-2019-02-02-1454-distro-basic-smithi/3542946
  • 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}
  • duration: 3:14:49
  • email:
  • failure_reason: "2019-02-03 04:11:10.223313 osd.11 (osd.11) 554 : cluster [WRN] slow request 30.628404 seconds old, received at 2019-02-03 04:10:39.584620: pg_notify((query:1759 sent:1759 10.9( v 1735'3655 lc 0'0 (0'0,1735'3655] local-lis/les=1725/1726 n=3655 ec=1705/1705 lis/c 1725/1705 les/c/f 1726/1706/0 1749/1749/1736))=([1705,1748] intervals=([1705,1724] acting 0,3),([1725,1735] acting 1,3),([1736,1748] acting 3,11)) epoch 1759) currently started" in cluster log
  • flavor: basic
  • job_id: 3542946
  • kernel:
    • sha1: distro
    • kdb: True
  • last_in_suite: False
  • machine_type: smithi
  • name: sage-2019-02-03_00:19:05-rados-wip-sage-testing-2019-02-02-1454-distro-basic-smithi
  • nuke_on_error: True
  • os_type: centos
  • os_version: 7.5
  • 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: f1cc03bcbe12493cbfdc1b301eece37c28c645a9
      • fs: xfs
      • mon_bind_msgr2: False
      • conf:
        • mds:
          • debug ms: 1
          • debug mds: 20
        • global:
          • ms dump corrupt message level: 0
          • ms bind msgr2: False
        • 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: f1cc03bcbe12493cbfdc1b301eece37c28c645a9
      • branch: wip-sage-testing-2019-02-02-1454
    • install:
      • ceph:
        • sha1: f1cc03bcbe12493cbfdc1b301eece37c28c645a9
    • admin_socket:
      • branch: wip-sage-testing-2019-02-02-1454
  • owner: scheduled_sage@teuthology
  • pid:
  • roles:
    • ['mon.a', 'mgr.x', 'mds.a', 'osd.0', 'osd.1', 'osd.2', 'osd.3']
    • ['mon.b', 'osd.4', 'osd.5', 'osd.6', 'osd.7']
    • ['mon.c', 'mgr.y', 'osd.8', 'osd.9', 'osd.10', 'osd.11']
    • ['client.0']
  • sentry_event:
  • status: fail
  • success: False
  • branch: wip-sage-testing-2019-02-02-1454
  • seed:
  • sha1: f1cc03bcbe12493cbfdc1b301eece37c28c645a9
  • subset:
  • suite:
  • suite_branch: wip-sage-testing-2019-02-02-1454
  • suite_path:
  • suite_relpath:
  • suite_repo:
  • suite_sha1: f1cc03bcbe12493cbfdc1b301eece37c28c645a9
  • targets:
    • smithi046.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDLkF6a9gHmN9hXnC0OVWxb64rWDEwGW6Z6ikB5PrlkEVr/uJ6Q6eWxLBND+7zj9Lcfvk22GH1GM/HnfQIiv//Ok3nWunkTFmn431Q72zktg3hRWPmAm/DjTviIVr46beLJZt+0c2mspdVKF6KfXESfctBKBS9KkGwLVb+Fb0QreZyX0t4ufn4Lcc3SrLWD5IYKED5AbJefRijZfRa7ZV28LY/3TQqXoxR/l7E2GvGxka5dTuDkGxlo0um95eqoPbAIkCywiAEm1UB+kq/lWh//qDEYic/AizbjWiFXgsq80XZ2C83N5/D/sKODIa5X6IkDiltGLIwjEieyCFGR6Qkt
    • smithi083.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDQ6ywA0el9dOsbM0BxlxQvlH0UWebXBGSioH0VJMVqP+TGJrR6PJsJP4REqBTS1Km4mvPLVQCCBdPJX2Ko4h/asM9u8uYi08z1B02dSXUL7qtUMf/bDI4WFL4HGIBc3lDLtN+wupGJvz9iPY49+mvfrKEAF6kZ5oHecrJECCkXeqpx+3OHuo+mOWHHRRytKf9jNv1Vohyop0l3aNLgm0LhxPC+FTUyyyhJgrE59/yrijYoubEnPWvXKHkSQd9nC1PND4z/ZxYjCLORR9w9gv3TIcwN1EQsB6k2hmb/9Ag5tVh6W0XLPNbh1ezIp9QssKks+4alvwydFNxD3Dz0CxA7
    • smithi014.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDK+lGGyAJt5EfTJhM10TJ7fUhBawqkJzdUI+xEd0XXW7Ak94j9zxoDwGW3yoz03/E2PxUaGjbN/MPmM2XUze+FsdJbO8nS2mhOOH9/qubW+pIE9BKENQ7fH+4y89I7PnCWU3gTbShK8FF+fJ2uhAxQjrTGvj54r67UVh43tNuz7CqMg5VOyhTJFPteYibsVJXr21ti+PAks99a8NWQR1YmMLEi/8UT7u/CHeYgHr1BCBwlLnyOU9bESc+8BcjRaNvYQlQe6xJc2X0YNEImaP8FNU5/YU71vK10wl62MwGz6jm8rst5yuv8AyTn+ggWdH9mI7Y/JbL6F006PJ+IKG7P
    • smithi061.front.sepia.ceph.com: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDRVt8hO5r1ixdt8AuL71fWqKTaOiHLQx1cfTcI9UTwSSdLNMjlcJDBc4hBJPOyncOt4DU/nYJPpxr9V6jxKwFSUW0SbOzTy7BM5+BSiXNl146xWDJhdNUJeODkDsw+zAwAq/9KvVdLcD+vHKG2Bab5rwRkA+IFphCPjbzajxvO2MUG8URp+S6XxGkVkLAFMG3Y7SQZOh66XpZQBCHs6WnXA/viQAU92EG7fr3HtuLhFz91DlvtjcGb1016+fU9EWiFYTagnJwZv95nwljGg+GkPQ7bR3kejKo9VpueBXi3hRk+EcZs9UVmQ7ndIB+tmufJkLkYC/aePidmyYWkcA8r
  • tasks:
    • internal.check_packages:
    • internal.buildpackages_prep:
    • internal.lock_machines:
      • 4
      • 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: f1cc03bcbe12493cbfdc1b301eece37c28c645a9
      • extra_packages:
        • librados2
      • exclude_packages:
        • librados3
      • branch: luminous
    • print: **** done install luminous
    • ceph:
    • print: **** done ceph
    • install.upgrade:
      • mon.a:
      • mon.b:
    • print: **** done install.upgrade osd.0
    • ceph.restart:
      • daemons:
        • mon.a
        • mon.b
      • wait-for-healthy: False
      • mon-health-to-clog: False
    • ceph.restart:
      • daemons:
        • osd.0
        • osd.1
        • osd.2
        • osd.3
        • osd.4
        • osd.5
        • osd.6
        • osd.7
      • wait-for-healthy: False
    • print: **** done ceph.restart 1st 2/3s
    • parallel:
      • split_tasks
    • install.upgrade:
      • mon.c:
    • ceph.restart:
      • daemons:
        • mon.c
        • mgr.x
        • mgr.y
      • wait-for-healthy: False
      • wait-for-up: True
    • ceph.restart:
      • daemons:
        • osd.8
        • osd.9
        • osd.10
        • osd.11
      • wait-for-healthy: False
      • wait-for-up: True
    • ceph.restart:
      • daemons:
        • mds.a
      • wait-for-healthy: False
      • wait-for-up: True
    • exec:
      • mon.a:
        • ceph mon enable-msgr2
    • 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-sage-testing-2019-02-02-1454
      • sha1: f1cc03bcbe12493cbfdc1b301eece37c28c645a9
    • 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
  • teuthology_branch: master
  • verbose: False
  • pcp_grafana_url:
  • priority:
  • user:
  • queue:
  • posted: 2019-02-03 00:21:30
  • started: 2019-02-03 02:37:56
  • updated: 2019-02-03 06:34:00
  • status_class: danger
  • runtime: 3:56:04
  • wait_time: 0:41:15