Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Trace output after refresh was changed compared to v1.5.0 #1364

Open
glazychev-art opened this issue Oct 7, 2022 · 0 comments
Open

Trace output after refresh was changed compared to v1.5.0 #1364

glazychev-art opened this issue Oct 7, 2022 · 0 comments
Milestone
v1.7.0

Comments

@glazychev-art
Copy link
Contributor

Expected Behavior

On version 1.5.0 and earlier, the logs from the first and last chain element were always full, which was convenient for tracking the current state.
For example, v1.5.0:

...
Oct  7 06:49:55.982 [TRAC] [id:alpine-0] [type:networkService] (1) ⎆ sdk/pkg/networkservice/utils/metadata/metaDataClient.Request()
Oct  7 06:49:55.982 [TRAC] [id:alpine-0] [type:networkService] (1.1)   request={"connection":{"id":"alpine-0","network_service":"icmp-responder","mechanism":{"cls":"LOCAL","type":"KERNEL","parameters":{"inodeURL":"file:///proc/thread-self/ns/net","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"}],"excluded_prefixes":["10.96.0.0/16","10.244.0.0/16"]},"dns_context":{"configs":[{"dns_server_ips":["10.96.0.10"],"search_domains":["ns-gggmz.svc.cluster.local","svc.cluster.local","cluster.local"]}]},"MTU":9000},"labels":{"nodeName":"kind-worker","podName":"alpine"},"path":{"path_segments":[{"name":"alpine","id":"alpine-0","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1nZ2dtei9wb2QvYWxwaW5lIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL25zbWdyLW1xdHJmIl0sImV4cCI6MTY2NTEyNTg3NX0.vfiakRdeOlmm4HrK8imxR-OpM49IsflgkWErNslBCuViOriSRaHE1PrPVrqiq6dQ_4eV_spOxWNPhxiTwYwT9Q","expires":{"seconds":1665125875,"nanos":793513821}},{"name":"nsmgr-mqtrf","id":"2e90ae67-5b5a-42a4-acc9-54d4302751ed","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9uc21nci1tcXRyZiIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWx2Y2Y2Il0sImV4cCI6MTY2NTEyNTg3NX0.lWdeLc_4DoOzOCkQ1fyeVUmihfuerpKSLP4tKUjOS_m_DYOXPlHj2G0apyfZkdPCWkmJ6CsfqLkWYMipMrrxEA","expires":{"seconds":1665125875,"nanos":849302343}},{"name":"forwarder-vpp-lvcf6","id":"aa4e557a-156f-40f1-b24f-89068bb6e8ad","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWx2Y2Y2IiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLWdnZ216L3BvZC9uc2Uta2VybmVsLTc0OTk2ZmRiNGItazZta2QiXSwiZXhwIjoxNjY1MTI1ODc1fQ.CVQJN-rKTvWTd2DM8iDCPb4zkcEtZJvE7-ysdsOoKOiuZTgpydtkdR1KQD6bla_13C6aOBDK7Sh3_Sp_vAHIWA","expires":{"seconds":1665125875,"nanos":945061964},"metrics":{"client_drops":"2","client_rx_bytes":"66862","client_rx_packets":"1153","client_tx_bytes":"66932","client_tx_packets":"1154","server_drops":"0","server_rx_bytes":"66932","server_rx_packets":"1154","server_tx_bytes":"66686","server_tx_packets":"1151"}},{"name":"nse-kernel-74996fdb4b-k6mkd","id":"878003f9-7995-4277-866e-ea2a4a3aad6a","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1nZ2dtei9wb2QvbnNlLWtlcm5lbC03NDk5NmZkYjRiLWs2bWtkIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL2ZvcndhcmRlci12cHAtbHZjZjYiXSwiZXhwIjoxNjY1MTI1ODc2fQ.bvYj_9jALsY5xxypQRIQUEw6VvALNcfEBjjZ_4OFilpqP9-vRnoXrPFiDiMWGKJ-ReEkfn-AOGhaDUZWCAzthA","expires":{"seconds":1665125876,"nanos":2295554}}]},"network_service_endpoint_name":"nse-kernel-74996fdb4b-k6mkd","payload":"ETHERNET"},"mechanism_preferences":[{"cls":"LOCAL","type":"KERNEL","parameters":{"inodeURL":"inode://4/4026534287","name":"nsm-1"}}]}
Oct  7 06:49:55.983 [TRAC] [id:alpine-0] [type:networkService] (2)  ⎆ sdk/pkg/networkservice/common/refresh/refreshClient.Request()
Oct  7 06:49:55.983 [TRAC] [id:alpine-0] [type:networkService] (3)   ⎆ sdk/pkg/networkservice/common/clienturl/clientURLClient.Request()
Oct  7 06:49:55.983 [DEBU] [id:alpine-0] [type:networkService] (3.1)     passed clientURL: unix:///var/lib/networkservicemesh/nsm.io.sock
Oct  7 06:49:55.984 [TRAC] [id:alpine-0] [type:networkService] (4)    ⎆ sdk/pkg/networkservice/common/clientconn/clientConnClient.Request()
Oct  7 06:49:55.984 [TRAC] [id:alpine-0] [type:networkService] (5)     ⎆ sdk/pkg/networkservice/common/heal/healClient.Request()
Oct  7 06:49:55.984 [DEBU] [id:alpine-0] [heal:eventLoop] [type:networkService] (5.1)       received eventIn: <nil>, err: rpc error: code = Canceled desc = context canceled
Oct  7 06:49:55.984 [TRAC] [id:alpine-0] [type:networkService] (6)      ⎆ sdk/pkg/networkservice/common/dial/dialClient.Request()
Oct  7 06:49:55.985 [TRAC] [id:alpine-0] [type:networkService] (7)       ⎆ sdk/pkg/networkservice/common/clientinfo/clientInfo.Request()
Oct  7 06:49:55.985 [WARN] [id:alpine-0] [type:networkService] (7.1)         Environment variable NODE_NAME is not set. Skipping.
Oct  7 06:49:55.985 [WARN] [id:alpine-0] [type:networkService] (7.2)         The label podName was already assigned to alpine. Skipping.
Oct  7 06:49:55.985 [WARN] [id:alpine-0] [type:networkService] (7.3)         Environment variable CLUSTER_NAME is not set. Skipping.
Oct  7 06:49:55.986 [TRAC] [id:alpine-0] [type:networkService] (8)        ⎆ sdk/pkg/networkservice/common/upstreamrefresh/upstreamRefreshClient.Request()
Oct  7 06:49:55.987 [TRAC] [id:alpine-0] [type:networkService] (9)         ⎆ sdk-sriov/pkg/networkservice/common/token/multitoken/tokenClient.Request()
Oct  7 06:49:55.988 [TRAC] [id:alpine-0] [type:networkService] (10)          ⎆ sdk/pkg/networkservice/common/mechanisms/mechanismsClient.Request()
Oct  7 06:49:55.988 [TRAC] [id:alpine-0] [type:networkService] (11)           ⎆ sdk/pkg/networkservice/core/next/nextClient.Request()
Oct  7 06:49:55.989 [TRAC] [id:alpine-0] [type:networkService] (12)            ⎆ sdk/pkg/networkservice/common/mechanisms/kernel/kernelMechanismClient.Request()
Oct  7 06:49:55.990 [TRAC] [id:alpine-0] [type:networkService] (12.1)              request-diff={"mechanism_preferences":{"0":{"parameters":{"inodeURL":"file:///proc/thread-self/ns/net"}}}}
Oct  7 06:49:55.991 [TRAC] [id:alpine-0] [type:networkService] (13)             ⎆ sdk/pkg/networkservice/common/mechanisms/sendfd/sendFDClient.Request()
Oct  7 06:49:55.992 [TRAC] [id:alpine-0] [type:networkService] (13.1)               request-diff={"connection":{"mechanism":{"parameters":{"inodeURL":"inode://4/4026534287"}}},"mechanism_preferences":{"0":{"parameters":{"inodeURL":"inode://4/4026534287"}}}}
Oct  7 06:49:55.992 [TRAC] [id:alpine-0] [type:networkService] (14)              ⎆ sdk/pkg/networkservice/connectioncontext/dnscontext/dnsContextClient.Request()
Oct  7 06:49:55.992 [TRAC] [id:alpine-0] [type:networkService] (15)               ⎆ sdk/pkg/networkservice/common/excludedprefixes/excludedPrefixesClient.Request()
Oct  7 06:49:55.993 [DEBU] [id:alpine-0] [ExcludedPrefixesClient:Request] [type:networkService] (15.1)                 Adding new excluded IPs to the request: [172.16.1.101/32 172.16.1.100/32 10.96.0.0/16 10.244.0.0/16]
Oct  7 06:49:55.993 [DEBU] [id:alpine-0] [ExcludedPrefixesClient:Request] [type:networkService] (15.2)                 Excluded prefixes from request - [10.96.0.0/16 10.244.0.0/16]
Oct  7 06:49:55.993 [TRAC] [id:alpine-0] [type:networkService] (16)                ⎆ sdk/pkg/networkservice/common/authorize/authorizeClient.Request()
Oct  7 06:49:55.993 [TRAC] [id:alpine-0] [type:networkService] (17)                 ⎆ sdk/pkg/networkservice/common/trimpath/trimpathClient.Request()
Oct  7 06:49:55.994 [TRAC] [id:alpine-0] [type:networkService] (18)                  ⎆ sdk/pkg/networkservice/common/connect/connectClient.Request()
Oct  7 06:49:56.073 [TRAC] [id:alpine-0] [type:networkService] (18.1)                    request-response={"id":"alpine-0","network_service":"icmp-responder","mechanism":{"cls":"LOCAL","type":"KERNEL","parameters":{"inodeURL":"inode://4/4026534287","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"}],"excluded_prefixes":["10.96.0.0/16","10.244.0.0/16"]},"dns_context":{"configs":[{"dns_server_ips":["10.96.0.10"],"search_domains":["ns-gggmz.svc.cluster.local","svc.cluster.local","cluster.local"]}]},"MTU":9000},"labels":{"nodeName":"kind-worker","podName":"alpine"},"path":{"path_segments":[{"name":"alpine","id":"alpine-0","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1nZ2dtei9wb2QvYWxwaW5lIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL25zbWdyLW1xdHJmIl0sImV4cCI6MTY2NTEyNTk5NX0.tqvRtkGpOmRulTW-7PcyMVZsKYQB1pBCkzkmStizoieH3CcFtPhvgR91TGdkTIBCLkPYXQBYFKnng6-8WlQZbw","expires":{"seconds":1665125995,"nanos":994504279}},{"name":"nsmgr-mqtrf","id":"2e90ae67-5b5a-42a4-acc9-54d4302751ed","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9uc21nci1tcXRyZiIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWx2Y2Y2Il0sImV4cCI6MTY2NTEyNTk5Nn0.eRVDAPVP7lnonNZc7Yzt00RJN4uM-wdzmZpyLNQFoYTzP7qsxx3BWWu_kCYDi6FJ8hTfut7wi5qTz4CyedGFEQ","expires":{"seconds":1665125996,"nanos":34554731}},{"name":"forwarder-vpp-lvcf6","id":"aa4e557a-156f-40f1-b24f-89068bb6e8ad","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWx2Y2Y2IiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLWdnZ216L3BvZC9uc2Uta2VybmVsLTc0OTk2ZmRiNGItazZta2QiXSwiZXhwIjoxNjY1MTI1OTk2fQ.-A_f9Yl2idtj4tLFqyeGUy8PMmf8h6e5ct9cn0fne5ydn3iCU6Z3XFY7xs-YbU6pIuQIQ_3bqgs0cXbCkB7LlQ","expires":{"seconds":1665125996,"nanos":58960436},"metrics":{"client_drops":"2","client_rx_bytes":"101014","client_rx_packets":"1743","client_tx_bytes":"101014","client_tx_packets":"1743","server_drops":"0","server_rx_bytes":"101014","server_rx_packets":"1743","server_tx_bytes":"100838","server_tx_packets":"1741"}},{"name":"nse-kernel-74996fdb4b-k6mkd","id":"878003f9-7995-4277-866e-ea2a4a3aad6a","token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1nZ2dtei9wb2QvbnNlLWtlcm5lbC03NDk5NmZkYjRiLWs2bWtkIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL2ZvcndhcmRlci12cHAtbHZjZjYiXSwiZXhwIjoxNjY1MTI1OTk2fQ.V76Ni5ivYaysTR7DqqBo3TEY_uv3WySDQq3tUvpeFgGJhUOplZUBs8slZfyfMOorMgCuLaKewfGiHrqEHPLlgQ","expires":{"seconds":1665125996,"nanos":59648666}}]},"network_service_endpoint_name":"nse-kernel-74996fdb4b-k6mkd","payload":"ETHERNET"}
Oct  7 06:49:56.074 [DEBU] [id:alpine-0] [ExcludedPrefixesClient:Request] [type:networkService] (15.3)                 Request excluded IPs - srcIPs: [172.16.1.101/32], dstIPs: [172.16.1.100/32], excluded prefixes: [10.96.0.0/16 10.244.0.0/16]
Oct  7 06:49:56.074 [DEBU] [id:alpine-0] [ExcludedPrefixesClient:Request] [type:networkService] (15.4)                 Added excluded prefixes: [172.16.1.101/32 172.16.1.100/32 10.96.0.0/16 10.244.0.0/16]
Oct  7 06:49:56.074 [TRAC] [id:alpine-0] [type:networkService] (13.2)               request-response-diff={"mechanism":{"parameters":{"inodeURL":"file:///proc/thread-self/ns/net"}}}
Oct  7 06:49:56.075 [INFO] [id:alpine-0] [type:networkService] (2.1)    expiration after 9m59.918734759s at 2022-10-07 06:59:55.994504279 +0000 UTC
...

