eclipse-cyclonedds / cyclonedds

Eclipse Cyclone DDS project
https://projects.eclipse.org/projects/iot.cyclonedds
Other
877 stars 360 forks source link

QoS issue when subscribing to OpenDDS publisher #1544

Open smnrgrs opened 1 year ago

smnrgrs commented 1 year ago

Testing with the i11eperf source, with history = KEEP_ALL, 'cyclonedds ls' indicates History.KeepLast for opub. Opub-> csub comms fails.

Is there an issue with cyclonedds ls decoding of QoS from OpenDDS?

eboasson commented 1 year ago

cyclonedds ls indicating "keep last" is not surprising to me because I think the history QoS is often left out of the discovery information because it is not needed for reader/writer matching. If OpenDDS indeed left it out, then the "keep last" is simply the default being applied, as per the DDSI spec.

Looking at the specs, it is part of PublicationBuiltinTopicData and SubscriptionBuiltinTopicData and I can't immediately locate the place in the DDSI spec where it says it can be omitted. But before we start pointing fingers, perhaps we should make sure that it really is left out, and look at the spec a little bit more carefully 😁

Since opub -> csub failing is really interesting ... would you be able to get a Cyclone trace of the csub side. No guarantee that it provides enough information, but it might. (Or you could have a quick look at Wireshark, sometimes that is enough already.)

smnrgrs commented 1 year ago

Log from csub below. osub says: (51434|51437) Sedp::match_continue - WARNING Data types of topic Data does not match (inconsistent)

