common, osd: duplicated "start" event in OpTracker, improve OpTracker::dump_ops

[liuchang0812]

1. there are two "started" event in EC osd_op : PrimaryLogPG::do_op, ECBackend::handle_sub_write.

2. no need to sort events in OpHistory::dump_ops_by_duration. We could use OpHistory::duration directly

3. refine OpHistory::dump_ops & OpHistory::dump_ops_by_duration

[

                  {
                        "time": "2018-03-29 17:30:30.343031",
                        "event": "initiated"
                    },  
                    {
                        "time": "2018-03-29 17:30:30.343031",
                        "event": "header_read"
                    },  
                    {
                        "time": "2018-03-29 17:30:30.343035",
                        "event": "throttled"
                    },  
                    {
                        "time": "2018-03-29 17:30:30.345821",
                        "event": "all_read"
                    },  
                    {
                        "time": "2018-03-29 17:30:30.345873",
                        "event": "dispatched"
                    },  
                    {
                        "time": "2018-03-29 17:30:30.345913",
                        "event": "queued_for_pg"
                    },  
                    {
                        "time": "2018-03-29 17:30:31.783523",
                        "event": "reached_pg"
                    },  
                    {
                        "time": "2018-03-29 17:30:31.783610",
                        "event": "started"
                    },  
                    {
                        "time": "2018-03-29 17:30:31.790104",
                        "event": "sub_op_started"          # <-- here is "started" without this patch
                    },  
                    {
                        "time": "2018-03-29 17:30:32.367766",
                        "event": "sub_op_committed"
                    },  
                    {
                        "time": "2018-03-29 17:30:32.367797",
                        "event": "commit_sent"
                    },  
                    {
                        "time": "2018-03-29 17:30:32.367830",
                        "event": "done"
                    }
]

[liuchang0812]

hi, @liewegas mind taking a look?

and, should we add "waiting for subops from" & "receive sub op from" event to ECBackend? thanks

[tchaikov]

• http://pulpito.ceph.com/kchai-2018-04-04_00:32:09-rados-wip-kefu-testing-2018-04-04-0003-distro-basic-mira/
• http://pulpito.ceph.com/kchai-2018-04-07_06:55:51-rados-wip-kefu-testing-2018-04-07-1326-distro-basic-mira/