Bug #24129: qa: test_version_splitting (tasks.cephfs.test_sessionmap.TestSessionMap) timeout not long enough - CephFS - Ceph

Actions

Copy link

Bug #24129

closed

qa: test_version_splitting (tasks.cephfs.test_sessionmap.TestSessionMap) timeout not long enough

Added by Patrick Donnelly almost 6 years ago. Updated over 5 years ago.

Status:

Resolved

Priority:

High

Assignee:

Patrick Donnelly

Category:

Target version:

Ceph - v14.0.0

% Done:

Source:

Q/A

Tags:

Backport:

mimic

Regression:

Severity:

3 - minor

Reviewed:

Affected Versions:

ceph-qa-suite:

Component(FS):

qa-suite

Labels (FS):

qa, task(easy)

Pull request ID:

Crash signature (v1):

Crash signature (v2):

Description

2018-05-14T19:07:51.972 INFO:tasks.cephfs_test_runner:======================================================================
2018-05-14T19:07:51.972 INFO:tasks.cephfs_test_runner:ERROR: test_version_splitting (tasks.cephfs.test_sessionmap.TestSessionMap)
2018-05-14T19:07:51.972 INFO:tasks.cephfs_test_runner:----------------------------------------------------------------------
2018-05-14T19:07:51.972 INFO:tasks.cephfs_test_runner:Traceback (most recent call last):
2018-05-14T19:07:51.972 INFO:tasks.cephfs_test_runner:  File "/home/teuthworker/src/git.ceph.com_ceph-c_wip-pdonnell-testing-20180514.164441/qa/tasks/cephfs/test_sessionmap.py", line 151, in test_version_splitting
2018-05-14T19:07:51.973 INFO:tasks.cephfs_test_runner:    timeout=10  # Long enough for an export to get acked
2018-05-14T19:07:51.973 INFO:tasks.cephfs_test_runner:  File "/home/teuthworker/src/git.ceph.com_ceph-c_wip-pdonnell-testing-20180514.164441/qa/tasks/ceph_test_case.py", line 145, in wait_until_true
2018-05-14T19:07:51.973 INFO:tasks.cephfs_test_runner:    raise RuntimeError("Timed out after {0}s".format(elapsed))
2018-05-14T19:07:51.973 INFO:tasks.cephfs_test_runner:RuntimeError: Timed out after 10s

From: /ceph/teuthology-archive/pdonnell-2018-05-14_18:21:44-fs-wip-pdonnell-testing-20180514.164441-testing-basic-smithi/2532176/teuthology.log

Lines from the log:

2018-05-14 19:07:13.090 7fd7a29bc700 10 mds.1.server finish_force_open_sessions on 2 clients, initial v 2
2018-05-14 19:07:13.090 7fd7a29bc700 10 mds.1.server force_open_sessions skipping already-open client.4724 172.21.15.83:0/1165696022
2018-05-14 19:07:13.090 7fd7a29bc700 20 mds.1.sessionmap mark_dirty s=0x557b6ced4300 name=client.4724 v=2
2018-05-14 19:07:13.090 7fd7a29bc700 10 mds.1.server force_open_sessions skipping already-open client.4726 172.21.15.104:0/3568942919
2018-05-14 19:07:13.090 7fd7a29bc700 20 mds.1.sessionmap mark_dirty s=0x557b6ced5b80 name=client.4726 v=3
2018-05-14 19:07:13.090 7fd7a29bc700 10 mds.1.sessionmap save: needv 3, v 3
2018-05-14 19:07:13.090 7fd7a29bc700 20 mds.1.sessionmap  updating keys:
2018-05-14 19:07:13.090 7fd7a29bc700 20 mds.1.sessionmap   client.4724
2018-05-14 19:07:13.090 7fd7a29bc700 20 mds.1.sessionmap  removing keys:
...
2018-05-14 19:07:22.721 7fd7a29bc700 10 MDSIOContextBase::complete: 20C_MDS_session_finish
2018-05-14 19:07:22.721 7fd7a29bc700 10 mds.1.server _session_logged client.4724 172.21.15.83:0/1165696022 state_seq 3 close 5
2018-05-14 19:07:22.721 7fd7a29bc700 20 mds.1.sessionmap mark_dirty s=0x557b6ced4300 name=client.4724 v=4
2018-05-14 19:07:22.721 7fd7a29bc700 10 mds.1.sessionmap save: needv 4, v 4
2018-05-14 19:07:22.721 7fd7a29bc700 20 mds.1.sessionmap  updating keys:
2018-05-14 19:07:22.721 7fd7a29bc700 20 mds.1.sessionmap   client.4726
2018-05-14 19:07:22.721 7fd7a29bc700 20 mds.1.sessionmap  removing keys:

Related issues 2 (0 open — 2 closed)