1674226239.584314 [0]       csub: config: Domain/General/MulticastRecvNetworkInterfaceAddresses/#text: preferred {}
1674226239.584337 [0]       csub: config: Domain/General/ExternalNetworkAddress/#text: auto {}
1674226239.584342 [0]       csub: config: Domain/General/ExternalNetworkMask/#text: 0.0.0.0 {}
1674226239.584346 [0]       csub: config: Domain/General/AllowMulticast/#text: default {}
1674226239.584350 [0]       csub: config: Domain/General/MulticastTimeToLive/#text: 32 {}
1674226239.584353 [0]       csub: config: Domain/General/DontRoute/#text: false {}
1674226239.584357 [0]       csub: config: Domain/General/Transport/#text: udp {}
1674226239.584361 [0]       csub: config: Domain/General/EnableMulticastLoopback/#text: true {}
1674226239.584365 [0]       csub: config: Domain/General/MaxMessageSize/#text: 14720 B {}
1674226239.584369 [0]       csub: config: Domain/General/MaxRexmitMessageSize/#text: 1456 B {}
1674226239.584373 [0]       csub: config: Domain/General/FragmentSize/#text: 1344 B {}
1674226239.584376 [0]       csub: config: Domain/General/RedundantNetworking/#text: false {}
1674226239.584380 [0]       csub: config: Domain/General/EntityAutoNaming/#text: empty {}
1674226239.584384 [0]       csub: config: Domain/General/EntityAutoNaming[@seed]: 2404167308 3025537174 3034303842 2462688384 3384864606 1795436304 393768017 4201071084 {}
1674226239.584390 [0]       csub: config: Domain/Sizing/ReceiveBufferSize/#text: 1 MiB {}
1674226239.584393 [0]       csub: config: Domain/Sizing/ReceiveBufferChunkSize/#text: 128 KiB {}
1674226239.584397 [0]       csub: config: Domain/Compatibility/StandardsConformance/#text: lax {}
1674226239.584401 [0]       csub: config: Domain/Compatibility/ExplicitlyPublishQosSetToDefault/#text: false {}
1674226239.584405 [0]       csub: config: Domain/Compatibility/ManySocketsMode/#text: single {}
1674226239.584408 [0]       csub: config: Domain/Compatibility/AssumeRtiHasPmdEndpoints/#text: false {}
1674226239.584412 [0]       csub: config: Domain/Discovery/Tag/#text:  {}
1674226239.584416 [0]       csub: config: Domain/Discovery/ExternalDomainId/#text: 0 {}
1674226239.584420 [0]       csub: config: Domain/Discovery/DSGracePeriod/#text: 30 s {}
1674226239.584424 [0]       csub: config: Domain/Discovery/ParticipantIndex/#text: none {}
1674226239.584428 [0]       csub: config: Domain/Discovery/MaxAutoParticipantIndex/#text: 9 {}
1674226239.584431 [0]       csub: config: Domain/Discovery/SPDPMulticastAddress/#text: 239.255.0.1 {}
1674226239.584435 [0]       csub: config: Domain/Discovery/SPDPInterval/#text: 30 s {}
1674226239.584439 [0]       csub: config: Domain/Discovery/DefaultMulticastAddress/#text: auto {}
1674226239.584443 [0]       csub: config: Domain/Discovery/Ports/Base/#text: 7400 {}
1674226239.584447 [0]       csub: config: Domain/Discovery/Ports/DomainGain/#text: 250 {}
1674226239.584451 [0]       csub: config: Domain/Discovery/Ports/ParticipantGain/#text: 2 {}
1674226239.584454 [0]       csub: config: Domain/Discovery/Ports/MulticastMetaOffset/#text: 0 {}
1674226239.584458 [0]       csub: config: Domain/Discovery/Ports/UnicastMetaOffset/#text: 10 {}
1674226239.584462 [0]       csub: config: Domain/Discovery/Ports/MulticastDataOffset/#text: 1 {}
1674226239.584466 [0]       csub: config: Domain/Discovery/Ports/UnicastDataOffset/#text: 11 {}
1674226239.584469 [0]       csub: config: Domain/Discovery/EnableTopicDiscoveryEndpoints/#text: false {}
1674226239.584473 [0]       csub: config: Domain/Discovery/LeaseDuration/#text: 10 s {}
1674226239.584481 [0]       csub: config: Domain/Tracing/Category/#text: fatal,error,warning,info,config,discovery,throttle {1}
1674226239.584485 [0]       csub: config: Domain/Tracing/OutputFile/#text: cdds.log.51426 {1}
1674226239.584489 [0]       csub: config: Domain/Tracing/AppendToFile/#text: false {}
1674226239.584493 [0]       csub: config: Domain/Tracing/PacketCaptureFile/#text:  {}
1674226239.584497 [0]       csub: config: Domain/Internal/DeliveryQueueMaxSamples/#text: 256 {}
1674226239.584500 [0]       csub: config: Domain/Internal/PrimaryReorderMaxSamples/#text: 128 {}
1674226239.584510 [0]       csub: config: Domain/Internal/SecondaryReorderMaxSamples/#text: 128 {}
1674226239.584529 [0]       csub: config: Domain/Internal/DefragUnreliableMaxSamples/#text: 4 {}
1674226239.584536 [0]       csub: config: Domain/Internal/DefragReliableMaxSamples/#text: 16 {}
1674226239.584540 [0]       csub: config: Domain/Internal/BuiltinEndpointSet/#text: writers {}
1674226239.584544 [0]       csub: config: Domain/Internal/MeasureHbToAckLatency/#text: false {}
1674226239.584548 [0]       csub: config: Domain/Internal/UnicastResponseToSPDPMessages/#text: true {}
1674226239.584551 [0]       csub: config: Domain/Internal/SynchronousDeliveryPriorityThreshold/#text: 0 {}
1674226239.584555 [0]       csub: config: Domain/Internal/SynchronousDeliveryLatencyBound/#text: inf {}
1674226239.584559 [0]       csub: config: Domain/Internal/MaxParticipants/#text: 0 {}
1674226239.584562 [0]       csub: config: Domain/Internal/AccelerateRexmitBlockSize/#text: 0 {}
1674226239.584566 [0]       csub: config: Domain/Internal/RetransmitMerging/#text: never {}
1674226239.584570 [0]       csub: config: Domain/Internal/RetransmitMergingPeriod/#text: 5 ms {}
1674226239.584574 [0]       csub: config: Domain/Internal/HeartbeatInterval/#text: 100 ms {}
1674226239.584577 [0]       csub: config: Domain/Internal/HeartbeatInterval[@min]: 5 ms {}
1674226239.584581 [0]       csub: config: Domain/Internal/HeartbeatInterval[@minsched]: 20 ms {}
1674226239.584585 [0]       csub: config: Domain/Internal/HeartbeatInterval[@max]: 8 s {}
1674226239.584589 [0]       csub: config: Domain/Internal/MaxQueuedRexmitBytes/#text: 512 KiB {}
1674226239.584593 [0]       csub: config: Domain/Internal/MaxQueuedRexmitMessages/#text: 200 {}
1674226239.584596 [0]       csub: config: Domain/Internal/WriterLingerDuration/#text: 1 s {}
1674226239.584600 [0]       csub: config: Domain/Internal/SocketReceiveBufferSize[@min]: default {}
1674226239.584604 [0]       csub: config: Domain/Internal/SocketReceiveBufferSize[@max]: default {}
1674226239.584608 [0]       csub: config: Domain/Internal/SocketSendBufferSize[@min]: 64 KiB {}
1674226239.584612 [0]       csub: config: Domain/Internal/SocketSendBufferSize[@max]: default {}
1674226239.584615 [0]       csub: config: Domain/Internal/NackDelay/#text: 100 ms {}
1674226239.584619 [0]       csub: config: Domain/Internal/AckDelay/#text: 10 ms {}
1674226239.584623 [0]       csub: config: Domain/Internal/AutoReschedNackDelay/#text: 3 s {}
1674226239.584627 [0]       csub: config: Domain/Internal/PreEmptiveAckDelay/#text: 10 ms {}
1674226239.584631 [0]       csub: config: Domain/Internal/ScheduleTimeRounding/#text: 0 s {}
1674226239.584634 [0]       csub: config: Domain/Internal/DDSI2DirectMaxThreads/#text: 1 {}
1674226239.584638 [0]       csub: config: Domain/Internal/SquashParticipants/#text: false {}
1674226239.584642 [0]       csub: config: Domain/Internal/SPDPResponseMaxDelay/#text: 0 s {}
1674226239.584645 [0]       csub: config: Domain/Internal/LateAckMode/#text: false {}
1674226239.584649 [0]       csub: config: Domain/Internal/RetryOnRejectBestEffort/#text: false {}
1674226239.584652 [0]       csub: config: Domain/Internal/GenerateKeyhash/#text: false {}
1674226239.584656 [0]       csub: config: Domain/Internal/MaxSampleSize/#text: 2147483647 B {}
1674226239.584660 [0]       csub: config: Domain/Internal/WriteBatch/#text: false {}
1674226239.584664 [0]       csub: config: Domain/Internal/LivelinessMonitoring/#text: false {}
1674226239.584667 [0]       csub: config: Domain/Internal/LivelinessMonitoring[@StackTraces]: true {}
1674226239.584671 [0]       csub: config: Domain/Internal/LivelinessMonitoring[@Interval]: 1 s {}
1674226239.584675 [0]       csub: config: Domain/Internal/MonitorPort/#text: -1 {}
1674226239.584678 [0]       csub: config: Domain/Internal/PrioritizeRetransmit/#text: true {}
1674226239.584682 [0]       csub: config: Domain/Internal/UseMulticastIfMreqn/#text: 0 {}
1674226239.584686 [0]       csub: config: Domain/Internal/RediscoveryBlacklistDuration/#text: 0 s {}
1674226239.584690 [0]       csub: config: Domain/Internal/RediscoveryBlacklistDuration[@enforce]: false {}
1674226239.584698 [0]       csub: config: Domain/Internal/MultipleReceiveThreads/#text: default {}
1674226239.584702 [0]       csub: config: Domain/Internal/MultipleReceiveThreads[@maxretries]: 4294967295 {}
1674226239.584706 [0]       csub: config: Domain/Internal/Test/XmitLossiness/#text: 0 {}
1674226239.584710 [0]       csub: config: Domain/Internal/Watermarks/WhcLow/#text: 1 KiB {}
1674226239.584714 [0]       csub: config: Domain/Internal/Watermarks/WhcHigh/#text: 500 KiB {}
1674226239.584718 [0]       csub: config: Domain/Internal/Watermarks/WhcHighInit/#text: 30 KiB {}
1674226239.584722 [0]       csub: config: Domain/Internal/Watermarks/WhcAdaptive/#text: true {}
1674226239.584726 [0]       csub: config: Domain/Internal/BurstSize/MaxRexmit/#text: 1 MiB {}
1674226239.584730 [0]       csub: config: Domain/Internal/BurstSize/MaxInitTransmit/#text: 4294967295 {}
1674226239.584734 [0]       csub: config: Domain/Internal/EnableExpensiveChecks/#text:  [ignored] {}
1674226239.584738 [0]       csub: config: Domain/TCP/NoDelay/#text: true {}
1674226239.584742 [0]       csub: config: Domain/TCP/Port/#text: -1 {}
1674226239.584745 [0]       csub: config: Domain/TCP/ReadTimeout/#text: 2 s {}
1674226239.584749 [0]       csub: config: Domain/TCP/WriteTimeout/#text: 2 s {}
1674226239.584753 [0]       csub: config: Domain/TCP/AlwaysUsePeeraddrForUnicast/#text: false {}
1674226239.584757 [0]       csub: config: Domain/SSL/Enable/#text: false {}
1674226239.584761 [0]       csub: config: Domain/SSL/CertificateVerification/#text: true {}
1674226239.584764 [0]       csub: config: Domain/SSL/VerifyClient/#text: true {}
1674226239.584768 [0]       csub: config: Domain/SSL/SelfSignedCertificates/#text: false {}
1674226239.584772 [0]       csub: config: Domain/SSL/KeystoreFile/#text: keystore {}
1674226239.584776 [0]       csub: config: Domain/SSL/KeyPassphrase/#text: secret {}
1674226239.584779 [0]       csub: config: Domain/SSL/Ciphers/#text: ALL:!ADH:!LOW:!EXP:!MD5:@STRENGTH {}
1674226239.584783 [0]       csub: config: Domain/SSL/EntropyFile/#text:  {}
1674226239.584787 [0]       csub: config: Domain/SSL/MinimumTLSVersion/#text: 1.3 {}
1674226239.584791 [0]       csub: config: Domain[@Id]: 0 {1}
1674226239.584831 [0]       csub: started at 1674226239.06584796 -- 2023-01-20 14:50:39+00:00
1674226239.584836 [0]       csub: udp initialized
1674226239.585009 [0]       csub: interfaces: lo udp/127.0.0.1(q1) eth0 udp/192.168.234.128(q9) docker0 udp/172.17.0.1(q9)
1674226239.585015 [0]       csub: using network interface eth0 (udp/192.168.234.128) selected arbitrarily from: eth0, docker0
1674226239.585049 [0]       csub: selected interfaces: eth0 (index 2 priority 0)
1674226239.585053 [0]       csub: presumed robust multicast support, use for everything
1674226239.585068 [0]       csub: ownip: udp/192.168.234.128
1674226239.585072 [0]       csub: extmask: invalid/0 (not applicable)
1674226239.585075 [0]       csub: SPDP MC: udp/239.255.0.1
1674226239.585078 [0]       csub: default MC: udp/239.255.0.1
1674226239.585081 [0]       csub: SSM support included
1674226239.585188 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674226239.585199 [0]       csub: rtps_init: uc ports: disc 0 data 0
1674226239.585203 [0]       csub: rtps_init: domainid 0 participantid -2
1674226239.585206 [0]       csub: Unicast Ports: discovery 33487 data 33487
1674226239.585216 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674226239.585230 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674226239.585237 [0]       csub: Multicast Ports: discovery 7400 data 7401 
1674226239.585245 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674226239.585258 [0]       csub: interface eth0: transmit port 51501
1674226239.585297 [0]       csub: ddsi_new_local_orphan_writer(DCPSParticipant/org::eclipse::cyclonedds::builtin::DCPSParticipant)
1674226239.585325 [0]       csub: WRITER 0:0:0:100c2 QOS={user_data=0<>,topic_name="DCPSParticipant",type_name="org::eclipse::cyclonedds::builtin::DCPSParticipant",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.585333 [0]       csub: match_writer_with_readers(wr 0:0:0:100c2) scanning all rds of topic DCPSParticipant
1674226239.585337 [0]       csub: ddsi_new_local_orphan_writer(DCPSTopic/org::eclipse::cyclonedds::builtin::DCPSTopic)
1674226239.585349 [0]       csub: WRITER 0:0:0:2c2 QOS={user_data=0<>,topic_name="DCPSTopic",type_name="org::eclipse::cyclonedds::builtin::DCPSTopic",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.585353 [0]       csub: match_writer_with_readers(wr 0:0:0:2c2) scanning all rds of topic DCPSTopic
1674226239.585357 [0]       csub: ddsi_new_local_orphan_writer(DCPSPublication/org::eclipse::cyclonedds::builtin::DCPSPublication)
1674226239.585368 [0]       csub: WRITER 0:0:0:3c2 QOS={user_data=0<>,topic_name="DCPSPublication",type_name="org::eclipse::cyclonedds::builtin::DCPSPublication",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.585372 [0]       csub: match_writer_with_readers(wr 0:0:0:3c2) scanning all rds of topic DCPSPublication
1674226239.585375 [0]       csub: ddsi_new_local_orphan_writer(DCPSSubscription/org::eclipse::cyclonedds::builtin::DCPSSubscription)
1674226239.585388 [0]       csub: WRITER 0:0:0:4c2 QOS={user_data=0<>,topic_name="DCPSSubscription",type_name="org::eclipse::cyclonedds::builtin::DCPSSubscription",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.585392 [0]       csub: match_writer_with_readers(wr 0:0:0:4c2) scanning all rds of topic DCPSSubscription
1674226239.585839 [0]       csub: ddsi_new_participant(110b80d:483173fd:adcdf2d0:1c1, 0)
1674226239.585877 [0]       csub: PARTICIPANT 110b80d:483173fd:adcdf2d0:1c1 QOS={user_data=0<>,liveliness=0:10000000000,property_list={1:"__ProcessName":"csub",1:"__Pid":"51426",1:"__Hostname":"simon-Virtual-Machine"}:{},adlink_entity_factory=0}
1674226239.585893 [0]       csub: new_writer(guid 110b80d:483173fd:adcdf2d0:100c2, (null).DCPSParticipant/ParticipantBuiltinTopicData)
1674226239.585899 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:100c2 @ 0x560dc9e53264) user 1 builtin 1
1674226239.585914 [0]       csub: WRITER 110b80d:483173fd:adcdf2d0:100c2 QOS={user_data=0<>,topic_name="DCPSParticipant",type_name="ParticipantBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:0,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_writer_data_lifecycle=1,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.585924 [0]       csub: match_writer_with_proxy_readers(wr 110b80d:483173fd:adcdf2d0:100c2) scanning proxy participants tgt=0
1674226239.585930 [0]       csub: new_writer(guid 110b80d:483173fd:adcdf2d0:4c2, (null).DCPSSubscription/SubscriptionBuiltinTopicData)
1674226239.585934 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:4c2 @ 0x560dc9e53a74) user 1 builtin 2
1674226239.585946 [0]       csub: WRITER 110b80d:483173fd:adcdf2d0:4c2 QOS={user_data=0<>,topic_name="DCPSSubscription",type_name="SubscriptionBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.585950 [0]       csub: match_writer_with_proxy_readers(wr 110b80d:483173fd:adcdf2d0:4c2) scanning proxy participants tgt=4c7
1674226239.585957 [0]       csub: new_writer(guid 110b80d:483173fd:adcdf2d0:3c2, (null).DCPSPublication/PublicationBuiltinTopicData)
1674226239.585962 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:3c2 @ 0x560dc9e54294) user 1 builtin 3
1674226239.585972 [0]       csub: WRITER 110b80d:483173fd:adcdf2d0:3c2 QOS={user_data=0<>,topic_name="DCPSPublication",type_name="PublicationBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.585977 [0]       csub: match_writer_with_proxy_readers(wr 110b80d:483173fd:adcdf2d0:3c2) scanning proxy participants tgt=3c7
1674226239.585981 [0]       csub: new_writer(guid 110b80d:483173fd:adcdf2d0:200c2, (null).DCPSParticipantMessage/ParticipantMessageData)
1674226239.585986 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:200c2 @ 0x560dc9e54b14) user 1 builtin 4
1674226239.585996 [0]       csub: WRITER 110b80d:483173fd:adcdf2d0:200c2 QOS={user_data=0<>,topic_name="DCPSParticipantMessage",type_name="ParticipantMessageData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.586003 [0]       csub: match_writer_with_proxy_readers(wr 110b80d:483173fd:adcdf2d0:200c2) scanning proxy participants tgt=200c7
1674226239.586007 [0]       csub: new_writer(guid 110b80d:483173fd:adcdf2d0:300c3, (null).DCPSTypeLookupRequest/DDS::Builtin::TypeLookup_Request)
1674226239.586011 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:300c3 @ 0x560dc9e553a4) user 1 builtin 5
1674226239.586023 [0]       csub: WRITER 110b80d:483173fd:adcdf2d0:300c3 QOS={user_data=0<>,topic_name="DCPSTypeLookupRequest",type_name="DDS::Builtin::TypeLookup_Request",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.586030 [0]       csub: match_writer_with_proxy_readers(wr 110b80d:483173fd:adcdf2d0:300c3) scanning proxy participants tgt=300c4
1674226239.586035 [0]       csub: new_writer(guid 110b80d:483173fd:adcdf2d0:301c3, (null).DCPSTypeLookupReply/DDS::Builtin::TypeLookup_Reply)
1674226239.586039 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:301c3 @ 0x560dc9e55c24) user 1 builtin 6
1674226239.586053 [0]       csub: WRITER 110b80d:483173fd:adcdf2d0:301c3 QOS={user_data=0<>,topic_name="DCPSTypeLookupReply",type_name="DDS::Builtin::TypeLookup_Reply",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674226239.586058 [0]       csub: match_writer_with_proxy_readers(wr 110b80d:483173fd:adcdf2d0:301c3) scanning proxy participants tgt=301c4
1674226239.586062 [0]       csub: new_reader(guid 110b80d:483173fd:adcdf2d0:100c7, (null).DCPSParticipant/ParticipantBuiltinTopicData)
1674226239.586066 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:100c7 @ 0x560dc9e561b4) user 1 builtin 7
1674226239.586079 [0]       csub: READER 110b80d:483173fd:adcdf2d0:100c7 QOS={user_data=0<>,topic_name="DCPSParticipant",type_name="ParticipantBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:0,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_writer_data_lifecycle=1,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.586084 [0]       csub: match_reader_with_proxy_writers(rd 110b80d:483173fd:adcdf2d0:100c7) scanning proxy participants tgt=0
1674226239.586088 [0]       csub: new_reader(guid 110b80d:483173fd:adcdf2d0:4c7, (null).DCPSSubscription/SubscriptionBuiltinTopicData)
1674226239.586092 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:4c7 @ 0x560dc9e56504) user 1 builtin 8
1674226239.586103 [0]       csub: READER 110b80d:483173fd:adcdf2d0:4c7 QOS={user_data=0<>,topic_name="DCPSSubscription",type_name="SubscriptionBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.586107 [0]       csub: match_reader_with_proxy_writers(rd 110b80d:483173fd:adcdf2d0:4c7) scanning proxy participants tgt=4c2
1674226239.586111 [0]       csub: new_reader(guid 110b80d:483173fd:adcdf2d0:3c7, (null).DCPSPublication/PublicationBuiltinTopicData)
1674226239.586118 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:3c7 @ 0x560dc9e56874) user 1 builtin 9
1674226239.586128 [0]       csub: READER 110b80d:483173fd:adcdf2d0:3c7 QOS={user_data=0<>,topic_name="DCPSPublication",type_name="PublicationBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.586133 [0]       csub: match_reader_with_proxy_writers(rd 110b80d:483173fd:adcdf2d0:3c7) scanning proxy participants tgt=3c2
1674226239.586136 [0]       csub: new_reader(guid 110b80d:483173fd:adcdf2d0:200c7, (null).DCPSParticipantMessage/ParticipantMessageData)
1674226239.586140 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:200c7 @ 0x560dc9e56be4) user 1 builtin 10
1674226239.586151 [0]       csub: READER 110b80d:483173fd:adcdf2d0:200c7 QOS={user_data=0<>,topic_name="DCPSParticipantMessage",type_name="ParticipantMessageData",topic_data=0<>,group_data=0<>,durability=1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.586155 [0]       csub: match_reader_with_proxy_writers(rd 110b80d:483173fd:adcdf2d0:200c7) scanning proxy participants tgt=200c2
1674226239.586159 [0]       csub: new_reader(guid 110b80d:483173fd:adcdf2d0:300c4, (null).DCPSTypeLookupRequest/DDS::Builtin::TypeLookup_Request)
1674226239.586165 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:300c4 @ 0x560dc9e56f44) user 1 builtin 11
1674226239.586187 [0]       csub: READER 110b80d:483173fd:adcdf2d0:300c4 QOS={user_data=0<>,topic_name="DCPSTypeLookupRequest",type_name="DDS::Builtin::TypeLookup_Request",topic_data=0<>,group_data=0<>,durability=0,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.586191 [0]       csub: match_reader_with_proxy_writers(rd 110b80d:483173fd:adcdf2d0:300c4) scanning proxy participants tgt=300c3
1674226239.586194 [0]       csub: new_reader(guid 110b80d:483173fd:adcdf2d0:301c4, (null).DCPSTypeLookupReply/DDS::Builtin::TypeLookup_Reply)
1674226239.586197 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:301c4 @ 0x560dc9e572b4) user 1 builtin 12
1674226239.586206 [0]       csub: READER 110b80d:483173fd:adcdf2d0:301c4 QOS={user_data=0<>,topic_name="DCPSTypeLookupReply",type_name="DDS::Builtin::TypeLookup_Reply",topic_data=0<>,group_data=0<>,durability=0,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.586209 [0]       csub: match_reader_with_proxy_writers(rd 110b80d:483173fd:adcdf2d0:301c4) scanning proxy participants tgt=301c3
1674226239.586566 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:14d @ 0x560dc9e58c04) user 2 builtin 12
1674226239.586585 [0]       csub: TOPIC 110b80d:483173fd:adcdf2d0:14d QOS={topic_name="Data",type_name="i11eperf::ou",durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,transport_priority=0,data_representation=1(0),type_information=[MINIMAL cc905e5bd3c6511586ad4761d0a0]/[COMPLETE c10bd356b2ae460cce9208c1c65e]}
1674226239.586632 [0]       csub:  topic-definition 0x0x560dc9e5b600: key 0x53017898f6419fc587c3861226471ad3 QOS={topic_name="Data",type_name="i11eperf::ou",durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,transport_priority=0,data_representation=1(0),type_information=[MINIMAL cc905e5bd3c6511586ad4761d0a0]/[COMPLETE c10bd356b2ae460cce9208c1c65e]}
1674226239.586721 [0]       csub: new_reader(guid 110b80d:483173fd:adcdf2d0:207, (default).Data/i11eperf::ou)
1674226239.586731 [0]       csub: ddsi_ref_participant(110b80d:483173fd:adcdf2d0:1c1 @ 0x560dc9e50490 <- 110b80d:483173fd:adcdf2d0:207 @ 0x560dc9e5cdc4) user 3 builtin 12
1674226239.586755 [0]       csub: READER 110b80d:483173fd:adcdf2d0:207 QOS={user_data=0<>,topic_name="Data",type_name="i11eperf::ou",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:10000000000,lifespan=9223372036854775807,destination_order=0,history=1:1,resource_limits=436906:-1:-1,ownership=0,presentation=0:0:0,partition={""},time_based_filter=0,transport_priority=0,type_consistency_enforcement=0:00010,data_representation=1(0),adlink_entity_factory=1,adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674226239.586763 [0]       csub: match_reader_with_proxy_writers(rd 110b80d:483173fd:adcdf2d0:207) scanning all pwrs of topic Data
1674226239.586766 [0]       csub: match_reader_with_writers(rd 110b80d:483173fd:adcdf2d0:207) scanning all wrs of topic Data
1674226241.732265 [0] dq.builtin: SPDP ST0 1030015:5d380101:c8eab0da:1c1 bes fc3f NEW (data udp/127.0.0.1:12345@2 meta udp/239.255.0.1:7402@2 udp/172.17.0.1:53998@2 udp/192.168.234.128:53998@2) QOS={}
1674226241.732328 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:c8eab0da:100c7) scanning participants tgt=0
1674226241.732341 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:c8eab0da:3c2) scanning participants tgt=3c7
1674226241.732346 [0] dq.builtin:   reader 110b80d:483173fd:adcdf2d0:3c7 init_acknack_count = 1
1674226241.732350 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:c8eab0da:3c2 rd 110b80d:483173fd:adcdf2d0:3c7)
1674226241.732375 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:c8eab0da:3c2 rd 110b80d:483173fd:adcdf2d0:3c7) - out-of-sync
1674226241.732385 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:c8eab0da:3c7) scanning participants tgt=3c2
1674226241.732390 [0] dq.builtin:   proxy_reader_add_connection(wr 110b80d:483173fd:adcdf2d0:3c2 prd 1030015:5d380101:c8eab0da:3c7)
1674226241.732394 [0] dq.builtin:   writer_add_connection(wr 110b80d:483173fd:adcdf2d0:3c2 prd 1030015:5d380101:c8eab0da:3c7) - ack seq 0
1674226241.732401 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:53998@2 udp/192.168.234.128:53998@2
1674226241.732405 [0] dq.builtin: reduced nlocs=3
1674226241.732410 [0] dq.builtin:                                                                   a
1674226241.732421 [0] dq.builtin:   loc  0 = udp/172.17.0.1:53998@2                            1 { +u  }
1674226241.732425 [0] dq.builtin:   loc  1 = udp/192.168.234.128:53998@2                       1 { +u  }
1674226241.732429 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674226241.732431 [0] dq.builtin:   best = 0
1674226241.732434 [0] dq.builtin:   simple udp/172.17.0.1:53998@2
1674226241.732439 [0] dq.builtin: ddsi_rebuild_writer_addrset(110b80d:483173fd:adcdf2d0:3c2): udp/172.17.0.1:53998@2 (burst size 4294901760 rexmit 283990)
1674226241.732463 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:c8eab0da:4c2) scanning participants tgt=4c7
1674226241.732470 [0] dq.builtin:   reader 110b80d:483173fd:adcdf2d0:4c7 init_acknack_count = 1
1674226241.732473 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:c8eab0da:4c2 rd 110b80d:483173fd:adcdf2d0:4c7)
1674226241.732477 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:c8eab0da:4c2 rd 110b80d:483173fd:adcdf2d0:4c7) - out-of-sync
1674226241.732485 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:c8eab0da:4c7) scanning participants tgt=4c2
1674226241.732488 [0] dq.builtin:   proxy_reader_add_connection(wr 110b80d:483173fd:adcdf2d0:4c2 prd 1030015:5d380101:c8eab0da:4c7)
1674226241.732492 [0] dq.builtin:   writer_add_connection(wr 110b80d:483173fd:adcdf2d0:4c2 prd 1030015:5d380101:c8eab0da:4c7) - ack seq 1
1674226241.732497 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:53998@2 udp/192.168.234.128:53998@2
1674226241.732501 [0] dq.builtin: reduced nlocs=3
1674226241.732505 [0] dq.builtin:                                                                   a
1674226241.732509 [0] dq.builtin:   loc  0 = udp/172.17.0.1:53998@2                            1 { +u  }
1674226241.732512 [0] dq.builtin:   loc  1 = udp/192.168.234.128:53998@2                       1 { +u  }
1674226241.732515 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674226241.732518 [0] dq.builtin:   best = 0
1674226241.732521 [0] dq.builtin:   simple udp/172.17.0.1:53998@2
1674226241.732525 [0] dq.builtin: ddsi_rebuild_writer_addrset(110b80d:483173fd:adcdf2d0:4c2): udp/172.17.0.1:53998@2 (burst size 4294901760 rexmit 283990)
1674226241.732530 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:c8eab0da:200c2) scanning participants tgt=200c7
1674226241.732534 [0] dq.builtin:   reader 110b80d:483173fd:adcdf2d0:200c7 init_acknack_count = 1
1674226241.732537 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:c8eab0da:200c2 rd 110b80d:483173fd:adcdf2d0:200c7)
1674226241.732540 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:c8eab0da:200c2 rd 110b80d:483173fd:adcdf2d0:200c7) - out-of-sync
1674226241.732545 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:c8eab0da:200c7) scanning participants tgt=200c2
1674226241.732548 [0] dq.builtin:   proxy_reader_add_connection(wr 110b80d:483173fd:adcdf2d0:200c2 prd 1030015:5d380101:c8eab0da:200c7)
1674226241.732551 [0] dq.builtin:   writer_add_connection(wr 110b80d:483173fd:adcdf2d0:200c2 prd 1030015:5d380101:c8eab0da:200c7) - ack seq 1
1674226241.732556 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:53998@2 udp/192.168.234.128:53998@2
1674226241.732559 [0] dq.builtin: reduced nlocs=3
1674226241.732563 [0] dq.builtin:                                                                   a
1674226241.732566 [0] dq.builtin:   loc  0 = udp/172.17.0.1:53998@2                            1 { +u  }
1674226241.732569 [0] dq.builtin:   loc  1 = udp/192.168.234.128:53998@2                       1 { +u  }
1674226241.732573 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674226241.732575 [0] dq.builtin:   best = 0
1674226241.732578 [0] dq.builtin:   simple udp/172.17.0.1:53998@2
1674226241.732582 [0] dq.builtin: ddsi_rebuild_writer_addrset(110b80d:483173fd:adcdf2d0:200c2): udp/172.17.0.1:53998@2 (burst size 4294901760 rexmit 283990)
1674226241.732595 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:c8eab0da:300c3) scanning participants tgt=300c4
1674226241.732598 [0] dq.builtin:   reader 110b80d:483173fd:adcdf2d0:300c4 init_acknack_count = 1
1674226241.732601 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:c8eab0da:300c3 rd 110b80d:483173fd:adcdf2d0:300c4)
1674226241.732605 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:c8eab0da:300c3 rd 110b80d:483173fd:adcdf2d0:300c4)
1674226241.732609 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:c8eab0da:300c4) scanning participants tgt=300c3
1674226241.732612 [0] dq.builtin:   proxy_reader_add_connection(wr 110b80d:483173fd:adcdf2d0:300c3 prd 1030015:5d380101:c8eab0da:300c4)
1674226241.732615 [0] dq.builtin:   writer_add_connection(wr 110b80d:483173fd:adcdf2d0:300c3 prd 1030015:5d380101:c8eab0da:300c4) - ack seq 0
1674226241.732620 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:53998@2 udp/192.168.234.128:53998@2
1674226241.732623 [0] dq.builtin: reduced nlocs=3
1674226241.732627 [0] dq.builtin:                                                                   a
1674226241.732630 [0] dq.builtin:   loc  0 = udp/172.17.0.1:53998@2                            1 { +u  }
1674226241.732634 [0] dq.builtin:   loc  1 = udp/192.168.234.128:53998@2                       1 { +u  }
1674226241.732637 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674226241.732639 [0] dq.builtin:   best = 0
1674226241.732642 [0] dq.builtin:   simple udp/172.17.0.1:53998@2
1674226241.732646 [0] dq.builtin: ddsi_rebuild_writer_addrset(110b80d:483173fd:adcdf2d0:300c3): udp/172.17.0.1:53998@2 (burst size 4294901760 rexmit 283990)
1674226241.732651 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:c8eab0da:301c3) scanning participants tgt=301c4
1674226241.732654 [0] dq.builtin:   reader 110b80d:483173fd:adcdf2d0:301c4 init_acknack_count = 1
1674226241.732657 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:c8eab0da:301c3 rd 110b80d:483173fd:adcdf2d0:301c4)
1674226241.732660 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:c8eab0da:301c3 rd 110b80d:483173fd:adcdf2d0:301c4)
1674226241.732666 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:c8eab0da:301c4) scanning participants tgt=301c3
1674226241.732670 [0] dq.builtin:   proxy_reader_add_connection(wr 110b80d:483173fd:adcdf2d0:301c3 prd 1030015:5d380101:c8eab0da:301c4)
1674226241.732673 [0] dq.builtin:   writer_add_connection(wr 110b80d:483173fd:adcdf2d0:301c3 prd 1030015:5d380101:c8eab0da:301c4) - ack seq 0
1674226241.732678 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:53998@2 udp/192.168.234.128:53998@2
1674226241.732681 [0] dq.builtin: reduced nlocs=3
1674226241.732684 [0] dq.builtin:                                                                   a
1674226241.732688 [0] dq.builtin:   loc  0 = udp/172.17.0.1:53998@2                            1 { +u  }
1674226241.732691 [0] dq.builtin:   loc  1 = udp/192.168.234.128:53998@2                       1 { +u  }
1674226241.732694 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674226241.732697 [0] dq.builtin:   best = 0
1674226241.732700 [0] dq.builtin:   simple udp/172.17.0.1:53998@2
1674226241.732704 [0] dq.builtin: ddsi_rebuild_writer_addrset(110b80d:483173fd:adcdf2d0:301c3): udp/172.17.0.1:53998@2 (burst size 4294901760 rexmit 283990)
1674226241.732748 [0] dq.builtin: broadcasted SPDP packet -> answering
1674226247.801059 [0] dq.builtin: SEDP ST0 1030015:5d380101:c8eab0da:2 reliable volatile writer unnamed: (default).Data/i11eperf::ou p(open) NEW (as udp/239.255.0.2:7401@2 udp/172.17.0.1:55835@2 udp/192.168.234.128:55835@2 ssm=0) QOS={user_data=0<>,topic_name="Data",type_name="i11eperf::ou",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:10000000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),type_information=[MINIMAL 5a3c0c46e4529da3ac2f9f6e5b40]/[COMPLETE 41bb44ca49453fdfd22a67cc0fa5],adlink_writer_data_lifecycle=0}
1674226247.801129 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:c8eab0da:2) scanning all rds of topic Data
1674226250.696904 [0] dq.builtin: SPDP ST0 1030015:5d380101:c8eab0da:1c1 (update)ddsi_update_qos_locked 1030015:5d380101:c8eab0da:1c1 delta=0 QOS={user_data=0<>,adlink_entity_factory=0}
eboasson commented 1 year ago

