Project

General

Profile

Bug #15153

when tacking an OpRequest, the time stamp of "dispatched" event is 0

Added by chuanhong wang about 8 years ago. Updated about 8 years ago.

Status:
Closed
Priority:
Normal
Assignee:
-
Category:
-
Target version:
-
% Done:

0%

Source:
other
Tags:
Backport:
Regression:
No
Severity:
3 - minor
Reviewed:
Affected Versions:
ceph-qa-suite:
Pull request ID:
Crash signature (v1):
Crash signature (v2):

Description

software: ceph-0.94.5+centos7
bug: the time stamp of "dispatched" event is 0, and the log listed as below.

2016-03-15 18:08:47.770223 7f5c99db1700 5 -- op tracker -- seq: 101899, time: 0.000000, event: dispatched, op: osd_repop(client.13938058.0:13708 1.319 85ebbb19/rbd_data.164a402ae8944a.000000000000f5f0/head//1 v 12638'75259)
2016-03-15 18:08:47.770552 7f5cbebb8700 5 -- op tracker -- seq: 101885, time: 2016-03-15 18:08:47.770551, event: journaled_completion_queued, op: osd_op(client.13938058.0:13655 rbd_data.164a402ae8944a.000000000000d7a0 [set-alloc-hint object_size 4194304 write_size 4194304,write 438272~4096] 1.4cf9af6d ack+ondisk+write+known_if_redirected e12638)
2016-03-15 18:08:47.770566 7f5ca80ec700 5 -- op tracker -- seq: 101897, time: 2016-03-15 18:08:47.770565, event: started, op: osd_repop(client.13938037.0:16406 1.2fd 5429befd/rbd_data.164a402ae8944a.000000000000db0d/head//1 v 12638'80535)
2016-03-15 18:08:47.770787 7f5ca60e8700 5 -- op tracker -- seq: 101893, time: 2016-03-15 18:08:47.770787, event: started, op: osd_repop(client.13938034.0:15792 1.379 65960f79/rbd_data.164a402ae8944a.0000000000002f61/head//1 v 12638'132245)
2016-03-15 18:08:47.770975 7f5cb93ad700 5 -- op tracker -- seq: 101885, time: 2016-03-15 18:08:47.770975, event: op_commit, op: osd_op(client.13938058.0:13655 rbd_data.164a402ae8944a.000000000000d7a0 [set-alloc-hint object_size 4194304 write_size 4194304,write 438272~4096] 1.4cf9af6d ack+ondisk+write+known_if_redirected e12638)
2016-03-15 18:08:47.771058 7f5c9a9bd700 5 -- op tracker -- seq: 101900, time: 2016-03-15 18:08:47.770943, event: header_read, op: osd_repop_reply(client.13938058.0:13655 1.36d ondisk, result = 0)
2016-03-15 18:08:47.771068 7f5c9a9bd700 5 -- op tracker -- seq: 101900, time: 2016-03-15 18:08:47.770945, event: throttled, op: osd_repop_reply(client.13938058.0:13655 1.36d ondisk, result = 0)
2016-03-15 18:08:47.771076 7f5c9a9bd700 5 -- op tracker -- seq: 101900, time: 2016-03-15 18:08:47.771029, event: all_read, op: osd_repop_reply(client.13938058.0:13655 1.36d ondisk, result = 0)
2016-03-15 18:08:47.771158 7f5c9a9bd700 5 -- op tracker -- seq: 101900, time: 0.000000, event: dispatched, op: osd_repop_reply(client.13938058.0:13655 1.36d ondisk, result = 0)

History

#1 Updated by chuanhong wang about 8 years ago

This issue does not exist in the latest Ceph source code. Please help me close it.
Someone has changed the function "Messenger::ms_fast_dispatch", and updated the dispatch time stamp.

#2 Updated by Nathan Cutler about 8 years ago

  • Status changed from New to Closed

Also available in: Atom PDF