client -> test-pvagw-03 (172.30.8.12) -> test-pvagw-01 (172.30.0.31) -> ioc-network (172.30.7.255)
client:
$ pvget demo:bb:counter
demo:bb:counter 2020-07-10 11:14:56.472 7
(epics) CI0011906:~ $ pvget demo:bb:counter
Timeout
$ pvget demo:bb:counter
demo:bb:counter 2020-07-10 11:15:46.471 10
test-pvagw-03 (-v --debug)
2020-07-10T11:14:56.180 UDP Server Rx (61) 172.30.8.12:5076 <- 172.18.157.59:54371
2020-07-10T11:14:56.181 UDP Tx (85) 172.30.8.12:5076 -> 224.0.0.128:5076.
2020-07-10T11:14:56.181 UDP Client Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
2020-07-10T11:14:56.181 UDP Server Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
DEBUG:p4p.gw:172.30.8.12:5076 Searching for b'demo:bb:counter'
DEBUG:p4p.gw:allowed: b'demo:bb:counter' by 172.30.8.12:5076 -> 0
2020-07-10T11:14:56.427 UDP Server Rx (61) 172.30.8.12:5076 <- 172.18.157.59:54371
2020-07-10T11:14:56.427 Sending 61 bytes 0.0.0.0:48182 -> 172.30.0.31:5076.
2020-07-10T11:14:56.427 UDP Tx (85) 172.30.8.12:5076 -> 224.0.0.128:5076.
2020-07-10T11:14:56.427 UDP Server Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
2020-07-10T11:14:56.427 UDP Client Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
DEBUG:p4p.gw:172.30.8.12:5076 Searching for b'demo:bb:counter'
DEBUG:p4p.gw:allowed: b'demo:bb:counter' by 172.30.8.12:5076 -> 0
2020-07-10T11:14:56.675 Sending 61 bytes 0.0.0.0:48182 -> 172.30.0.31:5076.
2020-07-10T11:14:56.676 UDP Client Rx (53) 0.0.0.0:48182 <- 172.30.0.31:59931
2020-07-10T11:14:56.676 Connecting to PVA server: 172.30.0.31:5075.
2020-07-10T11:14:56.676 Opening socket to PVA server 172.30.0.31:5075, attempt 1.
2020-07-10T11:14:56.676 Socket connected to PVA server: 172.30.0.31:5075.
2020-07-10T11:14:56.676 Acquiring transport to 172.30.0.31:5075.
2020-07-10T11:14:56.677 Connected to PVA server: 172.30.0.31:5075.
2020-07-10T11:14:56.869 UDP Server Rx (61) 172.30.8.12:5076 <- 172.18.157.59:54371
2020-07-10T11:14:56.869 UDP Tx (85) 172.30.8.12:5076 -> 224.0.0.128:5076.
2020-07-10T11:14:56.869 UDP Server Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
2020-07-10T11:14:56.869 UDP Client Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
DEBUG:p4p.gw:172.30.8.12:5076 Searching for b'demo:bb:counter'
DEBUG:p4p.gw:allowed: b'demo:bb:counter' by 172.30.8.12:5076 -> 1
2020-07-10T11:14:56.869 Sending 53 bytes 0.0.0.0:34827 -> 172.18.157.59:54371.
2020-07-10T11:14:56.902 Accepted connection from PVA client: 172.18.157.59:49773.
2020-07-10T11:14:56.932 Accepted security plug-in 'ca' for PVA client: 172.18.157.59:49773.
2020-07-10T11:14:56.932 Authentication completed with status 'OK' for PVA client: 172.18.157.59:49773.
2020-07-10T11:14:56.932 Serving to PVA client: 172.18.157.59:49773.
DEBUG:p4p.gw:Create b'demo:bb:counter' by 172.18.157.59:49773
DEBUG:p4p.asLib:(re)create <p4p._gw.Channel object at 0x7f323b010830>, DEFAULT, benjaminbertrand, 172.18.157.59, 0
2020-07-10T11:14:57.001 TCP socket to 172.18.157.59:49773 is to be closed.
2020-07-10T11:14:57.001 Transport to 172.18.157.59:49773 still has 1 channel(s) active and closing...
2020-07-10T11:14:59.290 UDP Server Rx (61) 172.30.8.12:5076 <- 172.18.157.59:50919
2020-07-10T11:14:59.290 UDP Tx (85) 172.30.8.12:5076 -> 224.0.0.128:5076.
2020-07-10T11:14:59.290 UDP Server Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
2020-07-10T11:14:59.290 UDP Client Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
DEBUG:p4p.gw:172.30.8.12:5076 Searching for b'demo:bb:counter'
DEBUG:p4p.gw:allowed: b'demo:bb:counter' by 172.30.8.12:5076 -> 1
2020-07-10T11:14:59.291 Sending 53 bytes 0.0.0.0:34827 -> 172.18.157.59:50919.
2020-07-10T11:14:59.323 Accepted connection from PVA client: 172.18.157.59:49775.
2020-07-10T11:14:59.342 Accepted security plug-in 'ca' for PVA client: 172.18.157.59:49775.
2020-07-10T11:14:59.342 Authentication completed with status 'OK' for PVA client: 172.18.157.59:49775.
2020-07-10T11:14:59.342 Serving to PVA client: 172.18.157.59:49775.
DEBUG:p4p.gw:Create b'demo:bb:counter' by 172.18.157.59:49775
DEBUG:p4p.asLib:(re)create <p4p._gw.Channel object at 0x7f3238581770>, DEFAULT, benjaminbertrand, 172.18.157.59, 0
2020-07-10T11:15:04.302 TCP socket to 172.18.157.59:49775 is to be closed.
2020-07-10T11:15:04.302 Transport to 172.18.157.59:49775 still has 1 channel(s) active and closing...
DEBUG:p4p.gw:Channel Cache sweep
2020-07-10T11:15:47.914 UDP Server Rx (61) 172.30.8.12:5076 <- 172.18.157.59:57564
2020-07-10T11:15:47.915 UDP Tx (85) 172.30.8.12:5076 -> 224.0.0.128:5076.
2020-07-10T11:15:47.915 UDP Server Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
2020-07-10T11:15:47.915 UDP Client Rx (85) 224.0.0.128:5076 <- 172.30.8.12:5076
DEBUG:p4p.gw:172.30.8.12:5076 Searching for b'demo:bb:counter'
DEBUG:p4p.gw:allowed: b'demo:bb:counter' by 172.30.8.12:5076 -> 1
2020-07-10T11:15:47.915 Sending 53 bytes 0.0.0.0:34827 -> 172.18.157.59:57564.
2020-07-10T11:15:47.949 Accepted connection from PVA client: 172.18.157.59:49781.
2020-07-10T11:15:47.969 Accepted security plug-in 'ca' for PVA client: 172.18.157.59:49781.
2020-07-10T11:15:47.969 Authentication completed with status 'OK' for PVA client: 172.18.157.59:49781.
2020-07-10T11:15:47.970 Serving to PVA client: 172.18.157.59:49781.
DEBUG:p4p.gw:Create b'demo:bb:counter' by 172.18.157.59:49781
DEBUG:p4p.asLib:(re)create <p4p._gw.Channel object at 0x7f3238581770>, DEFAULT, benjaminbertrand, 172.18.157.59, 0
2020-07-10T11:15:48.037 TCP socket to 172.18.157.59:49781 is to be closed.
2020-07-10T11:15:48.037 Transport to 172.18.157.59:49781 still has 1 channel(s) active and closing...
test-pvagw-03:GW:STS:cache
$ pvmonitor test-pvagw-03:GW:STS:cache
test-pvagw-03:GW:STS:cache 2020-07-10 11:14:44.302 []
test-pvagw-03:GW:STS:cache 2020-07-10 11:15:44.349 ["demo:bb:counter"]
test-pvagw-01 (-v)
DEBUG:p4p.gw:172.30.0.31:5076 Searching for b'demo:bb:counter'
DEBUG:p4p.gw:allowed: b'demo:bb:counter' by 172.30.0.31:5076 -> 0
DEBUG:p4p.gw:172.30.0.31:5076 Searching for b'demo:bb:counter'
DEBUG:p4p.gw:allowed: b'demo:bb:counter' by 172.30.0.31:5076 -> 1
DEBUG:p4p.gw:Create b'demo:bb:counter' by 172.30.8.12:51298
DEBUG:p4p.asLib:(re)create <p4p._gw.Channel object at 0x7f827197ffb0>, DEFAULT, csi, 172.30.8.12, 0
DEBUG:p4p.gw:Channel Cache sweep
test-pvagw-01:GW:STS:cache
$ pvmonitor test-pvagw-01:GW:STS:cache
test-pvagw-01:GW:STS:cache 2020-07-10 11:14:47.601 []
test-pvagw-01:GW:STS:cache 2020-07-10 11:15:47.657 ["demo:bb:counter"]