I had hoped for a full trace (i.e., <Verbosity>finest</Verbosity> or <Category>trace</Category> because that way there'd be a dump of discovered writer QoS prior to applying defaults (for the history QoS), but otherwise it is interesting.

It ends like this, with the discovery of the writer:

1674226247.801059 [0] dq.builtin: SEDP ST0 1030015:5d380101:c8eab0da:2 reliable volatile writer unnamed: (default).Data/i11eperf::ou p(open) NEW (as udp/239.255.0.2:7401@2 udp/172.17.0.1:55835@2 udp/192.168.234.128:55835@2 ssm=0) QOS={user_data=0<>,topic_name="Data",type_name="i11eperf::ou",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:10000000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),type_information=[MINIMAL 5a3c0c46e4529da3ac2f9f6e5b40]/[COMPLETE 41bb44ca49453fdfd22a67cc0fa5],adlink_writer_data_lifecycle=0}
1674226247.801129 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:c8eab0da:2) scanning all rds of topic Data
1674226250.696904 [0] dq.builtin: SPDP ST0 1030015:5d380101:c8eab0da:1c1 (update)ddsi_update_qos_locked 1030015:5d380101:c8eab0da:1c1 delta=0 QOS={user_data=0<>,adlink_entity_factory=0}

If it had matched the reader, it would have resulted in some additional output.

Looking at all the numbers and comparing to the reader side, there are small differences discrepancies:

1674226239.586585 [0]       csub: TOPIC 110b80d:483173fd:adcdf2d0:14d QOS={topic_name="Data",type_name="i11eperf::ou",durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,transport_priority=0,data_representation=1(0),type_information=[MINIMAL cc905e5bd3c6511586ad4761d0a0]/[COMPLETE c10bd356b2ae460cce9208c1c65e]}
1674226239.586632 [0]       csub:  topic-definition 0x0x560dc9e5b600: key 0x53017898f6419fc587c3861226471ad3 QOS={topic_name="Data",type_name="i11eperf::ou",durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,transport_priority=0,data_representation=1(0),type_information=[MINIMAL cc905e5bd3c6511586ad4761d0a0]/[COMPLETE c10bd356b2ae460cce9208c1c65e]}
1674226239.586755 [0]       csub: READER 110b80d:483173fd:adcdf2d0:207 QOS={user_data=0<>,topic_name="Data",type_name="i11eperf::ou",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:10000000000,lifespan=9223372036854775807,destination_order=0,history=1:1,resource_limits=436906:-1:-1,ownership=0,presentation=0:0:0,partition={""},time_based_filter=0,transport_priority=0,type_consistency_enforcement=0:00010,data_representation=1(0),adlink_entity_factory=1,adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}

There is a difference in partition ({} vs {""} — but those two should be equivalent), and there is a difference in the type information: 5a3c0c46e4529da3ac2f9f6e5b40 for OpenDDS and cc905e5bd3c6511586ad4761d0a0 for Cyclone DDS (this is the minimal type id, which is used for matching). The type name is i11eperf::ou, but I'm pretty sure yields a different type id, so I am right in concluding that you have modified that type definition?

That certainly is consistent with the warning from OpenDDS — either I overlooked it at first or you just added that, either way, I am grateful that the note is there!

So the question then becomes finding out why the two use incompatible types. Would it be possible to share the IDL and the output from the IDL compilers?

smnrgrs commented 1 year ago

Sorry, here's a better log. Yes I've tweaked the idl as I'm investigating problems with keys The topic looks like: @topic struct ou { @key long id; unsigned long long ts; unsigned long s; };

I'll try to get the idl compiler output

