-
Type: Bug
-
Status: Closed (View Workflow)
-
Priority: Major
-
Resolution: Done
-
Affects Version/s: 1.1.0
-
Fix Version/s: 1.2.0
-
Component/s: None
-
Labels:
-
Environment:
commit a31e13471ee626abce2bc43c413fab17586f4fc3
-
Epic Link:
-
Sprint:Cardinal Sprint 1 (3/23-4/3), Cardinal Sprint 4 (5/4-5/15), Cardinal Sprint 5 (5/18-5/22)
event reported from "topology-event" and "topology-events-metrics" mismatched when adding/removing a device:
1) set up one ovs switch (1.3);
2) connect or disconnect the switch from ONOS1 with 3 node cluster;
3) check Device and Graph timestamps from "topology-event" and "topology-event-metrics".
with topology-event:
=================
vent=DeviceEvent{time=2015-03-27T12:48:17.954, type=DEVICE_AVAILABILITY_CHANGED, subject=DefaultDevice{id=of:0000ce102bb8f243, type=SWITCH, manufacturer=Nicira, Inc., hwVersion=Open vSwitch, swVersion=2.3.1, serialNumber=None}}
Event=TopologyEvent{time=2015-03-27T12:48:17.968, type=TOPOLOGY_CHANGED, subject=DefaultTopology{time=870578696290347, creationTime=1427485697966, computeCost=1825449, clusters=0, devices=0, links=0}}
Reason=DeviceEvent{time=2015-03-27T12:48:17.954, type=DEVICE_AVAILABILITY_CHANGED, subject=DefaultDevice{id=of:0000ce102bb8f243, type=SWITCH, manufacturer=Nicira, Inc., hwVersion=Open vSwitch, swVersion=2.3.1, serialNumber=None}}
====================
But with topology-event-metrics:
===================
opology Device Event Timestamp (ms from epoch)=1427485697968
Topology Graph Event Timestamp (ms from epoch)=1427485697968
Topology Device Event Timestamp (ms from epoch)=1427485697956
Topology Graph Event Timestamp (ms from epoch)=1427485697957
Topology Device Event Timestamp (ms from epoch)=1427485697958
Topology Graph Event Timestamp (ms from epoch)=1427485697960
=====================
check ONOS1 Device event timestamps, first method reports: .954; second method report 968. And this mismatch seems only happen on ONOS1, but other nodes seem report the right timing (with some delay).
FYI the disconnect event is triggered and capture with tshark, as following:
35102 1427485430.697874 10.254.1.201 -> 10.254.1.200 TCP 66 6633 > 47233 [FIN, ACK] Seq=837 Ack=2302 Win=37632 Len=0 TSval=217502857 TSecr=304825145