Current Behavior

But on version 1.6.0, the log format has changed and there is only diff after refresh:

...
Oct  7 06:39:55.383 [TRAC] [id:alpine-0] [type:networkService] (2.1)    request-diff={"connection":{"mechanism":{"parameters":{"inodeURL":"file:///proc/thread-self/ns/net"}},"path":{"path_segments":{"0":{"expires":{"nanos":343385050,"seconds":1665125275},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1xNWJmcC9wb2QvYWxwaW5lIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL25zbWdyLTl4cnZ2Il0sImV4cCI6MTY2NTEyNTI3NX0.jy_7NnYxkPmHh9l5kGG6k669L_Y9s7wRk-dQqYRfTmFVTo_380J5rnXccAEgj0XS4C2TOi-aT4sLtEF1qUjECA"},"1":{"expires":{"nanos":355040395,"seconds":1665125275},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9uc21nci05eHJ2diIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWdrZ2RsIl0sImV4cCI6MTY2NTEyNTI3NX0.9yIW-XyIR60WjBfALG0Y1C75oyzR-En50-f7g8Wh65sHMGiahvFx2HhMdZr9vO0lgbNnzHGcViDftBbDk0hOYg"},"2":{"expires":{"nanos":371602774,"seconds":1665125275},"metrics":{"client_rx_bytes":"516","client_rx_packets":"6","client_tx_bytes":"516","client_tx_packets":"6","server_rx_bytes":"516","server_rx_packets":"6","server_tx_bytes":"516","server_tx_packets":"6"},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWdrZ2RsIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLXE1YmZwL3BvZC9uc2Uta2VybmVsLTc0OTk2ZmRiNGItcThoNGgiXSwiZXhwIjoxNjY1MTI1Mjc1fQ.d-e44nyZGgDKuEnZ2HglbUXkGJkztuv33fh5p4zv6VpR_DzqbAbuw4plgQPr5XfpQrlGQ6ZdkRuLUL5OWBfYaQ"},"3":{"expires":{"nanos":372272273,"seconds":1665125275},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1xNWJmcC9wb2QvbnNlLWtlcm5lbC03NDk5NmZkYjRiLXE4aDRoIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL2ZvcndhcmRlci12cHAtZ2tnZGwiXSwiZXhwIjoxNjY1MTI1Mjc1fQ.ZTI7fpBY4Nu9QiA0DmDz1m-TrSCW2SAcus2YFtIc-WZy89z3uOOzJVMgUdr3W5wARcua81SipxKmXAwHZauQbQ"}}}}}
Oct  7 06:39:55.383 [TRAC] [id:alpine-0] [type:networkService] (3)   ⎆ sdk/pkg/networkservice/utils/metadata/metaDataClient.Request()
Oct  7 06:39:55.383 [TRAC] [id:alpine-0] [type:networkService] (4)    ⎆ sdk/pkg/networkservice/common/refresh/refreshClient.Request()
Oct  7 06:39:55.384 [TRAC] [id:alpine-0] [type:networkService] (5)     ⎆ sdk/pkg/networkservice/common/clienturl/clientURLClient.Request()
Oct  7 06:39:55.384 [DEBU] [id:alpine-0] [type:networkService] (5.1)       passed clientURL: unix:///var/lib/networkservicemesh/nsm.io.sock
Oct  7 06:39:55.384 [TRAC] [id:alpine-0] [type:networkService] (6)      ⎆ sdk/pkg/networkservice/common/clientconn/clientConnClient.Request()
Oct  7 06:39:55.385 [TRAC] [id:alpine-0] [type:networkService] (7)       ⎆ sdk/pkg/networkservice/common/heal/healClient.Request()
Oct  7 06:39:55.385 [TRAC] [id:alpine-0] [type:networkService] (8)        ⎆ sdk/pkg/networkservice/common/dial/dialClient.Request()
Oct  7 06:39:55.385 [DEBU] [id:alpine-0] [heal:eventLoop] [type:networkService] (7.1)         received eventIn: <nil>, err: rpc error: code = Canceled desc = context canceled
Oct  7 06:39:55.386 [TRAC] [id:alpine-0] [type:networkService] (9)         ⎆ sdk/pkg/networkservice/common/clientinfo/clientInfo.Request()
Oct  7 06:39:55.386 [WARN] [id:alpine-0] [type:networkService] (9.1)           The label podName was already assigned to alpine. Skipping.
Oct  7 06:39:55.386 [WARN] [id:alpine-0] [type:networkService] (9.2)           Environment variable CLUSTER_NAME is not set. Skipping.
Oct  7 06:39:55.386 [WARN] [id:alpine-0] [type:networkService] (9.3)           Environment variable NODE_NAME is not set. Skipping.
Oct  7 06:39:55.386 [TRAC] [id:alpine-0] [type:networkService] (10)          ⎆ sdk/pkg/networkservice/common/upstreamrefresh/upstreamRefreshClient.Request()
Oct  7 06:39:55.387 [TRAC] [id:alpine-0] [type:networkService] (11)           ⎆ sdk-sriov/pkg/networkservice/common/token/multitoken/tokenClient.Request()
Oct  7 06:39:55.387 [TRAC] [id:alpine-0] [type:networkService] (12)            ⎆ sdk/pkg/networkservice/common/mechanisms/mechanismsClient.Request()
Oct  7 06:39:55.387 [TRAC] [id:alpine-0] [type:networkService] (13)             ⎆ sdk/pkg/networkservice/core/next/nextClient.Request()
Oct  7 06:39:55.388 [TRAC] [id:alpine-0] [type:networkService] (14)              ⎆ sdk/pkg/networkservice/common/mechanisms/kernel/kernelMechanismClient.Request()
Oct  7 06:39:55.388 [TRAC] [id:alpine-0] [type:networkService] (14.1)                request-diff={"mechanism_preferences":{"0":{"parameters":{"inodeURL":"file:///proc/thread-self/ns/net"}}}}
Oct  7 06:39:55.389 [TRAC] [id:alpine-0] [type:networkService] (15)               ⎆ sdk/pkg/networkservice/common/mechanisms/sendfd/sendFDClient.Request()
Oct  7 06:39:55.389 [TRAC] [id:alpine-0] [type:networkService] (15.1)                 request-diff={"connection":{"mechanism":{"parameters":{"inodeURL":"inode://4/4026534187"}}},"mechanism_preferences":{"0":{"parameters":{"inodeURL":"inode://4/4026534187"}}}}
Oct  7 06:39:55.389 [TRAC] [id:alpine-0] [type:networkService] (16)                ⎆ sdk/pkg/networkservice/connectioncontext/dnscontext/dnsContextClient.Request()
Oct  7 06:39:55.390 [TRAC] [id:alpine-0] [type:networkService] (17)                 ⎆ sdk/pkg/networkservice/common/excludedprefixes/excludedPrefixesClient.Request()
Oct  7 06:39:55.390 [DEBU] [id:alpine-0] [ExcludedPrefixesClient:Request] [type:networkService] (17.1)                   Adding new excluded IPs to the request: [172.16.1.101/32 172.16.1.100/32 10.96.0.0/16 10.244.0.0/16]
Oct  7 06:39:55.390 [DEBU] [id:alpine-0] [ExcludedPrefixesClient:Request] [type:networkService] (17.2)                   Excluded prefixes from request - [10.96.0.0/16 10.244.0.0/16]
Oct  7 06:39:55.391 [TRAC] [id:alpine-0] [type:networkService] (18)                  ⎆ sdk/pkg/networkservice/common/authorize/authorizeClient.Request()
Oct  7 06:39:55.391 [TRAC] [id:alpine-0] [type:networkService] (19)                   ⎆ sdk/pkg/networkservice/common/trimpath/trimpathClient.Request()
Oct  7 06:39:55.391 [TRAC] [id:alpine-0] [type:networkService] (20)                    ⎆ sdk/pkg/networkservice/common/connect/connectClient.Request()
Oct  7 06:39:55.544 [TRAC] [id:alpine-0] [type:networkService] (20.1)                      request-response-diff={"mechanism":{"parameters":{"inodeURL":"inode://4/4026534187"}},"path":{"path_segments":{"0":{"expires":{"nanos":392119143,"seconds":1665125395},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1xNWJmcC9wb2QvYWxwaW5lIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL25zbWdyLTl4cnZ2Il0sImV4cCI6MTY2NTEyNTM5NX0.d7QlobEcQqj32izW3oVynZWEKR_rAIrxucb6Kk5LpGfRO-0M5D-3w7p5mMRKbG0LqEl-4pVEhEjF3tSJUIHM6g"},"1":{"expires":{"nanos":452122714,"seconds":1665125395},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9uc21nci05eHJ2diIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWdrZ2RsIl0sImV4cCI6MTY2NTEyNTM5NX0.X6GhtO3a2fGc_E7hxo2fEXi2FVKDD1_378OChC5ZawuuoGeISdxGrsCTBoiRwt0_SqT9qOtf38Xuot-qhzVxRQ"},"2":{"expires":{"nanos":503701882,"seconds":1665125395},"metrics":{"client_rx_bytes":"33744","client_rx_packets":"580","client_tx_bytes":"33814","client_tx_packets":"581","server_rx_bytes":"33814","server_rx_packets":"581","server_tx_bytes":"33744","server_tx_packets":"580"},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20tc3lzdGVtL3BvZC9mb3J3YXJkZXItdnBwLWdrZ2RsIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zLXE1YmZwL3BvZC9uc2Uta2VybmVsLTc0OTk2ZmRiNGItcThoNGgiXSwiZXhwIjoxNjY1MTI1Mzk1fQ.NGLIMnWZ7ucOyt6YuO48B8eXSExvg770gflUJ_en-Z6Ld-y3s_3DLbNcCyp1aRWgAjhIysEygvFaPH-HviLMTA"},"3":{"expires":{"nanos":505467182,"seconds":1665125395},"token":"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9ucy1xNWJmcC9wb2QvbnNlLWtlcm5lbC03NDk5NmZkYjRiLXE4aDRoIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS1zeXN0ZW0vcG9kL2ZvcndhcmRlci12cHAtZ2tnZGwiXSwiZXhwIjoxNjY1MTI1Mzk1fQ.T2DHT-08DpzkJFnVt9KoPVll9VgxuYeiEWwB_drHyF0bw2egr61YtTKQSasXK9rRgjELpOn45qhFpS4TAvYaLg"}}}}
...

Failure Information (for bugs)

Probably it is related to google.golang.org/protobuf update
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
v1.7.0
Development

No branches or pull requests
2 participants
@glazychev-art @denis-tingaikin