1674230866.248774 [0]       csub: config: Domain/General/MulticastRecvNetworkInterfaceAddresses/#text: preferred {}
1674230866.248804 [0]       csub: config: Domain/General/ExternalNetworkAddress/#text: auto {}
1674230866.248811 [0]       csub: config: Domain/General/ExternalNetworkMask/#text: 0.0.0.0 {}
1674230866.248816 [0]       csub: config: Domain/General/AllowMulticast/#text: default {}
1674230866.248822 [0]       csub: config: Domain/General/MulticastTimeToLive/#text: 32 {}
1674230866.248827 [0]       csub: config: Domain/General/DontRoute/#text: false {}
1674230866.248832 [0]       csub: config: Domain/General/Transport/#text: udp {}
1674230866.248837 [0]       csub: config: Domain/General/EnableMulticastLoopback/#text: true {}
1674230866.248843 [0]       csub: config: Domain/General/MaxMessageSize/#text: 14720 B {}
1674230866.248849 [0]       csub: config: Domain/General/MaxRexmitMessageSize/#text: 1456 B {}
1674230866.248854 [0]       csub: config: Domain/General/FragmentSize/#text: 1344 B {}
1674230866.248859 [0]       csub: config: Domain/General/RedundantNetworking/#text: false {}
1674230866.248865 [0]       csub: config: Domain/General/EntityAutoNaming/#text: empty {}
1674230866.248871 [0]       csub: config: Domain/General/EntityAutoNaming[@seed]: 3838383794 3144112948 813853660 1758122408 1176379317 1124528797 1205471921 597338545 {}
1674230866.248878 [0]       csub: config: Domain/Sizing/ReceiveBufferSize/#text: 1 MiB {}
1674230866.248884 [0]       csub: config: Domain/Sizing/ReceiveBufferChunkSize/#text: 128 KiB {}
1674230866.248889 [0]       csub: config: Domain/Compatibility/StandardsConformance/#text: lax {}
1674230866.248894 [0]       csub: config: Domain/Compatibility/ExplicitlyPublishQosSetToDefault/#text: false {}
1674230866.248899 [0]       csub: config: Domain/Compatibility/ManySocketsMode/#text: single {}
1674230866.248905 [0]       csub: config: Domain/Compatibility/AssumeRtiHasPmdEndpoints/#text: false {}
1674230866.248910 [0]       csub: config: Domain/Discovery/Tag/#text:  {}
1674230866.248915 [0]       csub: config: Domain/Discovery/ExternalDomainId/#text: 0 {}
1674230866.248921 [0]       csub: config: Domain/Discovery/DSGracePeriod/#text: 30 s {}
1674230866.248926 [0]       csub: config: Domain/Discovery/ParticipantIndex/#text: none {}
1674230866.248931 [0]       csub: config: Domain/Discovery/MaxAutoParticipantIndex/#text: 9 {}
1674230866.248937 [0]       csub: config: Domain/Discovery/SPDPMulticastAddress/#text: 239.255.0.1 {}
1674230866.248942 [0]       csub: config: Domain/Discovery/SPDPInterval/#text: 30 s {}
1674230866.248947 [0]       csub: config: Domain/Discovery/DefaultMulticastAddress/#text: auto {}
1674230866.248953 [0]       csub: config: Domain/Discovery/Ports/Base/#text: 7400 {}
1674230866.248958 [0]       csub: config: Domain/Discovery/Ports/DomainGain/#text: 250 {}
1674230866.248964 [0]       csub: config: Domain/Discovery/Ports/ParticipantGain/#text: 2 {}
1674230866.248969 [0]       csub: config: Domain/Discovery/Ports/MulticastMetaOffset/#text: 0 {}
1674230866.248975 [0]       csub: config: Domain/Discovery/Ports/UnicastMetaOffset/#text: 10 {}
1674230866.248980 [0]       csub: config: Domain/Discovery/Ports/MulticastDataOffset/#text: 1 {}
1674230866.248985 [0]       csub: config: Domain/Discovery/Ports/UnicastDataOffset/#text: 11 {}
1674230866.248990 [0]       csub: config: Domain/Discovery/EnableTopicDiscoveryEndpoints/#text: false {}
1674230866.248996 [0]       csub: config: Domain/Discovery/LeaseDuration/#text: 10 s {}
1674230866.249005 [0]       csub: config: Domain/Tracing/Category/#text: trace {1}
1674230866.249011 [0]       csub: config: Domain/Tracing/OutputFile/#text: cdds.log.51886 {1}
1674230866.249016 [0]       csub: config: Domain/Tracing/AppendToFile/#text: false {}
1674230866.249021 [0]       csub: config: Domain/Tracing/PacketCaptureFile/#text:  {}
1674230866.249027 [0]       csub: config: Domain/Internal/DeliveryQueueMaxSamples/#text: 256 {}
1674230866.249032 [0]       csub: config: Domain/Internal/PrimaryReorderMaxSamples/#text: 128 {}
1674230866.249037 [0]       csub: config: Domain/Internal/SecondaryReorderMaxSamples/#text: 128 {}
1674230866.249048 [0]       csub: config: Domain/Internal/DefragUnreliableMaxSamples/#text: 4 {}
1674230866.249053 [0]       csub: config: Domain/Internal/DefragReliableMaxSamples/#text: 16 {}
1674230866.249058 [0]       csub: config: Domain/Internal/BuiltinEndpointSet/#text: writers {}
1674230866.249064 [0]       csub: config: Domain/Internal/MeasureHbToAckLatency/#text: false {}
1674230866.249069 [0]       csub: config: Domain/Internal/UnicastResponseToSPDPMessages/#text: true {}
1674230866.249074 [0]       csub: config: Domain/Internal/SynchronousDeliveryPriorityThreshold/#text: 0 {}
1674230866.249079 [0]       csub: config: Domain/Internal/SynchronousDeliveryLatencyBound/#text: inf {}
1674230866.249085 [0]       csub: config: Domain/Internal/MaxParticipants/#text: 0 {}
1674230866.249090 [0]       csub: config: Domain/Internal/AccelerateRexmitBlockSize/#text: 0 {}
1674230866.249095 [0]       csub: config: Domain/Internal/RetransmitMerging/#text: never {}
1674230866.249100 [0]       csub: config: Domain/Internal/RetransmitMergingPeriod/#text: 5 ms {}
1674230866.249106 [0]       csub: config: Domain/Internal/HeartbeatInterval/#text: 100 ms {}
1674230866.249111 [0]       csub: config: Domain/Internal/HeartbeatInterval[@min]: 5 ms {}
1674230866.249117 [0]       csub: config: Domain/Internal/HeartbeatInterval[@minsched]: 20 ms {}
1674230866.249122 [0]       csub: config: Domain/Internal/HeartbeatInterval[@max]: 8 s {}
1674230866.249128 [0]       csub: config: Domain/Internal/MaxQueuedRexmitBytes/#text: 512 KiB {}
1674230866.249133 [0]       csub: config: Domain/Internal/MaxQueuedRexmitMessages/#text: 200 {}
1674230866.249138 [0]       csub: config: Domain/Internal/WriterLingerDuration/#text: 1 s {}
1674230866.249144 [0]       csub: config: Domain/Internal/SocketReceiveBufferSize[@min]: default {}
1674230866.249149 [0]       csub: config: Domain/Internal/SocketReceiveBufferSize[@max]: default {}
1674230866.249155 [0]       csub: config: Domain/Internal/SocketSendBufferSize[@min]: 64 KiB {}
1674230866.249160 [0]       csub: config: Domain/Internal/SocketSendBufferSize[@max]: default {}
1674230866.249165 [0]       csub: config: Domain/Internal/NackDelay/#text: 100 ms {}
1674230866.249171 [0]       csub: config: Domain/Internal/AckDelay/#text: 10 ms {}
1674230866.249176 [0]       csub: config: Domain/Internal/AutoReschedNackDelay/#text: 3 s {}
1674230866.249181 [0]       csub: config: Domain/Internal/PreEmptiveAckDelay/#text: 10 ms {}
1674230866.249187 [0]       csub: config: Domain/Internal/ScheduleTimeRounding/#text: 0 s {}
1674230866.249192 [0]       csub: config: Domain/Internal/DDSI2DirectMaxThreads/#text: 1 {}
1674230866.249197 [0]       csub: config: Domain/Internal/SquashParticipants/#text: false {}
1674230866.249202 [0]       csub: config: Domain/Internal/SPDPResponseMaxDelay/#text: 0 s {}
1674230866.249207 [0]       csub: config: Domain/Internal/LateAckMode/#text: false {}
1674230866.249212 [0]       csub: config: Domain/Internal/RetryOnRejectBestEffort/#text: false {}
1674230866.249218 [0]       csub: config: Domain/Internal/GenerateKeyhash/#text: false {}
1674230866.249223 [0]       csub: config: Domain/Internal/MaxSampleSize/#text: 2147483647 B {}
1674230866.249228 [0]       csub: config: Domain/Internal/WriteBatch/#text: false {}
1674230866.249233 [0]       csub: config: Domain/Internal/LivelinessMonitoring/#text: false {}
1674230866.249239 [0]       csub: config: Domain/Internal/LivelinessMonitoring[@StackTraces]: true {}
1674230866.249244 [0]       csub: config: Domain/Internal/LivelinessMonitoring[@Interval]: 1 s {}
1674230866.249250 [0]       csub: config: Domain/Internal/MonitorPort/#text: -1 {}
1674230866.249255 [0]       csub: config: Domain/Internal/PrioritizeRetransmit/#text: true {}
1674230866.249260 [0]       csub: config: Domain/Internal/UseMulticastIfMreqn/#text: 0 {}
1674230866.249265 [0]       csub: config: Domain/Internal/RediscoveryBlacklistDuration/#text: 0 s {}
1674230866.249271 [0]       csub: config: Domain/Internal/RediscoveryBlacklistDuration[@enforce]: false {}
1674230866.249280 [0]       csub: config: Domain/Internal/MultipleReceiveThreads/#text: default {}
1674230866.249285 [0]       csub: config: Domain/Internal/MultipleReceiveThreads[@maxretries]: 4294967295 {}
1674230866.249291 [0]       csub: config: Domain/Internal/Test/XmitLossiness/#text: 0 {}
1674230866.249297 [0]       csub: config: Domain/Internal/Watermarks/WhcLow/#text: 1 KiB {}
1674230866.249302 [0]       csub: config: Domain/Internal/Watermarks/WhcHigh/#text: 500 KiB {}
1674230866.249308 [0]       csub: config: Domain/Internal/Watermarks/WhcHighInit/#text: 30 KiB {}
1674230866.249313 [0]       csub: config: Domain/Internal/Watermarks/WhcAdaptive/#text: true {}
1674230866.249319 [0]       csub: config: Domain/Internal/BurstSize/MaxRexmit/#text: 1 MiB {}
1674230866.249324 [0]       csub: config: Domain/Internal/BurstSize/MaxInitTransmit/#text: 4294967295 {}
1674230866.249330 [0]       csub: config: Domain/Internal/EnableExpensiveChecks/#text:  [ignored] {}
1674230866.249335 [0]       csub: config: Domain/TCP/NoDelay/#text: true {}
1674230866.249341 [0]       csub: config: Domain/TCP/Port/#text: -1 {}
1674230866.249346 [0]       csub: config: Domain/TCP/ReadTimeout/#text: 2 s {}
1674230866.249351 [0]       csub: config: Domain/TCP/WriteTimeout/#text: 2 s {}
1674230866.249357 [0]       csub: config: Domain/TCP/AlwaysUsePeeraddrForUnicast/#text: false {}
1674230866.249362 [0]       csub: config: Domain/SSL/Enable/#text: false {}
1674230866.249367 [0]       csub: config: Domain/SSL/CertificateVerification/#text: true {}
1674230866.249372 [0]       csub: config: Domain/SSL/VerifyClient/#text: true {}
1674230866.249378 [0]       csub: config: Domain/SSL/SelfSignedCertificates/#text: false {}
1674230866.249383 [0]       csub: config: Domain/SSL/KeystoreFile/#text: keystore {}
1674230866.249388 [0]       csub: config: Domain/SSL/KeyPassphrase/#text: secret {}
1674230866.249393 [0]       csub: config: Domain/SSL/Ciphers/#text: ALL:!ADH:!LOW:!EXP:!MD5:@STRENGTH {}
1674230866.249399 [0]       csub: config: Domain/SSL/EntropyFile/#text:  {}
1674230866.249404 [0]       csub: config: Domain/SSL/MinimumTLSVersion/#text: 1.3 {}
1674230866.249409 [0]       csub: config: Domain[@Id]: 0 {1}
1674230866.249461 [0]       csub: started at 1674230866.06249417 -- 2023-01-20 16:07:46+00:00
1674230866.249467 [0]       csub: udp initialized
1674230866.249738 [0]       csub: interfaces: lo udp/127.0.0.1(q1) eth0 udp/192.168.234.128(q9) docker0 udp/172.17.0.1(q9)
1674230866.249749 [0]       csub: using network interface eth0 (udp/192.168.234.128) selected arbitrarily from: eth0, docker0
1674230866.249802 [0]       csub: selected interfaces: eth0 (index 2 priority 0)
1674230866.249808 [0]       csub: presumed robust multicast support, use for everything
1674230866.249828 [0]       csub: ownip: udp/192.168.234.128
1674230866.249833 [0]       csub: extmask: invalid/0 (not applicable)
1674230866.249838 [0]       csub: SPDP MC: udp/239.255.0.1
1674230866.249843 [0]       csub: default MC: udp/239.255.0.1
1674230866.249847 [0]       csub: SSM support included
1674230866.249989 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674230866.250004 [0]       csub: ddsi_udp_create_conn unicast socket 4 port 52750
1674230866.250009 [0]       csub: rtps_init: uc ports: disc 0 data 0
1674230866.250013 [0]       csub: rtps_init: domainid 0 participantid -2
1674230866.250018 [0]       csub: Unicast Ports: discovery 52750 data 52750
1674230866.250033 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674230866.250044 [0]       csub: ddsi_udp_create_conn multicast socket 5 port 7400
1674230866.250058 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674230866.250067 [0]       csub: ddsi_udp_create_conn multicast socket 6 port 7401
1674230866.250072 [0]       csub: Multicast Ports: discovery 7400 data 7401 
1674230866.250084 [0]       csub: failed to increase socket receive buffer size to 1048576 bytes, continuing with 425984 bytes
1674230866.250107 [0]       csub: ddsi_udp_create_conn transmit(uc/mc) socket 7 port 51014
1674230866.250112 [0]       csub: interface eth0: transmit port 51014
1674230866.250124 [0]       csub: join conn 0x560bc2e3f0d0 for (udp/239.255.0.1, *) interface udp/192.168.234.128
1674230866.250133 [0]       csub: join conn 0x560bc2e3f150 for (udp/239.255.0.1, *) interface udp/192.168.234.128
1674230866.250140 [0]       csub: join conn 0x560bc2e3f0d0 for (udp/239.255.0.1, *) interface (default): already joined
1674230866.250146 [0]       csub: join conn 0x560bc2e3f150 for (udp/239.255.0.1, *) interface (default): already joined
1674230866.250165 [0]       csub: ddsi_new_local_orphan_writer(DCPSParticipant/org::eclipse::cyclonedds::builtin::DCPSParticipant)
1674230866.250197 [0]       csub: WRITER 0:0:0:100c2 QOS={user_data=0<>,topic_name="DCPSParticipant",type_name="org::eclipse::cyclonedds::builtin::DCPSParticipant",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.250207 [0]       csub: match_writer_with_readers(wr 0:0:0:100c2) scanning all rds of topic DCPSParticipant
1674230866.250212 [0]       csub: ddsi_new_local_orphan_writer(DCPSTopic/org::eclipse::cyclonedds::builtin::DCPSTopic)
1674230866.250229 [0]       csub: WRITER 0:0:0:2c2 QOS={user_data=0<>,topic_name="DCPSTopic",type_name="org::eclipse::cyclonedds::builtin::DCPSTopic",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.250235 [0]       csub: match_writer_with_readers(wr 0:0:0:2c2) scanning all rds of topic DCPSTopic
1674230866.250240 [0]       csub: ddsi_new_local_orphan_writer(DCPSPublication/org::eclipse::cyclonedds::builtin::DCPSPublication)
1674230866.250256 [0]       csub: WRITER 0:0:0:3c2 QOS={user_data=0<>,topic_name="DCPSPublication",type_name="org::eclipse::cyclonedds::builtin::DCPSPublication",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.250262 [0]       csub: match_writer_with_readers(wr 0:0:0:3c2) scanning all rds of topic DCPSPublication
1674230866.250266 [0]       csub: ddsi_new_local_orphan_writer(DCPSSubscription/org::eclipse::cyclonedds::builtin::DCPSSubscription)
1674230866.250284 [0]       csub: WRITER 0:0:0:4c2 QOS={user_data=0<>,topic_name="DCPSSubscription",type_name="org::eclipse::cyclonedds::builtin::DCPSSubscription",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=1:0:0,partition={"__BUILT-IN PARTITION__"},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.250291 [0]       csub: match_writer_with_readers(wr 0:0:0:4c2) scanning all rds of topic DCPSSubscription
1674230866.250299 [0]       csub: create_thread: gc: class 0 priority 0 stack 0
1674230866.250402 [0]       csub: create_thread: dq.builtins: class 0 priority 0 stack 0
1674230866.250474 [0]         gc: started new thread 51887: gc
1674230866.250567 [0] dq.builtin: started new thread 51888: dq.builtins
1674230866.250480 [0]       csub: create_thread: dq.user: class 0 priority 0 stack 0
1674230866.250710 [0]       csub: create_thread: tev: class 0 priority 0 stack 0
1674230866.250747 [0]    dq.user: started new thread 51889: dq.user
1674230866.250776 [0]         gc: thread_cputime 0.000000000
1674230866.250779 [0] dq.builtin: thread_cputime 0.000000000
1674230866.250818 [0]        tev: started new thread 51890: tev
1674230866.250802 [0]       csub: create_thread: recv: class 0 priority 0 stack 0
1674230866.250800 [0]    dq.user: thread_cputime 0.000000000
1674230866.250960 [0]       csub: create_thread: recvMC: class 0 priority 0 stack 0
1674230866.250969 [0]       recv: started new thread 51891: recv
1674230866.251047 [0]       csub: create_thread: recvUC: class 0 priority 0 stack 0
1674230866.251101 [0]       csub: ddsi_new_participant(110d552:bdf21630:38f55f5b:1c1, 0)
1674230866.251100 [0]     recvMC: started new thread 51892: recvMC
1674230866.251155 [0]       recv: thread_cputime 0.000000000
1674230866.251138 [0]       csub: PARTICIPANT 110d552:bdf21630:38f55f5b:1c1 QOS={user_data=0<>,liveliness=0:10000000000,property_list={1:"__ProcessName":"csub",1:"__Pid":"51886",1:"__Hostname":"simon-Virtual-Machine"}:{},adlink_entity_factory=0}
1674230866.251209 [0]        tev: thread_cputime 0.000000000
1674230866.251244 [0]       csub: new_writer(guid 110d552:bdf21630:38f55f5b:100c2, (null).DCPSParticipant/ParticipantBuiltinTopicData)
1674230866.251255 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:100c2 @ 0x560bc2e4d274) user 1 builtin 1
1674230866.251148 [0]         gc: gc 0x560bc2e4aaf0: not yet, shortsleep
1674230866.251278 [0]       csub: WRITER 110d552:bdf21630:38f55f5b:100c2 QOS={user_data=0<>,topic_name="DCPSParticipant",type_name="ParticipantBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:0,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_writer_data_lifecycle=1,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.251288 [0]       csub: match_writer_with_proxy_readers(wr 110d552:bdf21630:38f55f5b:100c2) scanning proxy participants tgt=0
1674230866.251300 [0]       csub: new_writer(guid 110d552:bdf21630:38f55f5b:4c2, (null).DCPSSubscription/SubscriptionBuiltinTopicData)
1674230866.251307 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:4c2 @ 0x560bc2e4da84) user 1 builtin 2
1674230866.251324 [0]       csub: WRITER 110d552:bdf21630:38f55f5b:4c2 QOS={user_data=0<>,topic_name="DCPSSubscription",type_name="SubscriptionBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.251331 [0]       csub: match_writer_with_proxy_readers(wr 110d552:bdf21630:38f55f5b:4c2) scanning proxy participants tgt=4c7
1674230866.251341 [0]       csub: new_writer(guid 110d552:bdf21630:38f55f5b:3c2, (null).DCPSPublication/PublicationBuiltinTopicData)
1674230866.251127 [0]     recvUC: started new thread 51893: recvUC
1674230866.251398 [0]     recvUC: thread_cputime 0.000000000
1674230866.251160 [0]     recvMC: thread_cputime 0.000000000
1674230866.251348 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:3c2 @ 0x560bc2e4e2a4) user 1 builtin 3
1674230866.251629 [0]       csub: WRITER 110d552:bdf21630:38f55f5b:3c2 QOS={user_data=0<>,topic_name="DCPSPublication",type_name="PublicationBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.251641 [0]       csub: match_writer_with_proxy_readers(wr 110d552:bdf21630:38f55f5b:3c2) scanning proxy participants tgt=3c7
1674230866.251649 [0]       csub: new_writer(guid 110d552:bdf21630:38f55f5b:200c2, (null).DCPSParticipantMessage/ParticipantMessageData)
1674230866.251656 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:200c2 @ 0x560bc2e4eb24) user 1 builtin 4
1674230866.251673 [0]       csub: WRITER 110d552:bdf21630:38f55f5b:200c2 QOS={user_data=0<>,topic_name="DCPSParticipantMessage",type_name="ParticipantMessageData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.251687 [0]       csub: match_writer_with_proxy_readers(wr 110d552:bdf21630:38f55f5b:200c2) scanning proxy participants tgt=200c7
1674230866.251695 [0]       csub: new_writer(guid 110d552:bdf21630:38f55f5b:300c3, (null).DCPSTypeLookupRequest/DDS::Builtin::TypeLookup_Request)
1674230866.251701 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:300c3 @ 0x560bc2e4f3b4) user 1 builtin 5
1674230866.251718 [0]       csub: WRITER 110d552:bdf21630:38f55f5b:300c3 QOS={user_data=0<>,topic_name="DCPSTypeLookupRequest",type_name="DDS::Builtin::TypeLookup_Request",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.251725 [0]       csub: match_writer_with_proxy_readers(wr 110d552:bdf21630:38f55f5b:300c3) scanning proxy participants tgt=300c4
1674230866.251731 [0]       csub: new_writer(guid 110d552:bdf21630:38f55f5b:301c3, (null).DCPSTypeLookupReply/DDS::Builtin::TypeLookup_Reply)
1674230866.251737 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:301c3 @ 0x560bc2e4fc34) user 1 builtin 6
1674230866.251755 [0]       csub: WRITER 110d552:bdf21630:38f55f5b:301c3 QOS={user_data=0<>,topic_name="DCPSTypeLookupReply",type_name="DDS::Builtin::TypeLookup_Reply",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,lifespan=9223372036854775807,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),adlink_writer_data_lifecycle=1}
1674230866.251762 [0]       csub: match_writer_with_proxy_readers(wr 110d552:bdf21630:38f55f5b:301c3) scanning proxy participants tgt=301c4
1674230866.251768 [0]       csub: new_reader(guid 110d552:bdf21630:38f55f5b:100c7, (null).DCPSParticipant/ParticipantBuiltinTopicData)
1674230866.251781 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:100c7 @ 0x560bc2e501c4) user 1 builtin 7
1674230866.251799 [0]       csub: READER 110d552:bdf21630:38f55f5b:100c7 QOS={user_data=0<>,topic_name="DCPSParticipant",type_name="ParticipantBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:0,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_writer_data_lifecycle=1,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.251806 [0]       csub: match_reader_with_proxy_writers(rd 110d552:bdf21630:38f55f5b:100c7) scanning proxy participants tgt=0
1674230866.251811 [0]       csub: new_reader(guid 110d552:bdf21630:38f55f5b:4c7, (null).DCPSSubscription/SubscriptionBuiltinTopicData)
1674230866.251817 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:4c7 @ 0x560bc2e50514) user 1 builtin 8
1674230866.251832 [0]       csub: READER 110d552:bdf21630:38f55f5b:4c7 QOS={user_data=0<>,topic_name="DCPSSubscription",type_name="SubscriptionBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.251838 [0]       csub: match_reader_with_proxy_writers(rd 110d552:bdf21630:38f55f5b:4c7) scanning proxy participants tgt=4c2
1674230866.251844 [0]       csub: new_reader(guid 110d552:bdf21630:38f55f5b:3c7, (null).DCPSPublication/PublicationBuiltinTopicData)
1674230866.251849 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:3c7 @ 0x560bc2e50884) user 1 builtin 9
1674230866.251864 [0]       csub: READER 110d552:bdf21630:38f55f5b:3c7 QOS={user_data=0<>,topic_name="DCPSPublication",type_name="PublicationBuiltinTopicData",topic_data=0<>,group_data=0<>,durability=1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.251870 [0]       csub: match_reader_with_proxy_writers(rd 110d552:bdf21630:38f55f5b:3c7) scanning proxy participants tgt=3c2
1674230866.251875 [0]       csub: new_reader(guid 110d552:bdf21630:38f55f5b:200c7, (null).DCPSParticipantMessage/ParticipantMessageData)
1674230866.251881 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:200c7 @ 0x560bc2e50bf4) user 1 builtin 10
1674230866.251895 [0]       csub: READER 110d552:bdf21630:38f55f5b:200c7 QOS={user_data=0<>,topic_name="DCPSParticipantMessage",type_name="ParticipantMessageData",topic_data=0<>,group_data=0<>,durability=1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.251905 [0]       csub: match_reader_with_proxy_writers(rd 110d552:bdf21630:38f55f5b:200c7) scanning proxy participants tgt=200c2
1674230866.251910 [0]       csub: new_reader(guid 110d552:bdf21630:38f55f5b:300c4, (null).DCPSTypeLookupRequest/DDS::Builtin::TypeLookup_Request)
1674230866.251919 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:300c4 @ 0x560bc2e50f54) user 1 builtin 11
1674230866.251933 [0]       csub: READER 110d552:bdf21630:38f55f5b:300c4 QOS={user_data=0<>,topic_name="DCPSTypeLookupRequest",type_name="DDS::Builtin::TypeLookup_Request",topic_data=0<>,group_data=0<>,durability=0,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.251940 [0]       csub: match_reader_with_proxy_writers(rd 110d552:bdf21630:38f55f5b:300c4) scanning proxy participants tgt=300c3
1674230866.251945 [0]       csub: new_reader(guid 110d552:bdf21630:38f55f5b:301c4, (null).DCPSTypeLookupReply/DDS::Builtin::TypeLookup_Reply)
1674230866.251951 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:301c4 @ 0x560bc2e512c4) user 1 builtin 12
1674230866.251965 [0]       csub: READER 110d552:bdf21630:38f55f5b:301c4 QOS={user_data=0<>,topic_name="DCPSTypeLookupReply",type_name="DDS::Builtin::TypeLookup_Reply",topic_data=0<>,group_data=0<>,durability=0,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:100000000,destination_order=0,history=1:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,partition={},time_based_filter=0,transport_priority=0,type_consistency_enforcement=1:11000,data_representation=1(0),adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.251971 [0]       csub: match_reader_with_proxy_writers(rd 110d552:bdf21630:38f55f5b:301c4) scanning proxy participants tgt=301c3
1674230866.251980 [0]       csub:  => EVERYONE
1674230866.251986 [0]       csub: spdp_write(110d552:bdf21630:38f55f5b:1c1)
1674230866.251995 [0]       csub: spdp_write(110d552:bdf21630:38f55f5b:1c1) - internals: simon-Virtual-Machine/0.11.0/Linux/Linux
1674230866.252034 [0]       csub: write_sample 110d552:bdf21630:38f55f5b:100c2 #1: ST0 DCPSParticipant/ParticipantBuiltinTopicData:{property_list={1:"__ProcessName":"csub",1:"__Pid":"51886",1:"__Hostname":"simon-Virtual-Machine"}:{},protocol_version=2:1,vendorid=1:16,participant_lease_duration=10000000000,participant_guid={110d552:bdf21630:38f55f5b:1c1},builtin_endpoint_set=64575,domain_id=0,default_unicast_locator={udp/192.168.234.128:52750},default_multicast_locator={udp/239.255.0.1:7401},metatraffic_unicast_locator={udp/192.168.234.128:52750},metatraffic_multicast_locator={udp/239.255.0.1:7400},adlink_participant_version_info=0:44:0:0:0:"simon-Virtual-Machine/0.11.0/Linux/Linux",cyclone_receive_buffer_size=425984}
1674230866.252081 [0]       csub: non-timed queue now has 1 items
1674230866.252161 [0]        tev: write_sample 110d552:bdf21630:38f55f5b:200c2 #1: ST0 DCPSParticipantMessage/ParticipantMessageData:{110d552:bdf21630:38f55f5b:1}:1<0>
1674230866.252181 [0]        tev: resched pmd(110d552:bdf21630:38f55f5b:1c1): 8s
1674230866.252193 [0]        tev: xpack_addmsg 0x7f089c000b60 0x560bc2e519f0 0(data(0:0:0:0:#0/1)): niov 0 sz 0 => now niov 3 sz 432
1674230866.252304 [0]       recv: HDR(110d552:bdf21630:38f55f5b vendor 1.16) len 432 from udp/192.168.234.128:51014
1674230866.252315 [0]       recv: INFOTS(1674230866.252012445)
1674230866.252329 [0]       recv: DATA(110d552:bdf21630:38f55f5b:100c2 -> 0:0:0:0 #1)
1674230866.252376 [0]         gc: gc 0x560bc2e4aaf0: deleting
1674230866.252403 [0] dq.builtin: data(builtin, vendor 1.16): 0:0:0:0 #1: ST0 /ParticipantBuiltinTopicData:{property_list={1:"__ProcessName":"csub",1:"__Pid":"51886",1:"__Hostname":"simon-Virtual-Machine"}:{},protocol_version=2:1,vendorid=1:16,participant_lease_duration=10000000000,participant_guid={110d552:bdf21630:38f55f5b:1c1},builtin_endpoint_set=64575,domain_id=0,default_unicast_locator={udp/192.168.234.128:52750},default_multicast_locator={udp/239.255.0.1:7401},metatraffic_unicast_locator={udp/192.168.234.128:52750},metatraffic_multicast_locator={udp/239.255.0.1:7400},adlink_participant_version_info=0:44:0:0:0:"simon-Virtual-Machine/0.11.0/Linux/Linux",cyclone_receive_buffer_size=425984}
1674230866.252414 [0] dq.builtin: SPDP ST0 110d552:bdf21630:38f55f5b:1c1 (local)
1674230866.252424 [0]        tev: nn_xpack_send 432: 0x7f089c000b6c:20 0x560bc2e51fc8:36 0x560bc2e51684:376 [ udp/239.255.0.1:7400@2 ]
1674230866.252430 [0]        tev: traffic-xmit (1) 432
1674230866.252532 [0]       csub: dds_create_topic_impl (pp 0x560bc2e52110 110d552:bdf21630:38f55f5b:1c1 sertype 0x560bc2e4a390 reg?no refc 1 Data/i11eperf::ou)
1674230866.252561 [0]       csub: lookup_and_check_ktopic_may_unlock_pp: no such ktopic
1674230866.252572 [0]       csub: create_and_lock_ktopic: ktp 0x560bc2e4a440
1674230866.252578 [0]       csub: dds_create_topic_impl: register new sertype 0x560bc2e4a390
1674230866.252621 [0]       csub: ref ddsi_type local sertype 0x560bc2e4a390 id [MINIMAL cc905e5bd3c6511586ad4761d0a0] new 0x560bc2e53330add dep <[MINIMAL cc905e5bd3c6511586ad4761d0a0], [INT32 0000000000000000000000000000]>
1674230866.252630 [0]       csub: ref ddsi_type type-id [INT32 0000000000000000000000000000] new 0x560bc2e53970 refc 1
1674230866.252638 [0]       csub: add dep <[MINIMAL cc905e5bd3c6511586ad4761d0a0], [UINT64 0000000000000000000000000000]>
1674230866.252645 [0]       csub: ref ddsi_type type-id [UINT64 0000000000000000000000000000] new 0x560bc2e53c30 refc 1
1674230866.252651 [0]       csub: add dep <[MINIMAL cc905e5bd3c6511586ad4761d0a0], [UINT32 0000000000000000000000000000]>
1674230866.252658 [0]       csub: ref ddsi_type type-id [UINT32 0000000000000000000000000000] new 0x560bc2e53ef0 refc 1
1674230866.252665 [0]       csub:  refc 1
1674230866.252671 [0]       csub: type [MINIMAL cc905e5bd3c6511586ad4761d0a0] resolved
1674230866.252698 [0]       csub: ref ddsi_type local sertype 0x560bc2e4a390 id [COMPLETE c10bd356b2ae460cce9208c1c65e] new 0x560bc2e54210add dep <[COMPLETE c10bd356b2ae460cce9208c1c65e], [INT32 0000000000000000000000000000]>
1674230866.252705 [0]       csub: ref ddsi_type type-id [INT32 0000000000000000000000000000] refc 2
1674230866.252712 [0]       csub: add dep <[COMPLETE c10bd356b2ae460cce9208c1c65e], [UINT64 0000000000000000000000000000]>
1674230866.252718 [0]       csub: ref ddsi_type type-id [UINT64 0000000000000000000000000000] refc 2
1674230866.252725 [0]       csub: add dep <[COMPLETE c10bd356b2ae460cce9208c1c65e], [UINT32 0000000000000000000000000000]>
1674230866.252731 [0]       csub: ref ddsi_type type-id [UINT32 0000000000000000000000000000] refc 2
1674230866.252736 [0]       csub:  refc 1
1674230866.252742 [0]       csub: type [COMPLETE c10bd356b2ae460cce9208c1c65e] resolved
1674230866.252761 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:14d @ 0x560bc2e52c24) user 2 builtin 12
1674230866.252783 [0]       csub: TOPIC 110d552:bdf21630:38f55f5b:14d QOS={topic_name="Data",type_name="i11eperf::ou",durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,transport_priority=0,data_representation=1(0),type_information=[MINIMAL cc905e5bd3c6511586ad4761d0a0]/[COMPLETE c10bd356b2ae460cce9208c1c65e]}
1674230866.252814 [0]       csub: ref ddsi_type local sertype 0x560bc2e4a390 id [MINIMAL cc905e5bd3c6511586ad4761d0a0] refc 2
1674230866.252828 [0]       csub: type [MINIMAL cc905e5bd3c6511586ad4761d0a0] resolved
1674230866.252844 [0]       csub: ref ddsi_type local sertype 0x560bc2e4a390 id [COMPLETE c10bd356b2ae460cce9208c1c65e] refc 2
1674230866.252850 [0]       csub: type [COMPLETE c10bd356b2ae460cce9208c1c65e] resolved
1674230866.252878 [0]       csub:  topic-definition 0x0x560bc2e55550: key 0x53017898f6419fc587c3861226471ad3 QOS={topic_name="Data",type_name="i11eperf::ou",durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=0:100000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,presentation=0:0:0,transport_priority=0,data_representation=1(0),type_information=[MINIMAL cc905e5bd3c6511586ad4761d0a0]/[COMPLETE c10bd356b2ae460cce9208c1c65e]}
1674230866.252887 [0]       csub:  => EVERYONE
1674230866.252895 [0]       csub: dds_create_topic_impl: new topic 946623642
1674230866.253081 [0]       csub: new_reader(guid 110d552:bdf21630:38f55f5b:207, (default).Data/i11eperf::ou)
1674230866.253100 [0]       csub: ddsi_ref_participant(110d552:bdf21630:38f55f5b:1c1 @ 0x560bc2e4a4a0 <- 110d552:bdf21630:38f55f5b:207 @ 0x560bc2e56d14) user 3 builtin 12
1674230866.253116 [0]       csub: ref ddsi_type local sertype 0x560bc2e4a390 id [MINIMAL cc905e5bd3c6511586ad4761d0a0] refc 3
1674230866.253133 [0]       csub: ref ddsi_type local sertype 0x560bc2e4a390 id [COMPLETE c10bd356b2ae460cce9208c1c65e] refc 3
1674230866.253178 [0]       csub: READER 110d552:bdf21630:38f55f5b:207 QOS={user_data=0<>,topic_name="Data",type_name="i11eperf::ou",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:10000000000,lifespan=9223372036854775807,destination_order=0,history=1:1,resource_limits=436906:-1:-1,ownership=0,presentation=0:0:0,partition={""},time_based_filter=0,transport_priority=0,type_consistency_enforcement=0:00010,data_representation=1(0),adlink_entity_factory=1,adlink_reader_lifespan=0:9223372036854775807,adlink_reader_data_lifecycle=9223372036854775807:9223372036854775807}
1674230866.253193 [0]       csub:  => EVERYONE
1674230866.253201 [0]       csub: match_reader_with_proxy_writers(rd 110d552:bdf21630:38f55f5b:207) scanning all pwrs of topic Data
1674230866.253207 [0]       csub: match_reader_with_writers(rd 110d552:bdf21630:38f55f5b:207) scanning all wrs of topic Data
1674230866.253252 [0]       csub: write_sample 110d552:bdf21630:38f55f5b:4c2 #1: ST0 DCPSSubscription/SubscriptionBuiltinTopicData:{topic_name="Data",type_name="i11eperf::ou",durability_service=0:0:1:-1:-1:-1,reliability=1:10000000000,lifespan=9223372036854775807,history=1:1,resource_limits=436906:-1:-1,type_consistency_enforcement=0:00010,type_information=[MINIMAL cc905e5bd3c6511586ad4761d0a0]/[COMPLETE c10bd356b2ae460cce9208c1c65e],protocol_version=2:1,vendorid=1:16,endpoint_guid={110d552:bdf21630:38f55f5b:207},adlink_entity_factory=1}
1674230866.253310 [0]         gc: gc 0x560bc2e55cd0: deleting
1674230866.265116 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 336 from udp/192.168.234.128:43618
1674230866.265144 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230866.265153 [0]     recvUC: HEARTBEAT(#1:1..0 1030015:5d380101:caa8038e:200c2? -> 110d552:bdf21630:38f55f5b:200c7)
1674230866.265158 [0]     recvUC: HEARTBEAT(#1:1..0 1030015:5d380101:caa8038e:4c2? -> 110d552:bdf21630:38f55f5b:4c7)
1674230866.265164 [0]     recvUC: HEARTBEAT(#1:1..1 1030015:5d380101:caa8038e:3c2? -> 110d552:bdf21630:38f55f5b:3c7)
1674230866.265169 [0]     recvUC: HEARTBEAT(#1:1..0 1030015:5d380101:caa8038e:301c3? -> 110d552:bdf21630:38f55f5b:301c4)
1674230866.265174 [0]     recvUC: HEARTBEAT(#1:1..0 1030015:5d380101:caa8038e:300c3? -> 110d552:bdf21630:38f55f5b:300c4)
1674230866.265180 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230866.265196 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230866.265202 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230866.265207 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230866.265212 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230866.352261 [0]        tev: non-timed queue now has 1 items
1674230866.352285 [0]        tev: xmit spdp 110d552:bdf21630:38f55f5b:1c1 to 0:0:0:100c7 (resched 8s)
1674230866.352291 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f089c001e80 0(data(0:0:0:0:#0/1)): niov 0 sz 0 => now niov 3 sz 432
1674230866.352442 [0]        tev: nn_xpack_send 432: 0x7f089c000b6c:20 0x7f089c001f78:36 0x560bc2e51684:376 [ udp/239.255.0.1:7400@2 ]
1674230866.352448 [0]        tev: traffic-xmit (1) 432
1674230866.352501 [0]       recv: HDR(110d552:bdf21630:38f55f5b vendor 1.16) len 432 from udp/192.168.234.128:51014
1674230866.352707 [0]       recv: INFOTS(1674230866.252012445)
1674230866.352783 [0]       recv: DATA(110d552:bdf21630:38f55f5b:100c2 -> 0:0:0:0 #1)
1674230866.352824 [0] dq.builtin: data(builtin, vendor 1.16): 0:0:0:0 #1: ST0 /ParticipantBuiltinTopicData:{property_list={1:"__ProcessName":"csub",1:"__Pid":"51886",1:"__Hostname":"simon-Virtual-Machine"}:{},protocol_version=2:1,vendorid=1:16,participant_lease_duration=10000000000,participant_guid={110d552:bdf21630:38f55f5b:1c1},builtin_endpoint_set=64575,domain_id=0,default_unicast_locator={udp/192.168.234.128:52750},default_multicast_locator={udp/239.255.0.1:7401},metatraffic_unicast_locator={udp/192.168.234.128:52750},metatraffic_multicast_locator={udp/239.255.0.1:7400},adlink_participant_version_info=0:44:0:0:0:"simon-Virtual-Machine/0.11.0/Linux/Linux",cyclone_receive_buffer_size=425984}
1674230866.352838 [0] dq.builtin: SPDP ST0 110d552:bdf21630:38f55f5b:1c1 (local)
1674230866.465466 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 336 from udp/192.168.234.128:43618
1674230866.465533 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230866.465556 [0]     recvUC: HEARTBEAT(#2:1..0 1030015:5d380101:caa8038e:200c2? -> 110d552:bdf21630:38f55f5b:200c7)
1674230866.465573 [0]     recvUC: HEARTBEAT(#2:1..0 1030015:5d380101:caa8038e:4c2? -> 110d552:bdf21630:38f55f5b:4c7)
1674230866.465589 [0]     recvUC: HEARTBEAT(#2:1..1 1030015:5d380101:caa8038e:3c2? -> 110d552:bdf21630:38f55f5b:3c7)
1674230866.465606 [0]     recvUC: HEARTBEAT(#2:1..0 1030015:5d380101:caa8038e:301c3? -> 110d552:bdf21630:38f55f5b:301c4)
1674230866.465622 [0]     recvUC: HEARTBEAT(#2:1..0 1030015:5d380101:caa8038e:300c3? -> 110d552:bdf21630:38f55f5b:300c4)
1674230866.465640 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230866.465657 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230866.465673 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230866.465689 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230866.465706 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230866.664955 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 336 from udp/192.168.234.128:43618
1674230866.664979 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230866.664984 [0]     recvUC: HEARTBEAT(#3:1..0 1030015:5d380101:caa8038e:200c2? -> 110d552:bdf21630:38f55f5b:200c7)
1674230866.664999 [0]     recvUC: HEARTBEAT(#3:1..0 1030015:5d380101:caa8038e:4c2? -> 110d552:bdf21630:38f55f5b:4c7)
1674230866.665006 [0]     recvUC: HEARTBEAT(#3:1..1 1030015:5d380101:caa8038e:3c2? -> 110d552:bdf21630:38f55f5b:3c7)
1674230866.665010 [0]     recvUC: HEARTBEAT(#3:1..0 1030015:5d380101:caa8038e:301c3? -> 110d552:bdf21630:38f55f5b:301c4)
1674230866.665013 [0]     recvUC: HEARTBEAT(#3:1..0 1030015:5d380101:caa8038e:300c3? -> 110d552:bdf21630:38f55f5b:300c4)
1674230866.665027 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230866.665030 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230866.665033 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230866.665036 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230866.665040 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230866.864975 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 336 from udp/192.168.234.128:43618
1674230866.864997 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230866.865002 [0]     recvUC: HEARTBEAT(#4:1..0 1030015:5d380101:caa8038e:200c2? -> 110d552:bdf21630:38f55f5b:200c7)
1674230866.865006 [0]     recvUC: HEARTBEAT(#4:1..0 1030015:5d380101:caa8038e:4c2? -> 110d552:bdf21630:38f55f5b:4c7)
1674230866.865009 [0]     recvUC: HEARTBEAT(#4:1..1 1030015:5d380101:caa8038e:3c2? -> 110d552:bdf21630:38f55f5b:3c7)
1674230866.865012 [0]     recvUC: HEARTBEAT(#4:1..0 1030015:5d380101:caa8038e:301c3? -> 110d552:bdf21630:38f55f5b:301c4)
1674230866.865015 [0]     recvUC: HEARTBEAT(#4:1..0 1030015:5d380101:caa8038e:300c3? -> 110d552:bdf21630:38f55f5b:300c4)
1674230866.865019 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230866.865036 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230866.865041 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230866.865044 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230866.865048 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230867.066375 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230867.066443 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230867.066466 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230867.066483 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230867.066500 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230867.066516 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230867.066533 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230867.266513 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 336 from udp/192.168.234.128:43618
1674230867.266539 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230867.266546 [0]     recvUC: HEARTBEAT(#5:1..0 1030015:5d380101:caa8038e:200c2? -> 110d552:bdf21630:38f55f5b:200c7)
1674230867.266550 [0]     recvUC: HEARTBEAT(#5:1..0 1030015:5d380101:caa8038e:4c2? -> 110d552:bdf21630:38f55f5b:4c7)
1674230867.266555 [0]     recvUC: HEARTBEAT(#5:1..1 1030015:5d380101:caa8038e:3c2? -> 110d552:bdf21630:38f55f5b:3c7)
1674230867.266559 [0]     recvUC: HEARTBEAT(#5:1..0 1030015:5d380101:caa8038e:301c3? -> 110d552:bdf21630:38f55f5b:301c4)
1674230867.266563 [0]     recvUC: HEARTBEAT(#5:1..0 1030015:5d380101:caa8038e:300c3? -> 110d552:bdf21630:38f55f5b:300c4)
1674230867.266568 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230867.266572 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230867.266576 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230867.266580 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230867.266584 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230867.266604 [0]     recvUC: thread_cputime 0.000000000
1674230867.466951 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230867.466981 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230867.466988 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230867.466994 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230867.466999 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230867.467003 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230867.467009 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230867.667321 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230867.667343 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230867.667348 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230867.667352 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230867.667355 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230867.667358 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230867.667361 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230867.867732 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 336 from udp/192.168.234.128:43618
1674230867.867867 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230867.867898 [0]     recvUC: HEARTBEAT(#6:1..0 1030015:5d380101:caa8038e:200c2? -> 110d552:bdf21630:38f55f5b:200c7)
1674230867.867916 [0]     recvUC: HEARTBEAT(#6:1..0 1030015:5d380101:caa8038e:4c2? -> 110d552:bdf21630:38f55f5b:4c7)
1674230867.867932 [0]     recvUC: HEARTBEAT(#6:1..1 1030015:5d380101:caa8038e:3c2? -> 110d552:bdf21630:38f55f5b:3c7)
1674230867.867949 [0]     recvUC: HEARTBEAT(#6:1..0 1030015:5d380101:caa8038e:301c3? -> 110d552:bdf21630:38f55f5b:301c4)
1674230867.867965 [0]     recvUC: HEARTBEAT(#6:1..0 1030015:5d380101:caa8038e:300c3? -> 110d552:bdf21630:38f55f5b:300c4)
1674230867.867984 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230867.868000 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230867.868016 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230867.868032 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230867.868049 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230868.068851 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230868.068874 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230868.068879 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230868.068883 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230868.068887 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230868.068890 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230868.068894 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230868.270122 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230868.270189 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230868.270211 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230868.270266 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230868.270283 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230868.270299 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230868.270316 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230868.270339 [0]     recvUC: thread_cputime 0.000000000
1674230868.471058 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230868.471130 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230868.471153 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230868.471170 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230868.471187 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230868.471203 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230868.471220 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230868.671396 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230868.671422 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230868.671428 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230868.671432 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230868.671437 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230868.671441 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230868.671445 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230868.868264 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 336 from udp/192.168.234.128:43618
1674230868.868333 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230868.868355 [0]     recvUC: HEARTBEAT(#7:1..0 1030015:5d380101:caa8038e:200c2? -> 110d552:bdf21630:38f55f5b:200c7)
1674230868.868372 [0]     recvUC: HEARTBEAT(#7:1..0 1030015:5d380101:caa8038e:4c2? -> 110d552:bdf21630:38f55f5b:4c7)
1674230868.868389 [0]     recvUC: HEARTBEAT(#7:1..1 1030015:5d380101:caa8038e:3c2? -> 110d552:bdf21630:38f55f5b:3c7)
1674230868.868405 [0]     recvUC: HEARTBEAT(#7:1..0 1030015:5d380101:caa8038e:301c3? -> 110d552:bdf21630:38f55f5b:301c4)
1674230868.868421 [0]     recvUC: HEARTBEAT(#7:1..0 1030015:5d380101:caa8038e:300c3? -> 110d552:bdf21630:38f55f5b:300c4)
1674230868.868440 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230868.868456 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230868.868473 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230868.868489 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230868.868506 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230869.072711 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 176 from udp/192.168.234.128:43618
1674230869.072778 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230869.072801 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:301c4? -> 110d552:bdf21630:38f55f5b:301c3)
1674230869.072818 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:300c4? -> 110d552:bdf21630:38f55f5b:300c3)
1674230869.072835 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:4c7? -> 110d552:bdf21630:38f55f5b:4c2)
1674230869.072851 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:200c7? -> 110d552:bdf21630:38f55f5b:200c2)
1674230869.072905 [0]     recvUC: ACKNACK(#0:1/0: 1030015:5d380101:caa8038e:3c7? -> 110d552:bdf21630:38f55f5b:3c2)
1674230869.262781 [0]       recv: HDR(1030015:5d380101:caa8038e vendor 1.3) len 296 from udp/192.168.234.128:7410
1674230869.263173 [0]       recv: DATA(1030015:5d380101:caa8038e:100c2 -> 0:0:0:0 #2)
1674230869.263225 [0]       recv: thread_cputime 0.000000000
1674230869.263377 [0] dq.builtin: data(builtin, vendor 1.3): 0:0:0:0 #2: ST0 /ParticipantBuiltinTopicData:{protocol_version=2:4,vendorid=1:3,participant_manual_liveliness_count=0,participant_builtin_endpoints=64575,participant_lease_duration=300000000000,participant_guid={1030015:5d380101:caa8038e:1c1},builtin_endpoint_set=64575,domain_id=0,default_unicast_locator={udp/127.0.0.1:12345},default_multicast_locator={udp/127.0.0.1:12345},metatraffic_unicast_locator={udp/192.168.234.128:43618,udp/172.17.0.1:43618},metatraffic_multicast_locator={udp/239.255.0.1:7402}}
1674230869.263507 [0] dq.builtin: SPDP ST0 1030015:5d380101:caa8038e:1c1 bes fc3f NEW (data udp/127.0.0.1:12345@2 meta udp/239.255.0.1:7402@2 udp/172.17.0.1:43618@2 udp/192.168.234.128:43618@2) QOS={}
1674230869.263539 [0] dq.builtin: lease_new(tdur 300000000000 guid 1030015:5d380101:caa8038e:1c1) @ 0x7f0894001300
1674230869.263556 [0] dq.builtin: lease_new(tdur 300000000000 guid 1030015:5d380101:caa8038e:1c1) @ 0x7f0894001380
1674230869.263682 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:caa8038e:100c7) scanning participants tgt=0
1674230869.263737 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:caa8038e:3c2) scanning participants tgt=3c7
1674230869.263759 [0] dq.builtin:   reader 110d552:bdf21630:38f55f5b:3c7 init_acknack_count = 1
1674230869.263775 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:caa8038e:3c2 rd 110d552:bdf21630:38f55f5b:3c7)
1674230869.263894 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:caa8038e:3c2 rd 110d552:bdf21630:38f55f5b:3c7) - out-of-sync
1674230869.263944 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:caa8038e:3c7) scanning participants tgt=3c2
1674230869.263965 [0] dq.builtin:   proxy_reader_add_connection(wr 110d552:bdf21630:38f55f5b:3c2 prd 1030015:5d380101:caa8038e:3c7)
1674230869.263983 [0] dq.builtin:   writer_add_connection(wr 110d552:bdf21630:38f55f5b:3c2 prd 1030015:5d380101:caa8038e:3c7) - ack seq 0
1674230869.264015 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:43618@2 udp/192.168.234.128:43618@2
1674230869.264048 [0] dq.builtin: reduced nlocs=3
1674230869.264017 [0]        tev: thread_cputime 0.000000000
1674230869.264068 [0] dq.builtin: nloopback = 0, nlocs = 3, redundant_networking = 0
1674230869.264383 [0] dq.builtin: rdidx 0 lidx udp/172.17.0.1:43618@2 0 -> 0
1674230869.264407 [0] dq.builtin: rdidx 0 lidx udp/192.168.234.128:43618@2 1 -> 0
1674230869.264425 [0] dq.builtin: rdidx 0 lidx udp/239.255.0.1:7402@2 2 -> 8
1674230869.264447 [0] dq.builtin:                                                                   a
1674230869.264469 [0] dq.builtin:   loc  0 = udp/172.17.0.1:43618@2                            1 { +u  }
1674230869.264552 [0] dq.builtin:   loc  1 = udp/192.168.234.128:43618@2                       1 { +u  }
1674230869.264573 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674230869.264646 [0] dq.builtin:   best = 0
1674230869.264670 [0] dq.builtin:   simple udp/172.17.0.1:43618@2
1674230869.264700 [0] dq.builtin: ddsi_rebuild_writer_addrset(110d552:bdf21630:38f55f5b:3c2): udp/172.17.0.1:43618@2 (burst size 4294901760 rexmit 283990)
1674230869.264837 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:caa8038e:4c2) scanning participants tgt=4c7
1674230869.264871 [0] dq.builtin:   reader 110d552:bdf21630:38f55f5b:4c7 init_acknack_count = 1
1674230869.264888 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:caa8038e:4c2 rd 110d552:bdf21630:38f55f5b:4c7)
1674230869.264909 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:caa8038e:4c2 rd 110d552:bdf21630:38f55f5b:4c7) - out-of-sync
1674230869.264975 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:caa8038e:4c7) scanning participants tgt=4c2
1674230869.264996 [0] dq.builtin:   proxy_reader_add_connection(wr 110d552:bdf21630:38f55f5b:4c2 prd 1030015:5d380101:caa8038e:4c7)
1674230869.265013 [0] dq.builtin:   writer_add_connection(wr 110d552:bdf21630:38f55f5b:4c2 prd 1030015:5d380101:caa8038e:4c7) - ack seq 1
1674230869.265043 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:43618@2 udp/192.168.234.128:43618@2
1674230869.265061 [0] dq.builtin: reduced nlocs=3
1674230869.265080 [0] dq.builtin: nloopback = 0, nlocs = 3, redundant_networking = 0
1674230869.265098 [0] dq.builtin: rdidx 0 lidx udp/172.17.0.1:43618@2 0 -> 0
1674230869.265114 [0] dq.builtin: rdidx 0 lidx udp/192.168.234.128:43618@2 1 -> 0
1674230869.265130 [0] dq.builtin: rdidx 0 lidx udp/239.255.0.1:7402@2 2 -> 8
1674230869.265147 [0] dq.builtin:                                                                   a
1674230869.265166 [0] dq.builtin:   loc  0 = udp/172.17.0.1:43618@2                            1 { +u  }
1674230869.265184 [0] dq.builtin:   loc  1 = udp/192.168.234.128:43618@2                       1 { +u  }
1674230869.265202 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674230869.265215 [0] dq.builtin:   best = 0
1674230869.265231 [0] dq.builtin:   simple udp/172.17.0.1:43618@2
1674230869.265252 [0] dq.builtin: ddsi_rebuild_writer_addrset(110d552:bdf21630:38f55f5b:4c2): udp/172.17.0.1:43618@2 (burst size 4294901760 rexmit 283990)
1674230869.265280 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:caa8038e:200c2) scanning participants tgt=200c7
1674230869.265298 [0] dq.builtin:   reader 110d552:bdf21630:38f55f5b:200c7 init_acknack_count = 1
1674230869.265313 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:caa8038e:200c2 rd 110d552:bdf21630:38f55f5b:200c7)
1674230869.265331 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:caa8038e:200c2 rd 110d552:bdf21630:38f55f5b:200c7) - out-of-sync
1674230869.265355 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:caa8038e:200c7) scanning participants tgt=200c2
1674230869.265372 [0] dq.builtin:   proxy_reader_add_connection(wr 110d552:bdf21630:38f55f5b:200c2 prd 1030015:5d380101:caa8038e:200c7)
1674230869.265389 [0] dq.builtin:   writer_add_connection(wr 110d552:bdf21630:38f55f5b:200c2 prd 1030015:5d380101:caa8038e:200c7) - ack seq 1
1674230869.265413 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:43618@2 udp/192.168.234.128:43618@2
1674230869.265429 [0] dq.builtin: reduced nlocs=3
1674230869.265445 [0] dq.builtin: nloopback = 0, nlocs = 3, redundant_networking = 0
1674230869.265462 [0] dq.builtin: rdidx 0 lidx udp/172.17.0.1:43618@2 0 -> 0
1674230869.265478 [0] dq.builtin: rdidx 0 lidx udp/192.168.234.128:43618@2 1 -> 0
1674230869.265494 [0] dq.builtin: rdidx 0 lidx udp/239.255.0.1:7402@2 2 -> 8
1674230869.265509 [0] dq.builtin:                                                                   a
1674230869.265527 [0] dq.builtin:   loc  0 = udp/172.17.0.1:43618@2                            1 { +u  }
1674230869.265544 [0] dq.builtin:   loc  1 = udp/192.168.234.128:43618@2                       1 { +u  }
1674230869.265562 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674230869.265629 [0] dq.builtin:   best = 0
1674230869.265658 [0] dq.builtin:   simple udp/172.17.0.1:43618@2
1674230869.265685 [0] dq.builtin: ddsi_rebuild_writer_addrset(110d552:bdf21630:38f55f5b:200c2): udp/172.17.0.1:43618@2 (burst size 4294901760 rexmit 283990)
1674230869.265742 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:caa8038e:300c3) scanning participants tgt=300c4
1674230869.265761 [0] dq.builtin:   reader 110d552:bdf21630:38f55f5b:300c4 init_acknack_count = 1
1674230869.265777 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:caa8038e:300c3 rd 110d552:bdf21630:38f55f5b:300c4)
1674230869.265811 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:caa8038e:300c3 rd 110d552:bdf21630:38f55f5b:300c4)
1674230869.265838 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:caa8038e:300c4) scanning participants tgt=300c3
1674230869.265856 [0] dq.builtin:   proxy_reader_add_connection(wr 110d552:bdf21630:38f55f5b:300c3 prd 1030015:5d380101:caa8038e:300c4)
1674230869.265872 [0] dq.builtin:   writer_add_connection(wr 110d552:bdf21630:38f55f5b:300c3 prd 1030015:5d380101:caa8038e:300c4) - ack seq 0
1674230869.265899 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:43618@2 udp/192.168.234.128:43618@2
1674230869.265916 [0] dq.builtin: reduced nlocs=3
1674230869.265932 [0] dq.builtin: nloopback = 0, nlocs = 3, redundant_networking = 0
1674230869.265949 [0] dq.builtin: rdidx 0 lidx udp/172.17.0.1:43618@2 0 -> 0
1674230869.265966 [0] dq.builtin: rdidx 0 lidx udp/192.168.234.128:43618@2 1 -> 0
1674230869.265983 [0] dq.builtin: rdidx 0 lidx udp/239.255.0.1:7402@2 2 -> 8
1674230869.265987 [0]        tev: writer_hbcontrol: wr 110d552:bdf21630:38f55f5b:3c2 unicasting to prd 1030015:5d380101:caa8038e:3c7 (rel-prd 1 seq-eq-max 0 seq 0 maxseq 0)
1674230869.266145 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:3c2) sent, resched in 0.1 s (min-ack 0!, avail-seq 0, xmit 0)
1674230869.265999 [0] dq.builtin:                                                                   a
1674230869.266313 [0] dq.builtin:   loc  0 = udp/172.17.0.1:43618@2                            1 { +u  }
1674230869.266343 [0] dq.builtin:   loc  1 = udp/192.168.234.128:43618@2                       1 { +u  }
1674230869.266180 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f089c001e80 0(control): niov 0 sz 0 => now niov 2 sz 68
1674230869.266501 [0]        tev: writer_hbcontrol: wr 110d552:bdf21630:38f55f5b:4c2 unicasting to prd 1030015:5d380101:caa8038e:4c7 (rel-prd 1 seq-eq-max 0 seq 1 maxseq 1)
1674230869.266366 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674230869.266539 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:4c2) sent, resched in 0.1 s (min-ack 1!, avail-seq 1, xmit 1)
1674230869.266627 [0] dq.builtin:   best = 0
1674230869.266678 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f089c002080 0(control): niov 2 sz 68 => now niov 3 sz 100
1674230869.266695 [0] dq.builtin:   simple udp/172.17.0.1:43618@2
1674230869.266735 [0] dq.builtin: ddsi_rebuild_writer_addrset(110d552:bdf21630:38f55f5b:300c3): udp/172.17.0.1:43618@2 (burst size 4294901760 rexmit 283990)
1674230869.266775 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:caa8038e:301c3) scanning participants tgt=301c4
1674230869.266836 [0] dq.builtin:   reader 110d552:bdf21630:38f55f5b:301c4 init_acknack_count = 1
1674230869.266853 [0] dq.builtin:   reader_add_connection(pwr 1030015:5d380101:caa8038e:301c3 rd 110d552:bdf21630:38f55f5b:301c4)
1674230869.266873 [0] dq.builtin:   proxy_writer_add_connection(pwr 1030015:5d380101:caa8038e:301c3 rd 110d552:bdf21630:38f55f5b:301c4)
1674230869.266901 [0]        tev: nn_xpack_send 100: 0x7f089c000b6c:20 0x7f089c001f68:48 0x7f089c002178:32 [ udp/192.168.234.128:43618@2 ]
1674230869.266939 [0]        tev: traffic-xmit (1) 100
1674230869.266918 [0] dq.builtin: match_proxy_reader_with_writers(prd 1030015:5d380101:caa8038e:301c4) scanning participants tgt=301c3
1674230869.266975 [0]        tev: writer_hbcontrol: wr 110d552:bdf21630:38f55f5b:200c2 unicasting to prd 1030015:5d380101:caa8038e:200c7 (rel-prd 1 seq-eq-max 0 seq 1 maxseq 1)
1674230869.266981 [0] dq.builtin:   proxy_reader_add_connection(wr 110d552:bdf21630:38f55f5b:301c3 prd 1030015:5d380101:caa8038e:301c4)
1674230869.267034 [0] dq.builtin:   writer_add_connection(wr 110d552:bdf21630:38f55f5b:301c3 prd 1030015:5d380101:caa8038e:301c4) - ack seq 0
1674230869.267004 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:200c2) sent, resched in 0.1 s (min-ack 1!, avail-seq 1, xmit 1)
1674230869.267068 [0] dq.builtin: setcover: all_addrs udp/239.255.0.1:7402@2 udp/172.17.0.1:43618@2 udp/192.168.234.128:43618@2
1674230869.267204 [0] dq.builtin: reduced nlocs=3
1674230869.267237 [0] dq.builtin: nloopback = 0, nlocs = 3, redundant_networking = 0
1674230869.267257 [0] dq.builtin: rdidx 0 lidx udp/172.17.0.1:43618@2 0 -> 0
1674230869.267274 [0] dq.builtin: rdidx 0 lidx udp/192.168.234.128:43618@2 1 -> 0
1674230869.267290 [0] dq.builtin: rdidx 0 lidx udp/239.255.0.1:7402@2 2 -> 8
1674230869.267307 [0] dq.builtin:                                                                   a
1674230869.267325 [0] dq.builtin:   loc  0 = udp/172.17.0.1:43618@2                            1 { +u  }
1674230869.267342 [0] dq.builtin:   loc  1 = udp/192.168.234.128:43618@2                       1 { +u  }
1674230869.267359 [0] dq.builtin:   loc  2 = udp/239.255.0.1:7402@2                            2 { +1  }
1674230869.267372 [0] dq.builtin:   best = 0
1674230869.267388 [0] dq.builtin:   simple udp/172.17.0.1:43618@2
1674230869.267083 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f089c001e80 0(control): niov 0 sz 0 => now niov 2 sz 68
1674230869.267409 [0] dq.builtin: ddsi_rebuild_writer_addrset(110d552:bdf21630:38f55f5b:301c3): udp/172.17.0.1:43618@2 (burst size 4294901760 rexmit 283990)
1674230869.267480 [0] dq.builtin:  => EVERYONE
1674230869.267500 [0] dq.builtin: lease_register(l 0x7f0894001380 guid 1030015:5d380101:caa8038e:1c1)
1674230869.267594 [0] dq.builtin: broadcasted SPDP packet -> answering 0
1674230869.267642 [0] dq.builtin: thread_cputime 0.003412000
1674230869.267650 [0]         gc: gc 0x7f0894005690: not yet, shortsleep
1674230869.267604 [0]        tev: nn_xpack_send 68: 0x7f089c000b6c:20 0x7f089c001f68:48 [ udp/192.168.234.128:43618@2 ]
1674230869.267827 [0]        tev: traffic-xmit (1) 68
1674230869.267869 [0]        tev: non-timed queue now has 1 items
1674230869.267894 [0]        tev: xmit spdp 110d552:bdf21630:38f55f5b:1c1 to 1030015:5d380101:caa8038e:100c7 (resched 1s)
1674230869.267919 [0]        tev: writer_hbcontrol: wr 110d552:bdf21630:38f55f5b:300c3 unicasting to prd 1030015:5d380101:caa8038e:300c4 (rel-prd 1 seq-eq-max 0 seq 0 maxseq 0)
1674230869.267943 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:300c3) sent, resched in 0.1 s (min-ack 0!, avail-seq 0, xmit 0)
1674230869.267963 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f089c002080 0(control): niov 0 sz 0 => now niov 2 sz 68
1674230869.267983 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f089c001e80 0(data(0:0:0:0:#0/1)): niov 2 sz 68 => now niov 4 sz 480
1674230869.268061 [0]        tev: nn_xpack_send 480: 0x7f089c000b6c:20 0x7f089c002168:48 0x7f089c001f78:36 0x560bc2e51684:376 [ udp/192.168.234.128:43618@2 ]
1674230869.268079 [0]        tev: traffic-xmit (1) 480
1674230869.268641 [0]        tev: writer_hbcontrol: wr 110d552:bdf21630:38f55f5b:301c3 unicasting to prd 1030015:5d380101:caa8038e:301c4 (rel-prd 1 seq-eq-max 0 seq 0 maxseq 0)
1674230869.268696 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:301c3) sent, resched in 0.1 s (min-ack 0!, avail-seq 0, xmit 0)
1674230869.268720 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f089c002080 0(control): niov 0 sz 0 => now niov 2 sz 68
1674230869.268805 [0]        tev: nn_xpack_send 68: 0x7f089c000b6c:20 0x7f089c002168:48 [ udp/192.168.234.128:43618@2 ]
1674230869.268824 [0]        tev: traffic-xmit (1) 68
1674230869.268908 [0]         gc: thread_cputime 0.000322000
1674230869.268953 [0]         gc: gc 0x7f0894005690: deleting
1674230869.272628 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 184 from udp/192.168.234.128:43618
1674230869.272640 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230869.272651 [0]     recvUC: ACKNACK(F#1:1/0: L(:1c1 209077.354925) 1030015:5d380101:caa8038e:301c4 -> 110d552:bdf21630:38f55f5b:301c3 setting-has-replied-to-hb happy-now)
1674230869.272656 [0]     recvUC: ACKNACK(F#1:1/0: 1030015:5d380101:caa8038e:300c4 -> 110d552:bdf21630:38f55f5b:300c3 setting-has-replied-to-hb happy-now)
1674230869.272714 [0]     recvUC: ACKNACK(#1:1/1:1 1030015:5d380101:caa8038e:4c7 -> 110d552:bdf21630:38f55f5b:4c2 complying RX1non-timed queue now has 1 items
1674230869.272728 [0]     recvUC:  rexmit#1 maxseq:1<1<=1defer_heartbeat_to_peer: 110d552:bdf21630:38f55f5b:4c2 -> 1030015:5d380101:caa8038e:4c7 - queue for transmit
1674230869.272733 [0]     recvUC: )
1674230869.272743 [0]     recvUC: ACKNACK(#1:1/1:1 1030015:5d380101:caa8038e:200c7 -> 110d552:bdf21630:38f55f5b:200c2 complying RX1non-timed queue now has 2 items
1674230869.272748 [0]     recvUC:  rexmit#1 maxseq:1<1<=1defer_heartbeat_to_peer: 110d552:bdf21630:38f55f5b:200c2 -> 1030015:5d380101:caa8038e:200c7 - queue for transmit
1674230869.272751 [0]     recvUC: non-timed queue now has 3 items
1674230869.272755 [0]     recvUC: )
1674230869.272760 [0]     recvUC: ACKNACK(F#1:1/0: 1030015:5d380101:caa8038e:3c7 -> 110d552:bdf21630:38f55f5b:3c2 setting-has-replied-to-hb happy-now)
1674230869.272763 [0]     recvUC: send_deferred_heartbeat: 3279ab2611e27ffc -> 32c8f2d697ae16dc - queue for transmit
1674230869.272766 [0]     recvUC: non-timed queue now has 3 items
1674230869.272772 [0]     recvUC: thread_cputime 0.000000000
1674230869.272772 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000b60 0(rexmit(110d552:bdf21630:38f55f5b:4c2:#1/1)): niov 0 sz 0 => now niov 3 sz 368
1674230869.272793 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000eb0 0(rexmit(110d552:bdf21630:38f55f5b:200c2:#1/1)): niov 3 sz 368 => now niov 5 sz 432
1674230869.272798 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000d30 0(control): niov 5 sz 432 => now niov 6 sz 464
1674230869.272802 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f08840010d0 0(control): niov 6 sz 464 => now niov 7 sz 496
1674230869.272845 [0]        tev: nn_xpack_send 496: 0x7f089c000b6c:20 0x7f0884000c48:52 0x560bc2e57ca4:296 0x7f0884000fa8:36 0x7f089c000c9c:28 0x7f0884000e28:32 0x7f08840011c8:32 [ udp/192.168.234.128:43618@2 ]
1674230869.272852 [0]        tev: traffic-xmit (1) 496
1674230869.273727 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 199 from udp/192.168.234.128:43618
1674230869.273739 [0]     recvUC: INFOTS(1674230869.273487000)
1674230869.273744 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230869.273752 [0]     recvUC: DATA(1030015:5d380101:caa8038e:300c3 -> 110d552:bdf21630:38f55f5b:300c4 #1 L(:1c1 209077.356024))
1674230869.273843 [0] dq.builtin: data(builtin, vendor 1.3): 1030015:5d380101:caa8038e:300c3 #1: ST0 DCPSTypeLookupRequest/DDS::Builtin::TypeLookup_Request:{{{{{1,3,0,21,93,56,1,1,202,168,3,142},{{0,3,0},195}},{0,1}},"dds.builtin.TOS.0110d552bdf2163038f55f5b000001c1"},{dlh:3925318099:{pl:31,lc:4,m:206790757,{{241:{204,144,94,91,211,198,81,21,134,173,71,97,208,160}}}}}}
1674230869.273875 [0] dq.builtin:  handle-tl-req wr 15000301:101385d:8e03a8ca:c3000300 seqnr 1 ntypeids 1 id [MINIMAL cc905e5bd3c6511586ad4761d0a0] tl-reply wr 110d552:bdf21630:38f55f5b:301c3
1674230869.273880 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000b60 0(control): niov 0 sz 0 => now niov 2 sz 64
1674230869.273895 [0] dq.builtin: write_sample 110d552:bdf21630:38f55f5b:301c3 #1: ST0 DCPSTypeLookupReply/DDS::Builtin::TypeLookup_Reply:{{{{{82,213,16,1,48,22,242,189,91,95,245,56},{{195,1,3},0}},{0,1}},0},{dlh:12825318099:dlh:1200:{pl:112,lc:5,m:41962193,{{{{241:{204,144,94,91,211,198,81,21,134,173,71,97,208,160}},{dlh:67241:81:{1,{dlh:1{0:},},{{dlh:11{0,49,{4:}},{{184,11,183,116}}},{dlh:11{1,1,{8:}},{{77,104,46,196}}},{dlh:11{2,1,{7:}},{{3,199,192,172}}}}}}}}},lc:5,m:193881463,{{}}}}}
1674230869.273902 [0] dq.builtin: non-timed queue now has 1 items
1674230869.273910 [0]        tev: nn_xpack_send 64: 0x7f089c000b6c:20 0x7f0884000c48:44 [ udp/192.168.234.128:43618@2 ]
1674230869.273915 [0]        tev: traffic-xmit (1) 64
1674230869.273920 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(data(110d552:bdf21630:38f55f5b:301c3:#1/1)): niov 0 sz 0 => now niov 3 sz 220
1674230869.273940 [0]        tev: nn_xpack_send 220: 0x7f089c000b6c:20 0x7f0894006df8:36 0x7f0894005bcc:164 [ udp/172.17.0.1:43618@2 ]
1674230869.273944 [0]        tev: traffic-xmit (1) 220
1674230869.274861 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(control): niov 0 sz 0 => now niov 2 sz 64
1674230869.274918 [0]        tev: nn_xpack_send 64: 0x7f089c000b6c:20 0x7f0894006de8:44 [ udp/192.168.234.128:43618@2 ]
1674230869.274925 [0]        tev: traffic-xmit (1) 64
1674230869.275812 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(control): niov 0 sz 0 => now niov 2 sz 64
1674230869.275838 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000b60 0(control): niov 2 sz 64 => now niov 3 sz 92
1674230869.275908 [0]        tev: nn_xpack_send 92: 0x7f089c000b6c:20 0x7f0894006de8:44 0x7f0884000c58:28 [ udp/192.168.234.128:43618@2 ]
1674230869.275916 [0]        tev: traffic-xmit (1) 92
1674230869.277004 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(control): niov 0 sz 0 => now niov 2 sz 64
1674230869.277091 [0]        tev: nn_xpack_send 64: 0x7f089c000b6c:20 0x7f0894006de8:44 [ udp/192.168.234.128:43618@2 ]
1674230869.277098 [0]        tev: traffic-xmit (1) 64
1674230869.284310 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 92 from udp/192.168.234.128:43618
1674230869.284334 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230869.284345 [0]     recvUC: ACKNACK(F#2:2/0: L(:1c1 209077.366617) 1030015:5d380101:caa8038e:4c7 -> 110d552:bdf21630:38f55f5b:4c2 setting-has-replied-to-hb happy-now)
1674230869.284350 [0]     recvUC: ACKNACK(F#2:2/0: 1030015:5d380101:caa8038e:200c7 -> 110d552:bdf21630:38f55f5b:200c2 setting-has-replied-to-hb happy-now)
1674230869.366078 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:3c2) suppressed, resched in inf s (min-ack 0, avail-seq 0, xmit 0)
1674230869.366593 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:4c2) suppressed, resched in inf s (min-ack 1, avail-seq 1, xmit 1)
1674230869.367080 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:200c2) suppressed, resched in inf s (min-ack 1, avail-seq 1, xmit 1)
1674230869.368034 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:300c3) suppressed, resched in inf s (min-ack 0, avail-seq 0, xmit 0)
1674230869.368736 [0]        tev: writer_hbcontrol: wr 110d552:bdf21630:38f55f5b:301c3 multicasting (rel-prd 1 seq-eq-max 0 seq 1 maxseq 0)
1674230869.368748 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:301c3) sent, resched in 0.1 s (min-ack 0, avail-seq 1, xmit 1)
1674230869.368752 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(control): niov 0 sz 0 => now niov 2 sz 52
1674230869.368805 [0]        tev: nn_xpack_send 52: 0x7f089c000b6c:20 0x7f0894006df8:32 [ udp/172.17.0.1:43618@2 ]
1674230869.368810 [0]        tev: traffic-xmit (1) 52
1674230869.379629 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 224 from udp/192.168.234.128:43618
1674230869.379651 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230869.379665 [0]     recvUC: HEARTBEAT(F#8:1..0  L(:1c1 209077.461935)1030015:5d380101:caa8038e:200c2 -> 110d552:bdf21630:38f55f5b:200c7: end-of-tl-seq(rd 110d552:bdf21630:38f55f5b:200c7 #0) msr_in_sync(110d552:bdf21630:38f55f5b:200c7 out-of-sync to tlcatchup) 110d552:bdf21630:38f55f5b:200c7@0(sync))
1674230869.379670 [0]     recvUC: HEARTBEAT(F#8:1..0 1030015:5d380101:caa8038e:4c2 -> 110d552:bdf21630:38f55f5b:4c7: end-of-tl-seq(rd 110d552:bdf21630:38f55f5b:4c7 #0) msr_in_sync(110d552:bdf21630:38f55f5b:4c7 out-of-sync to tlcatchup) 110d552:bdf21630:38f55f5b:4c7@0(sync))
1674230869.379700 [0]     recvUC: HEARTBEAT(F#8:1..1 1030015:5d380101:caa8038e:3c2 -> 110d552:bdf21630:38f55f5b:3c7: end-of-tl-seq(rd 110d552:bdf21630:38f55f5b:3c7 #1) 110d552:bdf21630:38f55f5b:3c7@0)
1674230869.379708 [0]     recvUC: HEARTBEAT(F#8:1..0 1030015:5d380101:caa8038e:301c3 -> 110d552:bdf21630:38f55f5b:301c4: 110d552:bdf21630:38f55f5b:301c4@0(sync))
1674230869.379713 [0]     recvUC: HEARTBEAT(F#8:1..1 1030015:5d380101:caa8038e:300c3 -> 110d552:bdf21630:38f55f5b:300c4: 110d552:bdf21630:38f55f5b:300c4@1(sync))
1674230869.379723 [0]     recvUC: ACKNACK(F#2:2/0: 1030015:5d380101:caa8038e:301c4 -> 110d552:bdf21630:38f55f5b:301c3 ACK1 RM1)
1674230869.379758 [0]        tev: acknack 110d552:bdf21630:38f55f5b:3c7 -> 1030015:5d380101:caa8038e:3c2: F#1:1/1:1
1674230869.379780 [0]        tev: send acknack(rd 110d552:bdf21630:38f55f5b:3c7 -> pwr 1030015:5d380101:caa8038e:3c2)
1674230869.379785 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(control): niov 0 sz 0 => now niov 2 sz 68
1674230869.379830 [0]        tev: nn_xpack_send 68: 0x7f089c000b6c:20 0x7f0894006de8:48 [ udp/192.168.234.128:43618@2 ]
1674230869.379836 [0]        tev: traffic-xmit (1) 68
1674230869.469387 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:301c3) suppressed, resched in inf s (min-ack 1, avail-seq 0, xmit 1)
1674230869.481140 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 344 from udp/192.168.234.128:43618
1674230869.481207 [0]     recvUC: INFOTS(1674230866.256929000)
1674230869.481226 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230869.481420 [0]     recvUC: DATA(1030015:5d380101:caa8038e:3c2 -> 110d552:bdf21630:38f55f5b:3c7 #1 L(:1c1 209077.563483) msr_in_sync(110d552:bdf21630:38f55f5b:3c7 out-of-sync to tlcatchup))
1674230869.481637 [0] dq.builtin: data(builtin, vendor 1.3): 1030015:5d380101:caa8038e:3c2 #1: ST0 DCPSPublication/PublicationBuiltinTopicData:{topic_name="Data",type_name="i11eperf::ou",reliability=1:10000000000,type_information=[MINIMAL 5a3c0c46e4529da3ac2f9f6e5b40]/[COMPLETE 41bb44ca49453fdfd22a67cc0fa5],endpoint_guid={1030015:5d380101:caa8038e:2},unicast_locator={udp/192.168.234.128:34872,udp/172.17.0.1:34872},multicast_locator={udp/239.255.0.2:7401}}
1674230869.481779 [0] dq.builtin: SEDP ST0 1030015:5d380101:caa8038e:2 reliable volatile writer unnamed: (default).Data/i11eperf::ou p(open) NEW (as udp/239.255.0.2:7401@2 udp/172.17.0.1:34872@2 udp/192.168.234.128:34872@2 ssm=0) QOS={user_data=0<>,topic_name="Data",type_name="i11eperf::ou",topic_data=0<>,group_data=0<>,durability=0,durability_service=0:0:1:-1:-1:-1,deadline=9223372036854775807,latency_budget=0,liveliness=0:9223372036854775807,reliability=1:10000000000,lifespan=9223372036854775807,destination_order=0,history=0:1,resource_limits=-1:-1:-1,ownership=0,ownership_strength=0,presentation=0:0:0,partition={},transport_priority=0,data_representation=1(0),type_information=[MINIMAL 5a3c0c46e4529da3ac2f9f6e5b40]/[COMPLETE 41bb44ca49453fdfd22a67cc0fa5],adlink_writer_data_lifecycle=0}
1674230869.481823 [0] dq.builtin: ref ddsi_type proxy id [MINIMAL 5a3c0c46e4529da3ac2f9f6e5b40] new 0x7f0894006310 refc 1
1674230869.481843 [0] dq.builtin: type [MINIMAL 5a3c0c46e4529da3ac2f9f6e5b40] add ep 1030015:5d380101:caa8038e:2
1674230869.481863 [0] dq.builtin: ref ddsi_type proxy id [COMPLETE 41bb44ca49453fdfd22a67cc0fa5] new 0x7f0894006740 refc 1
1674230869.481881 [0] dq.builtin: type [COMPLETE 41bb44ca49453fdfd22a67cc0fa5] add ep 1030015:5d380101:caa8038e:2
1674230869.481951 [0] dq.builtin:  => EVERYONE
1674230869.481978 [0] dq.builtin: match_proxy_writer_with_readers(pwr 1030015:5d380101:caa8038e:2) scanning all rds of topic Data
1674230869.491973 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 68 from udp/192.168.234.128:43618
1674230869.492042 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230869.492197 [0]     recvUC: HEARTBEAT(#9:1..1  L(:1c1 209077.574318)1030015:5d380101:caa8038e:3c2 -> 110d552:bdf21630:38f55f5b:3c7: 110d552:bdf21630:38f55f5b:3c7@1(sync))
1674230869.492360 [0]        tev: acknack 110d552:bdf21630:38f55f5b:3c7 -> 1030015:5d380101:caa8038e:3c2: F#2:2/0:
1674230869.492405 [0]        tev: send acknack(rd 110d552:bdf21630:38f55f5b:3c7 -> pwr 1030015:5d380101:caa8038e:3c2)
1674230869.492427 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(control): niov 0 sz 0 => now niov 2 sz 64
1674230869.492607 [0]        tev: nn_xpack_send 64: 0x7f089c000b6c:20 0x7f0894006de8:44 [ udp/192.168.234.128:43618@2 ]
1674230869.492637 [0]        tev: traffic-xmit (1) 64
1674230870.268529 [0]        tev: thread_cputime 0.003671000
1674230870.268632 [0]        tev: non-timed queue now has 1 items
1674230870.268659 [0]        tev: xmit spdp 110d552:bdf21630:38f55f5b:1c1 to 1030015:5d380101:caa8038e:100c7 (resched 1s)
1674230870.268724 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(data(0:0:0:0:#0/1)): niov 0 sz 0 => now niov 3 sz 448
1674230870.268940 [0]        tev: nn_xpack_send 448: 0x7f089c000b6c:20 0x7f0894006de8:52 0x560bc2e51684:376 [ udp/192.168.234.128:43618@2 ]
1674230870.268971 [0]        tev: traffic-xmit (1) 448
1674230870.468561 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 164 from udp/192.168.234.128:43618
1674230870.468589 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230870.468638 [0]     recvUC: HEARTBEAT(#9:1..0  L(:1c1 209078.550872)1030015:5d380101:caa8038e:200c2 -> 110d552:bdf21630:38f55f5b:200c7: 110d552:bdf21630:38f55f5b:200c7@0(sync))
1674230870.468650 [0]     recvUC: HEARTBEAT(#9:1..0 1030015:5d380101:caa8038e:4c2 -> 110d552:bdf21630:38f55f5b:4c7: 110d552:bdf21630:38f55f5b:4c7@0(sync))
1674230870.468657 [0]     recvUC: HEARTBEAT(#9:1..0 1030015:5d380101:caa8038e:301c3 -> 110d552:bdf21630:38f55f5b:301c4: 110d552:bdf21630:38f55f5b:301c4@0(sync))
1674230870.468663 [0]     recvUC: HEARTBEAT(#9:1..1 1030015:5d380101:caa8038e:300c3 -> 110d552:bdf21630:38f55f5b:300c4: 110d552:bdf21630:38f55f5b:300c4@1(sync))
1674230870.468670 [0]     recvUC: thread_cputime 0.000000000
1674230870.468714 [0]        tev: acknack 110d552:bdf21630:38f55f5b:200c7 -> 1030015:5d380101:caa8038e:200c2: F#1:1/0:
1674230870.468728 [0]        tev: send acknack(rd 110d552:bdf21630:38f55f5b:200c7 -> pwr 1030015:5d380101:caa8038e:200c2)
1674230870.468734 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(control): niov 0 sz 0 => now niov 2 sz 64
1674230870.468740 [0]        tev: acknack 110d552:bdf21630:38f55f5b:4c7 -> 1030015:5d380101:caa8038e:4c2: F#1:1/0:
1674230870.468745 [0]        tev: send acknack(rd 110d552:bdf21630:38f55f5b:4c7 -> pwr 1030015:5d380101:caa8038e:4c2)
1674230870.468750 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000b60 0(control): niov 2 sz 64 => now niov 3 sz 92
1674230870.468755 [0]        tev: acknack 110d552:bdf21630:38f55f5b:301c4 -> 1030015:5d380101:caa8038e:301c3: F#1:1/0:
1674230870.468759 [0]        tev: send acknack(rd 110d552:bdf21630:38f55f5b:301c4 -> pwr 1030015:5d380101:caa8038e:301c3)
1674230870.468764 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000eb0 0(control): niov 3 sz 92 => now niov 4 sz 120
1674230870.468769 [0]        tev: acknack 110d552:bdf21630:38f55f5b:300c4 -> 1030015:5d380101:caa8038e:300c3: F#1:2/0:
1674230870.468773 [0]        tev: send acknack(rd 110d552:bdf21630:38f55f5b:300c4 -> pwr 1030015:5d380101:caa8038e:300c3)
1674230870.468778 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000d30 0(control): niov 4 sz 120 => now niov 5 sz 148
1674230870.468839 [0]        tev: nn_xpack_send 148: 0x7f089c000b6c:20 0x7f0894006de8:44 0x7f0884000c58:28 0x7f0884000fa8:28 0x7f0884000e28:28 [ udp/192.168.234.128:43618@2 ]
1674230870.468848 [0]        tev: traffic-xmit (1) 148
1674230871.269256 [0]        tev: thread_cputime 0.004709000
1674230871.269359 [0]        tev: non-timed queue now has 1 items
1674230871.269386 [0]        tev: xmit spdp 110d552:bdf21630:38f55f5b:1c1 to 1030015:5d380101:caa8038e:100c7 (resched 1s)
1674230871.269411 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(data(0:0:0:0:#0/1)): niov 0 sz 0 => now niov 3 sz 448
1674230871.269628 [0]        tev: nn_xpack_send 448: 0x7f089c000b6c:20 0x7f0894006de8:52 0x560bc2e51684:376 [ udp/192.168.234.128:43618@2 ]
1674230871.269659 [0]        tev: traffic-xmit (1) 448
1674230872.269446 [0]        tev: thread_cputime 0.005234000
1674230872.269548 [0]        tev: non-timed queue now has 1 items
1674230872.269578 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(data(0:0:0:0:#0/1)): niov 0 sz 0 => now niov 3 sz 448
1674230872.269783 [0]        tev: nn_xpack_send 448: 0x7f089c000b6c:20 0x7f0894006de8:52 0x560bc2e51684:376 [ udp/192.168.234.128:43618@2 ]
1674230872.269813 [0]        tev: traffic-xmit (1) 448
1674230874.252905 [0]        tev: thread_cputime 0.005711000
1674230874.253011 [0]        tev: write_sample 110d552:bdf21630:38f55f5b:200c2 #2: ST0 DCPSParticipantMessage/ParticipantMessageData:{110d552:bdf21630:38f55f5b:1}:1<0>
1674230874.253095 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(data(110d552:bdf21630:38f55f5b:200c2:#2/1)): niov 0 sz 0 => now niov 3 sz 84
1674230874.253119 [0]        tev: writer_hbcontrol: wr 110d552:bdf21630:38f55f5b:200c2 multicasting (rel-prd 1 seq-eq-max 1 seq 2 maxseq 1)
1674230874.253143 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:200c2) piggybacked, resched in 0.1 s (min-ack 1, avail-seq 2, xmit 1)
1674230874.253162 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0884000b60 0(control): niov 3 sz 84 => now niov 4 sz 116
1674230874.253383 [0]        tev: nn_xpack_send 116: 0x7f089c000b6c:20 0x7f0894006df8:36 0x7f089400484c:28 0x7f0884000c58:32 [ udp/172.17.0.1:43618@2 ]
1674230874.253414 [0]        tev: traffic-xmit (1) 116
1674230874.253438 [0]        tev: resched pmd(110d552:bdf21630:38f55f5b:1c1): 8s
1674230874.265870 [0]     recvUC: HDR(1030015:5d380101:caa8038e vendor 1.3) len 64 from udp/192.168.234.128:43618
1674230874.266034 [0]     recvUC: INFODST(110d552:bdf21630:38f55f5b)
1674230874.266082 [0]     recvUC: ACKNACK(F#3:3/0: L(:1c1 209082.348306) 1030015:5d380101:caa8038e:200c7 -> 110d552:bdf21630:38f55f5b:200c2 ACK1 RM0)
1674230874.266107 [0]     recvUC: thread_cputime 0.000000000
1674230874.352859 [0]        tev: non-timed queue now has 1 items
1674230874.352936 [0]        tev: xmit spdp 110d552:bdf21630:38f55f5b:1c1 to 0:0:0:100c7 (resched 8s)
1674230874.352962 [0]        tev: xpack_addmsg 0x7f089c000b60 0x7f0894006d00 0(data(0:0:0:0:#0/1)): niov 0 sz 0 => now niov 3 sz 432
1674230874.353269 [0]        tev: nn_xpack_send 432: 0x7f089c000b6c:20 0x7f0894006df8:36 0x560bc2e51684:376 [ udp/239.255.0.1:7400@2 ]
1674230874.353300 [0]        tev: traffic-xmit (1) 432
1674230874.353328 [0]        tev: heartbeat(wr 110d552:bdf21630:38f55f5b:200c2) suppressed, resched in inf s (min-ack 2, avail-seq 2, xmit 2)
1674230874.353431 [0]       recv: HDR(110d552:bdf21630:38f55f5b vendor 1.16) len 432 from udp/192.168.234.128:51014
1674230874.353481 [0]       recv: INFOTS(1674230866.252012445)
1674230874.353614 [0]       recv: DATA(110d552:bdf21630:38f55f5b:100c2 -> 0:0:0:0 #1)
1674230874.353653 [0]       recv: thread_cputime 0.000000000
1674230874.353874 [0] dq.builtin: data(builtin, vendor 1.16): 0:0:0:0 #1: ST0 /ParticipantBuiltinTopicData:{property_list={1:"__ProcessName":"csub",1:"__Pid":"51886",1:"__Hostname":"simon-Virtual-Machine"}:{},protocol_version=2:1,vendorid=1:16,participant_lease_duration=10000000000,participant_guid={110d552:bdf21630:38f55f5b:1c1},builtin_endpoint_set=64575,domain_id=0,default_unicast_locator={udp/192.168.234.128:52750},default_multicast_locator={udp/239.255.0.1:7401},metatraffic_unicast_locator={udp/192.168.234.128:52750},metatraffic_multicast_locator={udp/239.255.0.1:7400},adlink_participant_version_info=0:44:0:0:0:"simon-Virtual-Machine/0.11.0/Linux/Linux",cyclone_receive_buffer_size=425984}
1674230874.353930 [0] dq.builtin: SPDP ST0 110d552:bdf21630:38f55f5b:1c1 (local)
1674230874.353955 [0] dq.builtin: thread_cputime 0.004779000
eboasson commented 1 year ago

Right, just as I expected:

1674230869.481637 [0] dq.builtin: data(builtin, vendor 1.3): 1030015:5d380101:caa8038e:3c2 #1: ST0 DCPSPublication/PublicationBuiltinTopicData:{topic_name="Data",type_name="i11eperf::ou",reliability=1:10000000000,type_information=[MINIMAL 5a3c0c46e4529da3ac2f9f6e5b40]/[COMPLETE 41bb44ca49453fdfd22a67cc0fa5],endpoint_guid={1030015:5d380101:caa8038e:2},unicast_locator={udp/192.168.234.128:34872,udp/172.17.0.1:34872},multicast_locator={udp/239.255.0.2:7401}}

It is not providing the history setting. So we can stop worrying about that. (Though perhaps it would be sensible to hide QoS settings not involved in matching in the output of cyclonedds ls for implementations that do not publish them.)

I don't have the time today anymore to dive into the type information. It is odd that such a simple type would already lead to different definitions. In principle, I could try generating it myself, of course. It is just that I lost my OpenDDS installation, then I might have a different version, &c.

One thought: did you check whether adding @final helps?

smnrgrs commented 1 year ago

Adding @final or @data_representation(XCDR1) had no effect. OpenDDS still says 'WARNING Data types of topic Data does not match (inconsistent)'. It works with all combinations except opub->csub. If I remove the @key from the idl, opub->csub works.

eboasson commented 1 year ago

Thanks for checking, that thought about the final/data_representation thing was a long shot and missed, oh well. That @key makes the difference is really interesting ...

The current theory is that there is a difference in one of the flags member field flags. For example, with @key the spec is unclear about what the relationship is between the "key" flag and the "must understand" flag. A key must be understood, according to the spec, but does that mean the type object needs that flag set, or is it implied? We have had discussions about that and even changed things a bit — probably prompted by a compatibility thing. Which all simply goes to say that there is reason to think such a thing might be the case.

To be certain we need the actual type objects, and the easiest way to get them is to take the outputs from the two IDL compilers. I'm tempted to install OpenDDS and simply run the IDL through, but you'd obviously have to tell me which exact versions of Cyclone and OpenDDS to use. If you zip the generated code and upload it, that'd be easier for me, of course.

eboasson commented 1 year ago

I decided to give it a go (I'm not used to waiting for a DDS implementation to finish downloading or building 😂):

OpenDDS 3.23 (minimal)
43 00 00 00 f1 51 01 00
  01 00 00 00 00 00 00 00 33 00 00 00 03 00 00 00
  0b 00 00 00 00 00 00 00*21*00 04 b8 0b b7 74 00
  0b 00 00 00 01 00 00 00 01 00 08 4d 68 2e c4 00
  0b 00 00 00 02 00 00 00 01 00 07 03 c7 c0 ac

Cyclone master (minimal)
43 00 00 00 f1 51 01 00
  01 00 00 00 00 00 00 00 33 00 00 00 03 00 00 00
  0b 00 00 00 00 00 00 00*31*00 04 b8 0b b7 74 00
  0b 00 00 00 01 00 00 00 01 00 08 4d 68 2e c4 00
  0b 00 00 00 02 00 00 00 01 00 07 03 c7 c0 ac

I've marked the byte that's different, and an educated guess is that it is the byte encoding member flags, 'cos that's where the key flag is stored. The bitmask is defined as follows: https://github.com/eclipse-cyclonedds/cyclonedds/blob/37c25f2af43add46d537c5d5d8c02e9f5cb936d6/src/core/ddsi/idl/ddsi_xt_typeinfo.idl#L116-L126

edited: I copied the type objects correctly, then reversed the conclusions 🤦 below the updated version — all of which only goes to show that it is hard to get this straight in one's mind

So by my reckoning that difference is that Cyclone explicitly sets the IS_MUST_UNDERSTAND flag, but OpenDDS doesn't. Should it be set? The spec doesn't say how Type Objects are to be constructed (thanks OMG!), but we ended up at saying the flag must be set in the Type Object based on the XTypes spec section 7.2.2.4.4.4.8 "Key Members":

A given member of an aggregated type may be designated as part of that type’s key. The type’s key will become the key of any DDS Topic that is constructed using the aforementioned aggregated type as the Topic’s type. If a given type has no members designated as key members, then the type—and any DDSTopic that is constructed using it as its type it—has no key. Key members shall never be optional, and they shall always have their “must understand” attribute set to true.

The last line is the important one and that's why we set it. Thanks @dpotman for pointing that out (see also https://github.com/eclipse-cyclonedds/cyclonedds/pull/1108).

The reason for my confusion is that when you do

struct ou {
  @key long id;
  unsigned long long ts;
  unsigned long s;
};
struct outer {
  struct ou inner;
};

then it should behave as-if the inner.id field had no @key annotation (you would have to do @key struct ou inner if you wanted it to be a key), and so the rule that @must_understand is implied for key fields then makes no sense in a topic of type outer. Aargh ...

@mitza-oci: it would be good to know why OpenDDS doesn't set it.

smnrgrs commented 1 year ago

Sorry, am trying to get IDL generated code uploaded, but firewall issues. I got to this point because I'm trying to investigate interoperability problem with more complex keys between OpenDDS and Python Cyclone. I figured simple key ought to work first.

smnrgrs commented 1 year ago

cyclonedds_generated.tar.gz opendds_generated.tar.gz

eboasson commented 1 year ago

Sorry, am trying to get IDL generated code uploaded, but firewall issues.

No worries, as long as we figure it out and I don't have to spend a lot of time on replicating things, it's fine. (I can easily ignore issues that I understand, but I find it much harder to ignore issues that I don't understand 😂 Just like I can't stand Cyclone crashing for anyone ...)

Besides, downloading & building all happens in the background, and so the time really went in figuring out which magic sequence of numbers to look at and how to make sense of those.

I got to this point because I'm trying to investigate interoperability problem with more complex keys between OpenDDS and Python Cyclone. I figured simple key ought to work first.

Sensible approach. I think the limits of the OMG specifications become apparent quite soon once you start doing "fancy" things, that is, things that are not covered in the minimum profile of the original specification. And even then, there are subtle details due to bad implementations (but usually those are very obviously wrong, like RTI (historically?) not sending serialised keys for disposes as is obviously required by the specification).

We'll see where this project of yours leads us. Hopefully to Cyclone being correct and OpenDDS get fixed 😁

eboasson commented 1 year ago

cyclonedds_generated.tar.gz opendds_generated.tar.gz

Thanks! A quick confirms that the output from the OpenDDS IDL compiler is the same for you as the one I looked at. So now the question is: "which one is correct" ...

mitza-oci commented 1 year ago

You may want to use https://github.com/OpenDDS/OpenDDS/pkgs/container/opendds if building from source is not desired.

If there's no @must_understand in the IDL then why would it appear in the TypeObject? The TypeObject should do what is required to encode the type information and no more. See 7.3.4.3 in the XTypes spec for background -- I realize the spec isn't completely clear on the specifics (and of course I didn't write the spec).

But the most straightforward interpretation I can come up with is that the fields inside TypeObject are a direct translation of the IDL. I couldn't find anything in the abstract typesystem description or the IDL Type Representation sections to indicate that must_understand implies key. They are close semantically, but that doesn't impact syntax.

mitza-oci commented 1 year ago

I missed this part, which you did quote above (sorry, there was a lot of content to digest).

7.2.2.4.4.4.8 Key Members

Key members shall never be optional, and they shall always have their “must understand” attribute set to true.

So we're left with: either the TypeObject encodes the IDL types or the abstract type system, but either way the spec should tell us. 7.3.4.3 at least hints at the "IDL" interpretation.

eboasson commented 1 year ago

@smnrgrs after discussing with @dpotman we concluded that although the type identifiers is different, this should work with the default settings. That's nice in that it means we there's no need to worry right now about what is the correct behaviour.

The reason it doesn't work is because the "type consistency enforcement" QoS is set incorrectly on the Cyclone side: (type_consistency_enforcement=0:00010), it requires the type identifiers to be identical, rather than requiring that the two two types are assignable.

That problem originates in a bug in the C++ binding that was fixed recently in https://github.com/eclipse-cyclonedds/cyclonedds-cxx/pull/331. Is it possible you don't have that fix in your build yet? Because with the current head of cyclonedds and cyclonedds-cxx it behaves as it would expect.

eboasson commented 1 year ago

@mitza-oci Should've mentioned you in my comment just now, too. Oh well, a post-scriptum will have to do.

Thanks for providing the background on OpenDDS behaviour. I do think it is important that the various DDS implementations do the same, and I do think that if IDL a and IDL b are equivalent they should end up with the same type object, and then that particular sentence in the spec does require consensus. But I grant you that opinions can differ; and in any case (as I also mentioned in my comment), I think there's a case to be made that the @must_understand shouldn't be set in the type object.

Given that interoperation is not that common and that it only is a problem with the (presumably) unusual setting of requiring the type identifiers to be identical, I think we should simply add this detail to the list of open issues at the OMG.

mitza-oci commented 1 year ago

The problem with "interoperation is not common" is that it's a self-fulfilling prophecy. If the spec was clear at sufficient level of detail, with examples, then there would be common interoperation since it would be easy, or at least possible.

Please create an issue for the RTF here https://issues.omg.org/issues/create-new-issue (or directly in Jira if you have access).

smnrgrs commented 1 year ago

@smnrgrs after discussing with @dpotman we concluded that although the type identifiers is different, this should work with the default settings. That's nice in that it means we there's no need to worry right now about what is the correct behaviour.

The reason it doesn't work is because the "type consistency enforcement" QoS is set incorrectly on the Cyclone side: (type_consistency_enforcement=0:00010), it requires the type identifiers to be identical, rather than requiring that the two two types are assignable.

That problem originates in a bug in the C++ binding that was fixed recently in eclipse-cyclonedds/cyclonedds-cxx#331. Is it possible you don't have that fix in your build yet? Because with the current head of cyclonedds and cyclonedds-cxx it behaves as it would expect.

I'll try that, thanks