Experimental Physics and
Industrial Control System

Michael Davidsaver <[email protected]> · Mon, 29 Oct 2018 09:46:08 -0700

So the clients are not getting past the search phase with one server,
but succeed with the other.  So the obvious question is whether these
have different configurations/builds etc.

Is 'training:ai1' is served from an older build?  If so, I suspect
the difference in behavior with the 'mailbox' server is due to the
fix for #110 which changed the way the auto address list is populated.

https://github.com/epics-base/pvAccessCPP/issues/110#issuecomment-401990536

On 10/29/18 9:30 AM, Kasemir, Kay wrote:
> Hi!
> 
>> First, repeat these two while adding '-d'.  This will likely produce a lot of output.
> 
> Here's the network setup:
> 
> $ /sbin/ifconfig 
> enp0s3: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
>         inet 10.0.2.15  netmask 255.255.255.0  broadcast 10.0.2.255
>         inet6 fe80::7f29:bd96:809b:6dc9  prefixlen 64  scopeid 0x20<link>
>         ether 08:00:27:c0:85:fe  txqueuelen 1000  (Ethernet)
>         RX packets 22  bytes 5668 (5.5 KiB)
>         RX errors 0  dropped 0  overruns 0  frame 0
>         TX packets 161  bytes 15518 (15.1 KiB)
>         TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
> 
> lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
>         inet 127.0.0.1  netmask 255.0.0.0
>         inet6 ::1  prefixlen 128  scopeid 0x10<host>
>         loop  txqueuelen 1000  (Local Loopback)
>         RX packets 504  bytes 41975 (40.9 KiB)
>         RX errors 0  dropped 0  overruns 0  frame 0
>         TX packets 504  bytes 41975 (40.9 KiB)
>         TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
> 
> virbr0: flags=4099<UP,BROADCAST,MULTICAST>  mtu 1500
>         inet 192.168.122.1  netmask 255.255.255.0  broadcast 192.168.122.255
>         ether 52:54:00:09:e8:c5  txqueuelen 1000  (Ethernet)
>         RX packets 0  bytes 0 (0.0 B)
>         RX errors 0  dropped 0  overruns 0  frame 0
>         TX packets 0  bytes 0 (0.0 B)
>         TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
> 
> 
> PV Info:
> 
> $ pvinfo -d training:ai1
> 2018-10-29T12:28:52.096 Client security plug-in 'ca' installed.
> 2018-10-29T12:28:52.096 Creating datagram socket from: 0.0.0.0:39127.
> 2018-10-29T12:28:52.096 Broadcast address #0: 10.0.2.15:5076. (unicast)
> 2018-10-29T12:28:52.096 Setting up UDP for interface 10.0.2.15/255.255.255.0, broadcast 10.0.2.255, dest <none>.
> 2018-10-29T12:28:52.096 Creating datagram socket from: 10.0.2.15:5076.
> 2018-10-29T12:28:52.096 Creating datagram socket from: 10.0.2.255:5076.
> 2018-10-29T12:28:52.096 Setting up UDP for interface 192.168.122.1/255.255.255.0, broadcast 192.168.122.255, dest <none>.
> 2018-10-29T12:28:52.097 Creating datagram socket from: 192.168.122.1:5076.
> 2018-10-29T12:28:52.097 Creating datagram socket from: 192.168.122.255:5076.
> 2018-10-29T12:28:52.097 Creating datagram socket from: 224.0.0.128:5076.
> 2018-10-29T12:28:52.097 Local multicast enabled on 127.0.0.1/224.0.0.128:5076.
> 2018-10-29T12:28:52.097 Sending 58 bytes 0.0.0.0:39127 -> 10.0.2.15:5076.
> 2018-10-29T12:28:52.097 UDP Rx (58) 10.0.2.15:5076 <- 10.0.2.15:39127
> 2018-10-29T12:28:52.097 UDP Tx (82) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:28:52.097 UDP Rx (82) 224.0.0.128:5076 <- 10.0.2.15:5076
> training:ai1 Error: channel destroyed
> 2018-10-29T12:28:52.097 UDP socket 0.0.0.0:0 closed.
> 2018-10-29T12:28:52.097 UDP Rx (0) 0.0.0.0:39127 <- <Ukn Addr Type>
> 2018-10-29T12:28:52.097 UDP socket 10.0.2.15:5076 closed.
> 2018-10-29T12:28:52.097 UDP Rx (0) 10.0.2.15:5076 <- 10.0.2.15:39127
> 2018-10-29T12:28:52.097 UDP socket 10.0.2.255:5076 closed.
> 2018-10-29T12:28:52.097 UDP Rx (0) 10.0.2.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:28:52.097 UDP socket 192.168.122.1:5076 closed.
> 2018-10-29T12:28:52.097 UDP Rx (0) 192.168.122.1:5076 <- <Ukn Addr Type>
> 2018-10-29T12:28:52.097 UDP socket 192.168.122.255:5076 closed.
> 2018-10-29T12:28:52.097 UDP Rx (0) 192.168.122.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:28:52.097 UDP socket 224.0.0.128:5076 closed.
> 2018-10-29T12:28:52.097 UDP Rx (0) 224.0.0.128:5076 <- 10.0.2.15:5076
> 
> 
> 
> PV Get:
> 
> $ pvget -d training:ai1
> 2018-10-29T12:29:26.870 Client security plug-in 'ca' installed.
> 2018-10-29T12:29:26.871 Creating datagram socket from: 0.0.0.0:54563.
> 2018-10-29T12:29:26.871 Broadcast address #0: 10.0.2.15:5076. (unicast)
> 2018-10-29T12:29:26.871 Setting up UDP for interface 10.0.2.15/255.255.255.0, broadcast 10.0.2.255, dest <none>.
> 2018-10-29T12:29:26.871 Creating datagram socket from: 10.0.2.15:5076.
> 2018-10-29T12:29:26.871 Creating datagram socket from: 10.0.2.255:5076.
> 2018-10-29T12:29:26.871 Setting up UDP for interface 192.168.122.1/255.255.255.0, broadcast 192.168.122.255, dest <none>.
> 2018-10-29T12:29:26.871 Creating datagram socket from: 192.168.122.1:5076.
> 2018-10-29T12:29:26.871 Creating datagram socket from: 192.168.122.255:5076.
> 2018-10-29T12:29:26.871 Creating datagram socket from: 224.0.0.128:5076.
> 2018-10-29T12:29:26.871 Local multicast enabled on 127.0.0.1/224.0.0.128:5076.
> 2018-10-29T12:29:26.871 Sending 58 bytes 0.0.0.0:54563 -> 10.0.2.15:5076.
> 2018-10-29T12:29:26.871 UDP Rx (58) 10.0.2.15:5076 <- 10.0.2.15:54563
> Waiting...
> 2018-10-29T12:29:26.871 UDP Tx (82) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:29:26.871 UDP Rx (82) 224.0.0.128:5076 <- 10.0.2.15:5076
> 2018-10-29T12:29:27.113 Sending 58 bytes 0.0.0.0:54563 -> 10.0.2.15:5076.
> 2018-10-29T12:29:27.113 UDP Rx (58) 10.0.2.15:5076 <- 10.0.2.15:54563
> 2018-10-29T12:29:27.113 UDP Tx (82) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:29:27.113 UDP Rx (82) 224.0.0.128:5076 <- 10.0.2.15:5076
> 2018-10-29T12:29:27.585 Sending 58 bytes 0.0.0.0:54563 -> 10.0.2.15:5076.
> 2018-10-29T12:29:27.585 UDP Rx (58) 10.0.2.15:5076 <- 10.0.2.15:54563
> 2018-10-29T12:29:27.585 UDP Tx (82) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:29:27.585 UDP Rx (82) 224.0.0.128:5076 <- 10.0.2.15:5076
> 2018-10-29T12:29:28.536 Sending 58 bytes 0.0.0.0:54563 -> 10.0.2.15:5076.
> 2018-10-29T12:29:28.536 UDP Rx (58) 10.0.2.15:5076 <- 10.0.2.15:54563
> 2018-10-29T12:29:28.536 UDP Tx (82) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:29:28.536 UDP Rx (82) 224.0.0.128:5076 <- 10.0.2.15:5076
> 2018-10-29T12:29:30.426 Sending 58 bytes 0.0.0.0:54563 -> 10.0.2.15:5076.
> 2018-10-29T12:29:30.426 UDP Rx (58) 10.0.2.15:5076 <- 10.0.2.15:54563
> 2018-10-29T12:29:30.426 UDP Tx (82) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:29:30.426 UDP Rx (82) 224.0.0.128:5076 <- 10.0.2.15:5076
> Timeout
> training:ai1 2018-10-29T12:29:31.872 UDP socket 0.0.0.0:0 closed.
> 2018-10-29T12:29:31.872 UDP Rx (0) 0.0.0.0:54563 <- <Ukn Addr Type>
> 2018-10-29T12:29:31.872 UDP socket 10.0.2.15:5076 closed.
> 2018-10-29T12:29:31.872 UDP Rx (0) 10.0.2.15:5076 <- 10.0.2.15:54563
> 2018-10-29T12:29:31.872 UDP socket 10.0.2.255:5076 closed.
> 2018-10-29T12:29:31.872 UDP Rx (0) 10.0.2.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:29:31.872 UDP socket 192.168.122.1:5076 closed.
> 2018-10-29T12:29:31.872 UDP Rx (0) 192.168.122.1:5076 <- <Ukn Addr Type>
> 2018-10-29T12:29:31.872 UDP socket 192.168.122.255:5076 closed.
> 2018-10-29T12:29:31.872 UDP Rx (0) 192.168.122.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:29:31.872 UDP socket 224.0.0.128:5076 closed.
> 2018-10-29T12:29:31.872 UDP Rx (0) 224.0.0.128:5076 <- 10.0.2.15:5076
> Done
> 
> 
> 
> 
>> Second.  Run:
>>> ./modules/pvAccess/examples/O.linux-x86_64/mailbox blah
>>
> 
> PV Info:
> 
> $ pvinfo -d blah
> 2018-10-29T12:30:20.791 Client security plug-in 'ca' installed.
> 2018-10-29T12:30:20.791 Creating datagram socket from: 0.0.0.0:49264.
> 2018-10-29T12:30:20.791 Broadcast address #0: 10.0.2.15:5076. (unicast)
> 2018-10-29T12:30:20.791 Setting up UDP for interface 10.0.2.15/255.255.255.0, broadcast 10.0.2.255, dest <none>.
> 2018-10-29T12:30:20.791 Creating datagram socket from: 10.0.2.15:5076.
> 2018-10-29T12:30:20.791 Creating datagram socket from: 10.0.2.255:5076.
> 2018-10-29T12:30:20.792 Setting up UDP for interface 192.168.122.1/255.255.255.0, broadcast 192.168.122.255, dest <none>.
> 2018-10-29T12:30:20.792 Creating datagram socket from: 192.168.122.1:5076.
> 2018-10-29T12:30:20.792 Creating datagram socket from: 192.168.122.255:5076.
> 2018-10-29T12:30:20.792 Creating datagram socket from: 224.0.0.128:5076.
> 2018-10-29T12:30:20.792 Local multicast enabled on 127.0.0.1/224.0.0.128:5076.
> 2018-10-29T12:30:20.792 Sending 50 bytes 0.0.0.0:49264 -> 10.0.2.15:5076.
> 2018-10-29T12:30:20.792 UDP Rx (50) 10.0.2.15:5076 <- 10.0.2.15:49264
> 2018-10-29T12:30:20.792 UDP Tx (74) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:30:20.792 UDP Rx (53) 0.0.0.0:49264 <- 10.0.2.15:48448
> 2018-10-29T12:30:20.792 UDP Rx (74) 224.0.0.128:5076 <- 10.0.2.15:5076
> blah Error: channel destroyed
> 2018-10-29T12:30:20.792 UDP socket 0.0.0.0:0 closed.
> 2018-10-29T12:30:20.792 Connecting to PVA server: 10.0.2.15:5075.
> 2018-10-29T12:30:20.792 Opening socket to PVA server 10.0.2.15:5075, attempt 1.
> 2018-10-29T12:30:20.792 Socket connected to PVA server: 10.0.2.15:5075.
> 2018-10-29T12:30:20.792 Acquiring transport to 10.0.2.15:5075.
> 2018-10-29T12:30:20.793 Connected to PVA server: 10.0.2.15:5075.
> 2018-10-29T12:30:20.793 UDP socket 10.0.2.15:5076 closed.
> 2018-10-29T12:30:20.793 UDP Rx (0) 10.0.2.15:5076 <- 10.0.2.15:49264
> 2018-10-29T12:30:20.793 UDP socket 10.0.2.255:5076 closed.
> 2018-10-29T12:30:20.793 UDP Rx (0) 10.0.2.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:30:20.793 UDP socket 192.168.122.1:5076 closed.
> 2018-10-29T12:30:20.793 UDP Rx (0) 192.168.122.1:5076 <- <Ukn Addr Type>
> 2018-10-29T12:30:20.793 UDP socket 192.168.122.255:5076 closed.
> 2018-10-29T12:30:20.793 UDP Rx (0) 192.168.122.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:30:20.793 UDP socket 224.0.0.128:5076 closed.
> 2018-10-29T12:30:20.793 UDP Rx (0) 224.0.0.128:5076 <- 10.0.2.15:5076
> 2018-10-29T12:30:21.993 PVA client context destroyed with 1 transport(s) active.
> 
> 
> PV Get:
> 
> $ pvget -d blah
> 2018-10-29T12:30:40.584 Client security plug-in 'ca' installed.
> 2018-10-29T12:30:40.585 Creating datagram socket from: 0.0.0.0:38927.
> 2018-10-29T12:30:40.585 Broadcast address #0: 10.0.2.15:5076. (unicast)
> 2018-10-29T12:30:40.585 Setting up UDP for interface 10.0.2.15/255.255.255.0, broadcast 10.0.2.255, dest <none>.
> 2018-10-29T12:30:40.585 Creating datagram socket from: 10.0.2.15:5076.
> 2018-10-29T12:30:40.585 Creating datagram socket from: 10.0.2.255:5076.
> 2018-10-29T12:30:40.585 Setting up UDP for interface 192.168.122.1/255.255.255.0, broadcast 192.168.122.255, dest <none>.
> 2018-10-29T12:30:40.585 Creating datagram socket from: 192.168.122.1:5076.
> 2018-10-29T12:30:40.585 Creating datagram socket from: 192.168.122.255:5076.
> 2018-10-29T12:30:40.585 Creating datagram socket from: 224.0.0.128:5076.
> 2018-10-29T12:30:40.585 Local multicast enabled on 127.0.0.1/224.0.0.128:5076.
> 2018-10-29T12:30:40.585 Sending 50 bytes 0.0.0.0:38927 -> 10.0.2.15:5076.
> 2018-10-29T12:30:40.585 UDP Rx (50) 10.0.2.15:5076 <- 10.0.2.15:38927
> Waiting...
> 2018-10-29T12:30:40.585 UDP Tx (74) 10.0.2.15:5076 -> 224.0.0.128:5076.
> 2018-10-29T12:30:40.585 UDP Rx (74) 224.0.0.128:5076 <- 10.0.2.15:5076
> 2018-10-29T12:30:40.585 UDP Rx (53) 0.0.0.0:38927 <- 10.0.2.15:48448
> 2018-10-29T12:30:40.585 Connecting to PVA server: 10.0.2.15:5075.
> 2018-10-29T12:30:40.585 Opening socket to PVA server 10.0.2.15:5075, attempt 1.
> 2018-10-29T12:30:40.585 Socket connected to PVA server: 10.0.2.15:5075.
> 2018-10-29T12:30:40.586 Acquiring transport to 10.0.2.15:5075.
> 2018-10-29T12:30:40.586 Connected to PVA server: 10.0.2.15:5075.
> blah  
> 2018-10-29T12:30:40.586 Releasing TCP transport to 10.0.2.15:5075.
> 2018-10-29T12:30:40.586 TCP socket to 10.0.2.15:5075 is to be closed.
> 2018-10-29T12:30:40.586 UDP socket 0.0.0.0:0 closed.
> 2018-10-29T12:30:40.586 UDP Rx (0) 0.0.0.0:38927 <- 10.0.2.15:48448
> 2018-10-29T12:30:40.586 UDP socket 10.0.2.15:5076 closed.
> 2018-10-29T12:30:40.586 UDP Rx (0) 10.0.2.15:5076 <- 10.0.2.15:38927
> 2018-10-29T12:30:40.586 UDP socket 10.0.2.255:5076 closed.
> 2018-10-29T12:30:40.586 UDP Rx (0) 10.0.2.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:30:40.587 UDP socket 192.168.122.1:5076 closed.
> 2018-10-29T12:30:40.587 UDP Rx (0) 192.168.122.1:5076 <- <Ukn Addr Type>
> 2018-10-29T12:30:40.587 UDP socket 192.168.122.255:5076 closed.
> 2018-10-29T12:30:40.587 UDP Rx (0) 192.168.122.255:5076 <- <Ukn Addr Type>
> 2018-10-29T12:30:40.587 UDP socket 224.0.0.128:5076 closed.
> 2018-10-29T12:30:40.587 UDP Rx (0) 224.0.0.128:5076 <- 10.0.2.15:5076
> Done
> 
> 
> Thanks,
> -Kay
> 

Experimental Physics and Industrial Control System

Experimental Physics and
Industrial Control System