Here is an example from a Zen32/Bond setup. The big button on the Zen32 triggers the light for a fan.
*****
2023-08-01T18:00:35.961698539+00:00 TRACE Zooz ZEN32 Scene Controller Received event with handler unnamed
*****
2023-08-01T18:00:35.962830771+00:00 INFO Zooz ZEN32 Scene Controller <ZwaveDevice: *****> received Z-Wave command: {args={current_value="OFF_DISABLE", duration=0, target_value="OFF_DISABLE", value="OFF_DISABLE"}, cmd_class="SWITCH_BINARY", cmd_id="REPORT", dst_channels={}, encap="S2_AUTH", payload="*****", src_channel=0, version=2}
2023-08-01T18:00:35.986480924+00:00 TRACE Zooz ZEN32 Scene Controller Found ZwaveDispatcher handler in zooz-zen32-scene-controller
2023-08-01T18:00:35.988557628+00:00 INFO Zooz ZEN32 Scene Controller <ZwaveDevice: *****> emitting event: {"attribute_id":"switch","component_id":"main","state":{"value":"off"},"capability_id":"switch"}
2023-08-01T18:00:35.992160762+00:00 DEBUG Zooz ZEN32 Scene Controller ***** device thread event handled
2023-08-01T18:00:36.312633371+00:00 TRACE Zooz ZEN32 Scene Controller Received event with handler unnamed
2023-08-01T18:00:36.314075211+00:00 INFO Zooz ZEN32 Scene Controller <ZwaveDevice: *****> received Z-Wave command: {args={key_attributes="KEY_PRESSED_1_TIME", scene_number=5, sequence_number=6, slow_refresh=true}, cmd_class="CENTRAL_SCENE", cmd_id="NOTIFICATION", dst_channels={}, encap="S2_AUTH", payload="*****", src_channel=0, version=3}
2023-08-01T18:00:36.332230389+00:00 TRACE Zooz ZEN32 Scene Controller Found ZwaveDispatcher handler in zooz-zen32-scene-controller
2023-08-01T18:00:36.385517116+00:00 WARN Zooz ZEN32 Scene Controller ***MESSAGE*** central_scene_notification_handler...
2023-08-01T18:00:36.400850623+00:00 INFO Zooz ZEN32 Scene Controller <ZwaveDevice: *****> emitting event: {"state_change":true,"attribute_id":"button","component_id":"largeButton","state":{"value":"pushed"},"capability_id":"button"}
2023-08-01T18:00:36.405748877+00:00 DEBUG Zooz ZEN32 Scene Controller ***** device thread event handled
*****
2023-08-01T18:00:36.407968143+00:00 TRACE Bond Bridge Received event with handler capability
*****
2023-08-01T18:00:36.446222733+00:00 TRACE Bond Bridge Found CapabilityCommandDispatcher handler in Bond
2023-08-01T18:00:36.447190516+00:00 INFO Bond Bridge <Device: *****> received command: {"positional_args":{},"command":"off","args":{},"component":"light","capability":"switch"}
2023-08-01T18:00:36.456351615+00:00 DEBUG Bond Bridge building socket for rest client
2023-08-01T18:00:36.491677854+00:00 DEBUG Bond Bridge setoption 'keepalive' for rest client
*****
2023-08-01T18:00:36.689073872+00:00 DEBUG Bond Bridge bpup message received ***** 30007 {"B":"*****","d":0,"v":"v3.13.6","t":"devices/*****/state","i":"*****","f":100,"s":200,"m":0,"x":"bond","b":{"power":0,"speed":1,"light":0,"_":"*****","__":"*****"}}
*****
2023-08-01T18:00:36.706255600+00:00 DEBUG Bond Bridge <Device: *****> online
2023-08-01T18:00:36.730973566+00:00 TRACE Bond Bridge Found CapabilityCommandDispatcher handler in Bond
2023-08-01T18:00:36.731985688+00:00 INFO Bond Bridge <Device: *****> emitting event: {"state":{"value":"off"},"component_id":"main","attribute_id":"switch","capability_id":"switch"}
2023-08-01T18:00:36.746147387+00:00 INFO Bond Bridge <Device: *****> emitting event: {"state":{"value":"off"},"component_id":"light","attribute_id":"switch","capability_id":"switch"}
2023-08-01T18:00:36.762830389+00:00 INFO Bond Bridge <Device: *****> emitting event: {"state":{"value":0},"component_id":"main","attribute_id":"fanSpeed","capability_id":"fanSpeed"}
2023-08-01T18:00:36.845864018+00:00 DEBUG Bond Bridge closing socket for rest client
2023-08-01T18:00:36.847346196+00:00 DEBUG Bond Bridge [200/OK] http://*****:80 PUT v2/devices/5d215548/actions/TurnLightOff
2023-08-01T18:00:36.848660687+00:00 DEBUG Bond Bridge Office Fan device thread event handled
*****
2023-08-01T18:00:36.933022142+00:00 DEBUG Bond Bridge bpup message received ***** 30007 {"B":"*****","d":0,"v":"v3.13.6","t":"devices/*****/actions/TurnLightOff","i":"*****","f":132,"s":200,"m":2,"x":"http","b":{"_":"00000000","__":"00000000"}}
*****
Here are the highlights. If you get a combined log of the Zen32 and Bond, you can find similar metrics.
2023-08-01T18:00:35.961698539+00:00 Zen32 gets button press event
+0.44 seconds
2023-08-01T18:00:36.407968143+00:00 Bond driver gets switch off event
+0.28 seconds ← This is what this driver is responsible for. It is the time from receiving the event to sending out the command
2023-08-01T18:00:36.689073872+00:00 Bond switched the light off
+0.25 seconds
2023-08-01T18:00:36.933022142+00:00 Bond round-trips the command and confirms that it succeeded