time="2021-06-21T14:35:06Z" level=info msg="Starting NetworkServiceMesh Client ..." 2021/06/21 14:35:06 Initializing logging reporter This application is configured via the environment. The following environment variables can be used: KEY TYPE DEFAULT REQUIRED DESCRIPTION NSM_NAME String cmd-nsc-init Name of the client 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 21 14:35:06.404 [INFO] [cmd:[/bin/app]] rootConf: &{Name:postgres-cl DialTimeout:5s RequestTimeout:15s ConnectTo:{Scheme:unix Opaque: User: Host: Path:/var/lib/networkservicemesh/nsm.io.sock RawPath: ForceQuery:false RawQuery: Fragment: RawFragment:} MaxTokenLifetime:24h0m0s NetworkServices:[{Scheme:kernel Opaque: User: Host:my-postgres-service Path:/nsm-1 RawPath: ForceQuery:false RawQuery: Fragment: RawFragment:}]} Jun 21 14:35:09.948 [INFO] [cmd:[/bin/app]] sVID: "spiffe://example.org/ns/ns-67jnk/sa/default" Jun 21 14:35:09.948 [INFO] [cmd:[/bin/app]] NSC: Connecting to Network Service Manager unix:///var/lib/networkservicemesh/nsm.io.sock Jun 21 14:35:09.949 [INFO] [cmd:[/bin/app]] (1) ⎆ sdk/pkg/networkservice/common/updatepath/updatePathClient.Request() span=19b650bab326c3a1:19b650bab326c3a1:0000000000000000:1 Jun 21 14:35:09.949 [INFO] [cmd:[/bin/app]] (1.1) request={"connection":{"id":"postgres-cl-0","network_service":"my-postgres-service"},"mechanism_preferences":[{"cls":"LOCAL","type":"KERNEL","parameters":{"name":"nsm-1"}}]} span=19b650bab326c3a1:19b650bab326c3a1:0000000000000000:1 Jun 21 14:35:09.949 [INFO] [cmd:[/bin/app]] (1.2) request-diff={"connection":{"path":{"path_segments":{"+0":{"name":"postgres-cl","id":"postgres-cl-0"}}}}} span=19b650bab326c3a1:19b650bab326c3a1:0000000000000000:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (2) ⎆ sdk/pkg/networkservice/common/serialize/serializeClient.Request() span=19b650bab326c3a1:184d1182dd6d5f40:19b650bab326c3a1:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (3) ⎆ sdk-sriov/pkg/networkservice/common/token/tokenClient.Request() span=19b650bab326c3a1:2175669d81223f25:184d1182dd6d5f40:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (4) ⎆ sdk/pkg/networkservice/common/mechanisms/mechanismsClient.Request() span=19b650bab326c3a1:4e588ce1447f50aa:2175669d81223f25:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (5) ⎆ sdk/pkg/networkservice/core/next/nextClient.Request() span=19b650bab326c3a1:0bda2fe35b6dc33a:4e588ce1447f50aa:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (6) ⎆ sdk/pkg/networkservice/common/mechanisms/kernel/kernelMechanismClient.Request() span=19b650bab326c3a1:410ebca6b26338a8:0bda2fe35b6dc33a:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (6.1) request-diff={"mechanism_preferences":{"0":{"parameters":{"+inodeURL":"file:///proc/thread-self/ns/net"}}}} span=19b650bab326c3a1:410ebca6b26338a8:0bda2fe35b6dc33a:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (7) ⎆ sdk/pkg/networkservice/connectioncontext/dnscontext/dnsContextClient.Request() span=19b650bab326c3a1:72b7db49b1d7b723:410ebca6b26338a8:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (8) ⎆ sdk/pkg/networkservice/common/authorize/authorizeClient.Request() span=19b650bab326c3a1:2750018b0b6d98fa:72b7db49b1d7b723:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (9) ⎆ sdk/pkg/networkservice/common/mechanisms/sendfd/sendFDClient.Request() span=19b650bab326c3a1:6a536055194ff572:2750018b0b6d98fa:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (9.1) request-diff={"mechanism_preferences":{"0":{"parameters":{"inodeURL":"inode://4/4026534129"}}}} span=19b650bab326c3a1:6a536055194ff572:2750018b0b6d98fa:1 Jun 21 14:35:09.950 [INFO] [cmd:[/bin/app]] (10) ⎆ api/pkg/api/networkservice/networkServiceClient.Request() span=19b650bab326c3a1:2db5cb7048a83f8d:6a536055194ff572:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:3d583145d0cb993c:2db5cb7048a83f8d:1 Jun 21 14:35:10.660 [INFO] [cmd:[/bin/app]] (10.1) response={"id":"postgres-cl-0","network_service":"my-postgres-service","mechanism":{"cls":"LOCAL","type":"KERNEL","parameters":{"inodeURL":"inode://4/4026534129","name":"nsm-1"}},"context":{"ip_context":{"src_ip_addrs":["172.16.1.101/32"],"dst_ip_addrs":["172.16.1.100/32"],"src_routes":[{"prefix":"172.16.1.100/32"}],"dst_routes":[{"prefix":"172.16.1.101/32"}]},"dns_context":{},"MTU":1450},"labels":{"nodeName":"kind-worker"},"path":{"path_segments":[{"name":"postgres-cl","id":"postgres-cl-0","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk3MDgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLTY3am5rL3NhL2RlZmF1bHQifQ.yNI4wOI2nrVF6wa5HqtDvJQEmfv-wnbkbbCjbnCjsaSnpXDGU1R7WK43905VXWnzMIvVqIFJNmlLQXmzrlvL2A","expires":{"seconds":1624289698}},{"name":"nsmgr-7rgcn","id":"79e88dc0-3013-49c1-a54f-ce88d7e1db19","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.kRwuk4l06Rpu5U2rusNVtvaJBBJo44ODvp5SQcWpGegTNF6UwC9fLIu-FCRsQQix4StGefZpEiujfhtthUbkzg","expires":{"seconds":1624289698}},{"name":"forwarder-vpp-k5b6p","id":"7345ade6-4971-4411-a687-95761f926cbe","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.uXTseP5uCdcOUy2O7h4y2QI84FaozJQhDbstxkHHHqvU9WFV2TR0e6H2zf2TzzOw-C3mZ3P95xxtqo2Zns5u7w","expires":{"seconds":1624289698},"metrics":{"client_drops":"0","client_rx_bytes":"0","client_rx_packets":"0","client_tx_bytes":"0","client_tx_packets":"0","server_drops":"0","server_rx_bytes":"0","server_rx_packets":"0","server_tx_bytes":"0","server_tx_packets":"0"}},{"name":"nsmgr-7rgcn","id":"7d5e6ad2-d593-43e9-a7a8-45de74234062","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.AHdyj46fh0Lqm1yprqLWSp-VJ8qCTfhc9bDHY2rwg6PVDJKK5B2r_Fgv_UCcBw4EA35HxSsbtc9hJzo3sdnC3Q","expires":{"seconds":1624289698}},{"name":"nsmgr-r5hqj","id":"fdd55302-839b-49b3-bc74-4f54fec62067","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.CukjPxjIGCAoEd0lJ0u5nuKZ2z3E3trSPHLvUvC34H4dRnXezGflYlKI1IzsCgh8Karb9CLyAChOKEqV3zGq-Q","expires":{"seconds":1624289698}},{"name":"forwarder-vpp-d9w2q","id":"c60edf36-efb4-4f4c-8ac6-c97c61d6f710","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.7Wbac_bUXAUg9XuwMEYahft7aPTKguYRELF9O8llC3czP47Cax2XkQlxKqA2lXOMVoAZQwSlZq3F_hpz4lXAbQ","expires":{"seconds":1624289698},"metrics":{"client_drops":"0","client_rx_bytes":"0","client_rx_packets":"0","client_tx_bytes":"0","client_tx_packets":"0","server_drops":"0","server_rx_bytes":"0","server_rx_packets":"0","server_tx_bytes":"0","server_tx_packets":"0"}},{"name":"nsmgr-r5hqj","id":"58222ddd-98e8-42ae-8c58-c9d6b09e5340","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy02N2puay9zYS9kZWZhdWx0IiwiZXhwIjoxNjI0Mjg5Njk4LCJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQifQ.NCtvSMRzgF7z92xDKdl6iRMk1aVUMzt_Vq3YWMCNysT3YcUI1lVWfxkGap6Lmu5t45uwjFG0PecPzMZSEMmsJQ","expires":{"seconds":1624289698}},{"name":"nse-kernel-6df9cf6549-l5xvg","id":"c9db4ecd-0a5e-4bfb-92d9-b0d94837575f","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk3MDgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLTY3am5rL3NhL2RlZmF1bHQifQ.fIq0VMkp30jfvaUB8G9VA-QRQ2CIRAYu0bc3Cxq2j7OwCFI-rdhsjs1CvL9qQ7A_FzUZHlWnWywiQeXfK1d6oA","expires":{"seconds":1624289698}}]},"network_service_endpoint_name":"a71cba59-1cc5-4da3-aa67-fad564c2129a-nse-kernel-6df9cf6549-l5xvg","payload":"ETHERNET"} span=19b650bab326c3a1:2db5cb7048a83f8d:6a536055194ff572:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:2db5cb7048a83f8d:6a536055194ff572:1 Jun 21 14:35:10.661 [INFO] [cmd:[/bin/app]] (9.2) response-diff={"mechanism":{"parameters":{"inodeURL":"file:///proc/thread-self/ns/net"}}} span=19b650bab326c3a1:6a536055194ff572:2750018b0b6d98fa:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:6a536055194ff572:2750018b0b6d98fa:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:2750018b0b6d98fa:72b7db49b1d7b723:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:72b7db49b1d7b723:410ebca6b26338a8:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:410ebca6b26338a8:0bda2fe35b6dc33a:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:0bda2fe35b6dc33a:4e588ce1447f50aa:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:4e588ce1447f50aa:2175669d81223f25:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:2175669d81223f25:184d1182dd6d5f40:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:184d1182dd6d5f40:19b650bab326c3a1:1 2021/06/21 14:35:10 Reporting span 19b650bab326c3a1:19b650bab326c3a1:0000000000000000:1 Jun 21 14:35:10.689 [INFO] [cmd:[/bin/app]] successfully connected to my-postgres-service. Response: id:"postgres-cl-0" network_service:"my-postgres-service" mechanism:{cls:"LOCAL" type:"KERNEL" parameters:{key:"inodeURL" value:"file:///proc/thread-self/ns/net"} parameters:{key:"name" value:"nsm-1"}} context:{ip_context:{src_ip_addrs:"172.16.1.101/32" dst_ip_addrs:"172.16.1.100/32" src_routes:{prefix:"172.16.1.100/32"} dst_routes:{prefix:"172.16.1.101/32"}} dns_context:{} MTU:1450} labels:{key:"nodeName" value:"kind-worker"} path:{path_segments:{name:"postgres-cl" id:"postgres-cl-0" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk3MDgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLTY3am5rL3NhL2RlZmF1bHQifQ.yNI4wOI2nrVF6wa5HqtDvJQEmfv-wnbkbbCjbnCjsaSnpXDGU1R7WK43905VXWnzMIvVqIFJNmlLQXmzrlvL2A" expires:{seconds:1624289698}} path_segments:{name:"nsmgr-7rgcn" id:"79e88dc0-3013-49c1-a54f-ce88d7e1db19" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.kRwuk4l06Rpu5U2rusNVtvaJBBJo44ODvp5SQcWpGegTNF6UwC9fLIu-FCRsQQix4StGefZpEiujfhtthUbkzg" expires:{seconds:1624289698}} path_segments:{name:"forwarder-vpp-k5b6p" id:"7345ade6-4971-4411-a687-95761f926cbe" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.uXTseP5uCdcOUy2O7h4y2QI84FaozJQhDbstxkHHHqvU9WFV2TR0e6H2zf2TzzOw-C3mZ3P95xxtqo2Zns5u7w" expires:{seconds:1624289698} metrics:{key:"client_drops" value:"0"} metrics:{key:"client_rx_bytes" value:"0"} metrics:{key:"client_rx_packets" value:"0"} metrics:{key:"client_tx_bytes" value:"0"} metrics:{key:"client_tx_packets" value:"0"} metrics:{key:"server_drops" value:"0"} metrics:{key:"server_rx_bytes" value:"0"} metrics:{key:"server_rx_packets" value:"0"} metrics:{key:"server_tx_bytes" value:"0"} metrics:{key:"server_tx_packets" value:"0"}} path_segments:{name:"nsmgr-7rgcn" id:"7d5e6ad2-d593-43e9-a7a8-45de74234062" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.AHdyj46fh0Lqm1yprqLWSp-VJ8qCTfhc9bDHY2rwg6PVDJKK5B2r_Fgv_UCcBw4EA35HxSsbtc9hJzo3sdnC3Q" expires:{seconds:1624289698}} path_segments:{name:"nsmgr-r5hqj" id:"fdd55302-839b-49b3-bc74-4f54fec62067" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.CukjPxjIGCAoEd0lJ0u5nuKZ2z3E3trSPHLvUvC34H4dRnXezGflYlKI1IzsCgh8Karb9CLyAChOKEqV3zGq-Q" expires:{seconds:1624289698}} path_segments:{name:"forwarder-vpp-d9w2q" id:"c60edf36-efb4-4f4c-8ac6-c97c61d6f710" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk2OTgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vc2EvZGVmYXVsdCJ9.7Wbac_bUXAUg9XuwMEYahft7aPTKguYRELF9O8llC3czP47Cax2XkQlxKqA2lXOMVoAZQwSlZq3F_hpz4lXAbQ" expires:{seconds:1624289698} metrics:{key:"client_drops" value:"0"} metrics:{key:"client_rx_bytes" value:"0"} metrics:{key:"client_rx_packets" value:"0"} metrics:{key:"client_tx_bytes" value:"0"} metrics:{key:"client_tx_packets" value:"0"} metrics:{key:"server_drops" value:"0"} metrics:{key:"server_rx_bytes" value:"0"} metrics:{key:"server_rx_packets" value:"0"} metrics:{key:"server_tx_bytes" value:"0"} metrics:{key:"server_tx_packets" value:"0"}} path_segments:{name:"nsmgr-r5hqj" id:"58222ddd-98e8-42ae-8c58-c9d6b09e5340" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy02N2puay9zYS9kZWZhdWx0IiwiZXhwIjoxNjI0Mjg5Njk4LCJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQifQ.NCtvSMRzgF7z92xDKdl6iRMk1aVUMzt_Vq3YWMCNysT3YcUI1lVWfxkGap6Lmu5t45uwjFG0PecPzMZSEMmsJQ" expires:{seconds:1624289698}} path_segments:{name:"nse-kernel-6df9cf6549-l5xvg" id:"c9db4ecd-0a5e-4bfb-92d9-b0d94837575f" token:"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3NhL2RlZmF1bHQiLCJleHAiOjE2MjQyODk3MDgsInN1YiI6InNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLTY3am5rL3NhL2RlZmF1bHQifQ.fIq0VMkp30jfvaUB8G9VA-QRQ2CIRAYu0bc3Cxq2j7OwCFI-rdhsjs1CvL9qQ7A_FzUZHlWnWywiQeXfK1d6oA" expires:{seconds:1624289698}}} network_service_endpoint_name:"a71cba59-1cc5-4da3-aa67-fad564c2129a-nse-kernel-6df9cf6549-l5xvg" payload:"ETHERNET"