Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] Setting env variable DLV_LISTEN_CMD_NSC_VPP to a valid dlv '--listen' value will cause the dlv debugger to execute this binary and listen as directed. Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] there are 5 phases which will be executed followed by a success message: Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] the phases include: Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] 1: get config from environment Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] 2: run vpp and get a connection to it Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] 3: retrieve spiffe svid Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] 4: create network service client Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] 5: connect to all passed services Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] a final success message with start time duration Jun 18 07:11:46.491 [INFO] [cmd:/bin/cmd-nsc-vpp] executing phase 1: get config from environment (time since start: 65.471µs) Jun 18 07:11:46.495 [INFO] [cmd:/bin/cmd-nsc-vpp] Config: &main.Config{Name:"cmd-nsc-vpp", DialTimeout:60000000000, RequestTimeout:300000000000, ConnectTo:url.URL{Scheme:"unix", Opaque:"", User:(*url.Userinfo)(nil), Host:"", Path:"/var/lib/networkservicemesh/nsm.io.sock", RawPath:"", ForceQuery:false, RawQuery:"", Fragment:"", RawFragment:""}, MaxTokenLifetime:86400000000000, NetworkServices:[]url.URL{url.URL{Scheme:"memif", Opaque:"", User:(*url.Userinfo)(nil), Host:"icmp-responder", Path:"/nsm-1", RawPath:"", ForceQuery:false, RawQuery:"", Fragment:"", RawFragment:""}}} Jun 18 07:11:46.495 [INFO] [cmd:/bin/cmd-nsc-vpp] [duration:4.034252ms] completed phase 1: get config from environment Jun 18 07:11:46.495 [INFO] [cmd:/bin/cmd-nsc-vpp] executing phase 2: run vpp and get a connection to it (time since start: 4.12712ms) This application is configured via the environment. The following environment variables can be used: KEY TYPE DEFAULT REQUIRED DESCRIPTION NSM_NAME String cmd-nsc-vpp Name of Endpoint NSM_DIAL_TIMEOUT Duration 5s timeout to dial NSMgr NSM_REQUEST_TIMEOUT Duration 15s timeout to request NSE NSM_CONNECT_TO URL unix:///var/lib/networkservicemesh/nsm.io.sock url to connect to NSM_MAX_TOKEN_LIFETIME Duration 24h maximum lifetime of tokens NSM_NETWORK_SERVICES Comma-separated list of URL A list of Network Service Requests Jun 18 07:11:46.496 [INFO] Configuration file: "/etc/vpp/helper/vpp.conf" not found, using defaults Jun 18 07:11:46.497 [INFO] [cmd:/bin/cmd-nsc-vpp] [duration:1.253216ms] completed phase 2: run vpp and get a connection to it Jun 18 07:11:46.497 [INFO] [cmd:/bin/cmd-nsc-vpp] executing phase 3: retrieving svid, check spire agent logs if this is the last line you see (time since start: 5.556479ms) Jun 18 07:11:46.496 [INFO] [cmd:vpp] vpp[8]: clib_elf_parse_file: open `linux-vdso.so.1': No such file or directory Jun 18 07:11:46.496 [INFO] [cmd:vpp] vpp[8]: buffer: vlib_physmem_shared_map_create: clib_mem_create_hugetlb_fd: open: No such file or directory Jun 18 07:11:46.496 [INFO] [cmd:vpp] Jun 18 07:11:46.496 [INFO] [cmd:vpp] vpp[8]: buffer: falling back to non-hugepage backed buffer pool Jun 18 07:11:46.496 [INFO] [cmd:vpp] vpp[8]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded... Jun 18 07:11:47.557 [INFO] SVID: "spiffe://example.org/ns/nsm/sa/endpoint-nsc" Jun 18 07:11:47.557 [INFO] [cmd:/bin/cmd-nsc-vpp] [duration:1.059700439s] completed phase 3: retrieving svid Jun 18 07:11:47.557 [INFO] [cmd:/bin/cmd-nsc-vpp] executing phase 4: create network service client (time since start: 1.065295482s) Jun 18 07:11:47.557 [INFO] [cmd:/bin/cmd-nsc-vpp] executing phase 5: connect to all passed services (time since start: 1.065391848s) Jun 18 07:11:47.557 [INFO] [cmd:/bin/cmd-nsc-vpp] (1) ⎆ sdk/pkg/networkservice/common/updatepath/updatePathClient.Request() Jun 18 07:11:47.557 [INFO] [cmd:/bin/cmd-nsc-vpp] (1.1) request={"connection":{"id":"cmd-nsc-vpp-c7f0a7dc-aeb2-4c16-874d-3cefe80c5889","network_service":"icmp-responder"}} Jun 18 07:11:47.558 [INFO] [cmd:/bin/cmd-nsc-vpp] (1.2) request-diff={"connection":{"path":{"path_segments":{"+0":{"name":"cmd-nsc-vpp","id":"cmd-nsc-vpp-c7f0a7dc-aeb2-4c16-874d-3cefe80c5889"}}}}} Jun 18 07:11:47.558 [INFO] [cmd:/bin/cmd-nsc-vpp] (2) ⎆ sdk/pkg/networkservice/common/serialize/serializeClient.Request() Jun 18 07:11:47.558 [INFO] [cmd:/bin/cmd-nsc-vpp] (3) ⎆ sdk/pkg/networkservice/common/refresh/refreshClient.Request() Jun 18 07:11:47.558 [INFO] [cmd:/bin/cmd-nsc-vpp] (4) ⎆ sdk/pkg/networkservice/utils/metadata/metaDataClient.Request() Jun 18 07:11:47.559 [INFO] [cmd:/bin/cmd-nsc-vpp] (5) ⎆ sdk/pkg/networkservice/core/adapters/serverToClient.Request() Jun 18 07:11:47.559 [INFO] [cmd:/bin/cmd-nsc-vpp] (6) ⎆ sdk/pkg/networkservice/common/heal/healServer.Request() Jun 18 07:11:47.559 [INFO] [cmd:/bin/cmd-nsc-vpp] (7) ⎆ sdk/pkg/networkservice/common/clienturl/clientURLServer.Request() Jun 18 07:11:47.559 [INFO] [cmd:/bin/cmd-nsc-vpp] (8) ⎆ sdk/pkg/networkservice/common/connect/connectServer.Request() Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] (9) ⎆ sdk/pkg/networkservice/utils/metadata/metaDataClient.Request() Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] (10) ⎆ sdk/pkg/networkservice/core/next/nextClient.Request() Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] (11) ⎆ sdk-vpp/pkg/networkservice/up/peerup/peerupClient.Request() Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] (12) ⎆ sdk-vpp/pkg/networkservice/up/upClient.Request() Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] [duration:147.507µs] [vppapi:WantInterfaceEvents] (12.1) completed Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] (13) ⎆ sdk/pkg/networkservice/core/next/nextClient.Request() Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] (14) ⎆ sdk-vpp/pkg/networkservice/connectioncontext/mtu/mtuClient.Request() Jun 18 07:11:47.565 [INFO] [cmd:/bin/cmd-nsc-vpp] (14.1) request-diff={"connection":{"context":{"MTU":9000}}} Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (15) ⎆ sdk-vpp/pkg/networkservice/connectioncontext/ipcontext/routes/routesClient.Request() Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (16) ⎆ sdk-vpp/pkg/networkservice/connectioncontext/ipcontext/ipaddress/ipaddressClient.Request() Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (17) ⎆ sdk/pkg/networkservice/core/next/nextClient.Request() Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (18) ⎆ sdk-vpp/pkg/networkservice/mechanisms/memif/memifClient.Request() Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (18.1) request-diff={"mechanism_preferences":{"+0":{"cls":"LOCAL","type":"MEMIF"}}} Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (19) ⎆ sdk-vpp/pkg/networkservice/mechanisms/memif/memifproxy/memifProxyClient.Request() Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (20) ⎆ sdk/pkg/networkservice/common/mechanisms/sendfd/sendFDClient.Request() Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (21) ⎆ sdk/pkg/networkservice/common/mechanisms/recvfd/recvFDClient.Request() Jun 18 07:11:47.566 [INFO] [cmd:/bin/cmd-nsc-vpp] (22) ⎆ sdk/pkg/networkservice/common/heal/healClient.Request() Jun 18 07:11:47.567 [INFO] [cmd:/bin/cmd-nsc-vpp] (23) ⎆ sdk/pkg/networkservice/common/null/nullClient.Request() Jun 18 07:11:47.567 [INFO] [cmd:/bin/cmd-nsc-vpp] (24) ⎆ api/pkg/api/networkservice/networkServiceClient.Request()