Closed aadarshkt closed 4 days ago
Issue is that find and offer packets are both visible at the interface, but three-way handshake is not happening, nor the communication.
Same behaviour can be replicated for tutorial on vsomeip in 10 mins, or with vsomeip-ctrl command line tool.
Here is the client log. 2024-10-29 16:48:59.363061 hello_world_client [info] Using configuration file: "/home/ubuntu/vsomeip-sample/client/config_file.json". 2024-10-29 16:48:59.371491 hello_world_client [info] Parsed vsomeip configuration in 0ms 2024-10-29 16:48:59.371568 hello_world_client [info] Configuration module loaded. 2024-10-29 16:48:59.371610 hello_world_client [info] Security disabled! 2024-10-29 16:48:59.371640 hello_world_client [info] Initializing vsomeip (3.5.1) application "hello_world_client". 2024-10-29 16:48:59.372086 hello_world_client [info] Instantiating routing manager [Host]. 2024-10-29 16:48:59.372829 hello_world_client [info] create_routing_root: Routing root @ /tmp/vsomeip-0 2024-10-29 16:48:59.373330 hello_world_client [info] Service Discovery enabled. Trying to load module. 2024-10-29 16:48:59.374898 hello_world_client [info] Service Discovery module loaded. 2024-10-29 16:48:59.375217 hello_world_client [info] Application(hello_world_client, 1313) is initialized (11, 100). 2024-10-29 16:48:59.375429 hello_world_client [info] Starting vsomeip application "hello_world_client" (1313) using 2 threads I/O nice 0 2024-10-29 16:48:59.375961 hello_world_client [debug] Thread created. Number of active threads for hello_world_client : 1 2024-10-29 16:48:59.376304 hello_world_client [info] Client [1313] routes unicast:172.31.88.228, netmask:255.255.240.0 2024-10-29 16:48:59.377215 hello_world_client [info] Watchdog is disabled! 2024-10-29 16:48:59.377713 hello_world_client [info] io thread id from application: 1313 (hello_world_client) is: 7904cfd4db80 TID: 4911 2024-10-29 16:48:59.378336 hello_world_client [info] Network interface "eth0" state changed: up 2024-10-29 16:48:59.378767 hello_world_client [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:59.378922 hello_world_client [info] Route "default route (0.0.0.0/0) if: eth0 gw: 172.31.80.1" state changed: up 2024-10-29 16:48:59.379339 hello_world_client [debug] Joining to multicast group 224.244.224.245 from 172.31.88.228 2024-10-29 16:48:59.379490 hello_world_client [info] SOME/IP routing ready. 2024-10-29 16:48:59.379699 hello_world_client [warning] Route "224.244.224.245/32 if: eth0 gw: n/a" state changed: up 2024-10-29 16:48:59.380345 hello_world_client [info] udp_server_endpoint_impl: SO_RCVBUF is: 212992 (1703936) local port:30490 2024-10-29 16:48:59.380646 hello_world_client [info] main dispatch thread id from application: 1313 (hello_world_client) is: 7904ce600640 TID: 4913 2024-10-29 16:48:59.380963 hello_world_client [info] REQUEST(1313): [1111.2222:255.4294967295] 2024-10-29 16:48:59.381472 hello_world_client [info] create_local_server: Listening @ /tmp/vsomeip-1313 2024-10-29 16:48:59.381831 hello_world_client [info] shutdown thread id from application: 1313 (hello_world_client) is: 7904cdc00640 TID: 4914 2024-10-29 16:48:59.382230 hello_world_client [info] io thread id from application: 1313 (hello_world_client) is: 7904cc800640 TID: 4916 2024-10-29 16:49:09.379669 hello_world_client [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:19.382011 hello_world_client [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:29.382916 hello_world_client [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:35.785185 hello_world_client [warning] Network interface "eth0" state changed: up 2024-10-29 16:49:35.785726 hello_world_client [warning] Route "default route (0.0.0.0/0) if: eth0 gw: 172.31.80.1" state changed: up 2024-10-29 16:49:35.785958 hello_world_client [warning] Route "224.244.224.245/32 if: eth0 gw: n/a" state changed: up 2024-10-29 16:49:39.383850 hello_world_client [info] vSomeIP 3.5.1 | (default) ^C2024-10-29 16:49:39.273937 hello_world_client [info] RELEASE(1313): [1111.2222] 2024-10-29 16:49:39.274270 hello_world_client [info] Stopping vsomeip application "hello_world_client" (1313). 2024-10-29 16:49:39.275929 hello_world_client [debug] Thread destroyed. Number of active threads for hello_world_client : 0
Here is the server log : 2024-10-29 16:46:16.833424 hello_world_service [info] Using configuration file: "/home/ubuntu/vsomeip-sample/server/config_file.json". 2024-10-29 16:46:16.834132 hello_world_service [info] Parsed vsomeip configuration in 3ms 2024-10-29 16:46:16.834210 hello_world_service [info] Configuration module loaded. 2024-10-29 16:46:16.834335 hello_world_service [info] Security disabled! 2024-10-29 16:46:16.834412 hello_world_service [info] Initializing vsomeip (3.5.1) application "hello_world_service". 2024-10-29 16:46:16.834904 hello_world_service [info] Instantiating routing manager [Host]. 2024-10-29 16:46:16.835693 hello_world_service [info] create_routing_root: Routing root @ /tmp/vsomeip-0 2024-10-29 16:46:16.843803 hello_world_service [info] Service Discovery enabled. Trying to load module. 2024-10-29 16:46:16.893557 hello_world_service [info] Service Discovery module loaded. 2024-10-29 16:46:16.894109 hello_world_service [info] Application(hello_world_service, 1212) is initialized (11, 100). 2024-10-29 16:46:16.894310 hello_world_service [info] Starting vsomeip application "hello_world_service" (1212) using 2 threads I/O nice 0 2024-10-29 16:46:16.894918 hello_world_service [debug] Thread created. Number of active threads for hello_world_service : 1 2024-10-29 16:46:16.895309 hello_world_service [info] Client [1212] routes unicast:172.31.26.247, netmask:255.255.240.0 2024-10-29 16:46:16.896431 hello_world_service [info] Watchdog is disabled! 2024-10-29 16:46:16.896821 hello_world_service [info] io thread id from application: 1212 (hello_world_service) is: 7052f2b13b80 TID: 1840 2024-10-29 16:46:16.897401 hello_world_service [info] Network interface "enX0" state changed: up 2024-10-29 16:46:16.897796 hello_world_service [info] Route "default route (0.0.0.0/0) if: enX0 gw: 172.31.16.1" state changed: up 2024-10-29 16:46:16.898452 hello_world_service [info] main dispatch thread id from application: 1212 (hello_world_service) is: 7052f0e006c0 TID: 1843 2024-10-29 16:46:16.898965 hello_world_service [info] shutdown thread id from application: 1212 (hello_world_service) is: 7052f04006c0 TID: 1844 2024-10-29 16:46:16.899415 hello_world_service [info] io thread id from application: 1212 (hello_world_service) is: 7052ef0006c0 TID: 1846 2024-10-29 16:46:16.914902 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:46:16.929354 hello_world_service [debug] Joining to multicast group 224.244.224.245 from 172.31.26.247 2024-10-29 16:46:16.929884 hello_world_service [info] SOME/IP routing ready. 2024-10-29 16:46:16.930375 hello_world_service [info] Hey udp_server_endpoint_implementation called first time 2024-10-29 16:46:16.932496 hello_world_service [info] Hey udp_server_endpoint function called 2024-10-29 16:46:16.932587 hello_world_service [info] udp_server_endpoint_impl: SO_RCVBUF is: 212992 (1703936) local port:30490 2024-10-29 16:46:16.932814 hello_world_service [warning] Route "224.244.224.245/32 if: enX0 gw: n/a" state changed: up 2024-10-29 16:46:16.931508 hello_world_service [info] create_local_server: Listening @ /tmp/vsomeip-1212 2024-10-29 16:46:16.933764 hello_world_service [info] OFFER(1212): [1111.2222:0.0] (true) 2024-10-29 16:46:26.915970 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:46:36.917329 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:46:46.918541 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:46:56.921690 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:47:06.922613 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:47:16.924593 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:47:26.925879 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:47:36.928901 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:47:46.930300 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:47:56.935837 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:06.937214 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:16.938589 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:26.939944 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:36.941227 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:46.942528 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:56.943460 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:06.944752 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:16.948739 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:26.950042 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:36.951248 hello_world_service [info] vSomeIP 3.5.1 | (default) ^C2024-10-29 16:49:46.952231 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:49:47.697294 hello_world_service [info] STOP OFFER(1212): [1111.2222:0.0] (true) 2024-10-29 16:49:47.698409 hello_world_service [info] Stopping vsomeip application "hello_world_service" (1212). 2024-10-29 16:49:47.702028 hello_world_service [debug] Thread destroyed. Number of active threads for hello_world_service : 0
Here is the capture packets. capture_vsomeip_client_49.zip
Changing the netmask to keep both machines in same subnet was the solution.
Issue is that find and offer packets are both visible at the interface, but three-way handshake is not happening, nor the communication.
Same behaviour can be replicated for tutorial on vsomeip in 10 mins, or with vsomeip-ctrl command line tool.
Here is the client log. 2024-10-29 16:48:59.363061 hello_world_client [info] Using configuration file: "/home/ubuntu/vsomeip-sample/client/config_file.json". 2024-10-29 16:48:59.371491 hello_world_client [info] Parsed vsomeip configuration in 0ms 2024-10-29 16:48:59.371568 hello_world_client [info] Configuration module loaded. 2024-10-29 16:48:59.371610 hello_world_client [info] Security disabled! 2024-10-29 16:48:59.371640 hello_world_client [info] Initializing vsomeip (3.5.1) application "hello_world_client". 2024-10-29 16:48:59.372086 hello_world_client [info] Instantiating routing manager [Host]. 2024-10-29 16:48:59.372829 hello_world_client [info] create_routing_root: Routing root @ /tmp/vsomeip-0 2024-10-29 16:48:59.373330 hello_world_client [info] Service Discovery enabled. Trying to load module. 2024-10-29 16:48:59.374898 hello_world_client [info] Service Discovery module loaded. 2024-10-29 16:48:59.375217 hello_world_client [info] Application(hello_world_client, 1313) is initialized (11, 100). 2024-10-29 16:48:59.375429 hello_world_client [info] Starting vsomeip application "hello_world_client" (1313) using 2 threads I/O nice 0 2024-10-29 16:48:59.375961 hello_world_client [debug] Thread created. Number of active threads for hello_world_client : 1 2024-10-29 16:48:59.376304 hello_world_client [info] Client [1313] routes unicast:172.31.88.228, netmask:255.255.240.0 2024-10-29 16:48:59.377215 hello_world_client [info] Watchdog is disabled! 2024-10-29 16:48:59.377713 hello_world_client [info] io thread id from application: 1313 (hello_world_client) is: 7904cfd4db80 TID: 4911 2024-10-29 16:48:59.378336 hello_world_client [info] Network interface "eth0" state changed: up 2024-10-29 16:48:59.378767 hello_world_client [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:48:59.378922 hello_world_client [info] Route "default route (0.0.0.0/0) if: eth0 gw: 172.31.80.1" state changed: up 2024-10-29 16:48:59.379339 hello_world_client [debug] Joining to multicast group 224.244.224.245 from 172.31.88.228 2024-10-29 16:48:59.379490 hello_world_client [info] SOME/IP routing ready. 2024-10-29 16:48:59.379699 hello_world_client [warning] Route "224.244.224.245/32 if: eth0 gw: n/a" state changed: up 2024-10-29 16:48:59.380345 hello_world_client [info] udp_server_endpoint_impl: SO_RCVBUF is: 212992 (1703936) local port:30490
2024-10-29 16:48:59.380646 hello_world_client [info] main dispatch thread id from application: 1313 (hello_world_client) is: 7904ce600640 TID: 4913
2024-10-29 16:48:59.380963 hello_world_client [info] REQUEST(1313): [1111.2222:255.4294967295]
2024-10-29 16:48:59.381472 hello_world_client [info] create_local_server: Listening @ /tmp/vsomeip-1313
2024-10-29 16:48:59.381831 hello_world_client [info] shutdown thread id from application: 1313 (hello_world_client) is: 7904cdc00640 TID: 4914
2024-10-29 16:48:59.382230 hello_world_client [info] io thread id from application: 1313 (hello_world_client) is: 7904cc800640 TID: 4916
2024-10-29 16:49:09.379669 hello_world_client [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:19.382011 hello_world_client [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:29.382916 hello_world_client [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:35.785185 hello_world_client [warning] Network interface "eth0" state changed: up
2024-10-29 16:49:35.785726 hello_world_client [warning] Route "default route (0.0.0.0/0) if: eth0 gw: 172.31.80.1" state changed: up
2024-10-29 16:49:35.785958 hello_world_client [warning] Route "224.244.224.245/32 if: eth0 gw: n/a" state changed: up
2024-10-29 16:49:39.383850 hello_world_client [info] vSomeIP 3.5.1 | (default)
^C2024-10-29 16:49:39.273937 hello_world_client [info] RELEASE(1313): [1111.2222]
2024-10-29 16:49:39.274270 hello_world_client [info] Stopping vsomeip application "hello_world_client" (1313).
2024-10-29 16:49:39.275929 hello_world_client [debug] Thread destroyed. Number of active threads for hello_world_client : 0
Here is the server log : 2024-10-29 16:46:16.833424 hello_world_service [info] Using configuration file: "/home/ubuntu/vsomeip-sample/server/config_file.json". 2024-10-29 16:46:16.834132 hello_world_service [info] Parsed vsomeip configuration in 3ms 2024-10-29 16:46:16.834210 hello_world_service [info] Configuration module loaded. 2024-10-29 16:46:16.834335 hello_world_service [info] Security disabled! 2024-10-29 16:46:16.834412 hello_world_service [info] Initializing vsomeip (3.5.1) application "hello_world_service". 2024-10-29 16:46:16.834904 hello_world_service [info] Instantiating routing manager [Host]. 2024-10-29 16:46:16.835693 hello_world_service [info] create_routing_root: Routing root @ /tmp/vsomeip-0 2024-10-29 16:46:16.843803 hello_world_service [info] Service Discovery enabled. Trying to load module. 2024-10-29 16:46:16.893557 hello_world_service [info] Service Discovery module loaded. 2024-10-29 16:46:16.894109 hello_world_service [info] Application(hello_world_service, 1212) is initialized (11, 100). 2024-10-29 16:46:16.894310 hello_world_service [info] Starting vsomeip application "hello_world_service" (1212) using 2 threads I/O nice 0 2024-10-29 16:46:16.894918 hello_world_service [debug] Thread created. Number of active threads for hello_world_service : 1 2024-10-29 16:46:16.895309 hello_world_service [info] Client [1212] routes unicast:172.31.26.247, netmask:255.255.240.0 2024-10-29 16:46:16.896431 hello_world_service [info] Watchdog is disabled! 2024-10-29 16:46:16.896821 hello_world_service [info] io thread id from application: 1212 (hello_world_service) is: 7052f2b13b80 TID: 1840 2024-10-29 16:46:16.897401 hello_world_service [info] Network interface "enX0" state changed: up 2024-10-29 16:46:16.897796 hello_world_service [info] Route "default route (0.0.0.0/0) if: enX0 gw: 172.31.16.1" state changed: up 2024-10-29 16:46:16.898452 hello_world_service [info] main dispatch thread id from application: 1212 (hello_world_service) is: 7052f0e006c0 TID: 1843 2024-10-29 16:46:16.898965 hello_world_service [info] shutdown thread id from application: 1212 (hello_world_service) is: 7052f04006c0 TID: 1844 2024-10-29 16:46:16.899415 hello_world_service [info] io thread id from application: 1212 (hello_world_service) is: 7052ef0006c0 TID: 1846 2024-10-29 16:46:16.914902 hello_world_service [info] vSomeIP 3.5.1 | (default) 2024-10-29 16:46:16.929354 hello_world_service [debug] Joining to multicast group 224.244.224.245 from 172.31.26.247 2024-10-29 16:46:16.929884 hello_world_service [info] SOME/IP routing ready. 2024-10-29 16:46:16.930375 hello_world_service [info] Hey udp_server_endpoint_implementation called first time 2024-10-29 16:46:16.932496 hello_world_service [info] Hey udp_server_endpoint function called 2024-10-29 16:46:16.932587 hello_world_service [info] udp_server_endpoint_impl: SO_RCVBUF is: 212992 (1703936) local port:30490
2024-10-29 16:46:16.932814 hello_world_service [warning] Route "224.244.224.245/32 if: enX0 gw: n/a" state changed: up
2024-10-29 16:46:16.931508 hello_world_service [info] create_local_server: Listening @ /tmp/vsomeip-1212
2024-10-29 16:46:16.933764 hello_world_service [info] OFFER(1212): [1111.2222:0.0] (true)
2024-10-29 16:46:26.915970 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:46:36.917329 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:46:46.918541 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:46:56.921690 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:47:06.922613 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:47:16.924593 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:47:26.925879 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:47:36.928901 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:47:46.930300 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:47:56.935837 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:48:06.937214 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:48:16.938589 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:48:26.939944 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:48:36.941227 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:48:46.942528 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:48:56.943460 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:06.944752 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:16.948739 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:26.950042 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:36.951248 hello_world_service [info] vSomeIP 3.5.1 | (default)
^C2024-10-29 16:49:46.952231 hello_world_service [info] vSomeIP 3.5.1 | (default)
2024-10-29 16:49:47.697294 hello_world_service [info] STOP OFFER(1212): [1111.2222:0.0] (true)
2024-10-29 16:49:47.698409 hello_world_service [info] Stopping vsomeip application "hello_world_service" (1212).
2024-10-29 16:49:47.702028 hello_world_service [debug] Thread destroyed. Number of active threads for hello_world_service : 0