Excessive logging in clients/servers with v1.14.2-rc.1 sdk

[zolug]

We have some custom network service clients and servers, some of which are handling numerous NSM connections.
With v1.14.2-rc.1 sdk, new server-request, server-request-response and similar client related INFO level printouts have appeared.
These info printouts unnecessarily spam our logs (for each connection they appear every 2 minutes).
As SDK users, on INFO log level we would not expect such extensive, recurring logging basically tracing connections.

{"severity":"info","timestamp":"2024-12-11T13:44:58.261+00:00","service_id":"Meridio-LB","message":"server-request={\"connection\":{\"id\":\"fcc409af-6493-4538-9fc0-8601a6279b51\",\"network_service\":\"load-balancer-a1.trench-a.default\",\"mechanism\":{\"cls\":\"LOCAL\",\"type\":\"KERNEL\",\"parameters\":{\"inodeURL\":\"inode://4/4026537767\",\"name\":\"load-balan-YvpQ\"}},\"context\":{\"ip_context\":{\"src_ip_addrs\":[\"172.16.3.11/24\",\"fd00:0:0:3::b/64\"],\"dst_ip_addrs\":[\"172.16.3.10/24\",\"fd00:0:0:3::a/64\"],\"excluded_prefixes\":[\"10.96.0.0/16\",\"10.244.0.0/16\"]},\"MTU\":1446},\"labels\":{\"nodeName\":\"kind-worker\"},\"path\":{\"index\":4,\"path_segments\":[{\"name\":\"proxy-load-balancer-a1-4k9cv\",\"id\":\"proxy-load-balancer-a1-4k9cv-load-balancer-a1.trench-a.default-stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9kZWZhdWx0L3NhL2RlZmF1bHQiLCJhdWQiOlsic3BpZmZlOi8vZXhhbXBsZS5vcmcvbnMvbnNtL3NhL25zbWdyLXNhIl0sImV4cCI6MTczMzkyNTI5OH0.tab53g-WP6kcpWjFsEgHhhlRpU5U0MoM41kfCM7rd7QEKCORHsavjTLHz6NJHYj2S7KFpciry4r11dMYQVyQiw\",\"expires\":{\"seconds\":1733925298,\"nanos\":145603346},\"metrics\":{\"client_interface\":\"KERNEL/unknown\"}},{\"name\":\"nsmgr-bz69x\",\"id\":\"eaab4ec7-e7b6-4e23-999e-927cb1db3b8f\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvbnNtZ3Itc2EiLCJhdWQiOlsic3BpZmZlOi8vZXhhbXBsZS5vcmcvbnMvbnNtL3NhL2RlZmF1bHQiXSwiZXhwIjoxNzMzOTI1Mjk4fQ.9Zj4vCJ46CEFnGqT8jdUzmAC_6vj8-6uSi2MoTHRJEvujEe33pxTglUx0jV9K4O7I1d3PWTLrA5tLcckbXNYKA\",\"expires\":{\"seconds\":1733925298,\"nanos\":166444238}},{\"name\":\"forwarder-vpp-dmgsg\",\"id\":\"888c90a0-491d-4e62-825b-0313cde7333f\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvZGVmYXVsdCIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvbnNtZ3Itc2EiXSwiZXhwIjoxNzMzOTI1Mjk4fQ.xux7fOCRyQSvslWEgEnyBJtpyYPsNqp-AiSW-V6mg9cuLjhQHC9FfUMVQNx8NolCSLM7qOqKM2KBgmMUBQVoRQ\",\"expires\":{\"seconds\":1733925298,\"nanos\":197477981},\"metrics\":{\"client_drops\":\"0\",\"client_interface\":\"VXLAN/vxlan_tunnel3\",\"client_rx_bytes\":\"536\",\"client_rx_packets\":\"4\",\"client_tx_bytes\":\"2024\",\"client_tx_packets\":\"12\",\"server_drops\":\"0\",\"server_interface\":\"VIRTIO/tap5\",\"server_rx_bytes\":\"1592\",\"server_rx_packets\":\"12\",\"server_tx_bytes\":\"536\",\"server_tx_packets\":\"4\"}},{\"name\":\"nsmgr-knw5h\",\"id\":\"15da2be1-142d-402c-94ed-c323555b58c7\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvbnNtZ3Itc2EiLCJhdWQiOlsic3BpZmZlOi8vZXhhbXBsZS5vcmcvbnMvbnNtL3NhL2RlZmF1bHQiXSwiZXhwIjoxNzMzOTI1Mjk4fQ.36txbhp6tsdaWDiPb7SGF4ZmUN8nrPhI73epKeIEuEzcalu6rtD_a4YABF1W2DNK1qHb76TiPe5zpZ8ErOddPw\",\"expires\":{\"seconds\":1733925298,\"nanos\":217239030}},{\"name\":\"forwarder-vpp-wgf9s\",\"id\":\"fcc409af-6493-4538-9fc0-8601a6279b51\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvZGVmYXVsdCIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvbnNtZ3Itc2EiXSwiZXhwIjoxNzMzOTI1Mjk4fQ.p5hjbCb6LMA5_84GLkMH_ruOFyou88wS7zCdAVPKnR3kvRO9a1gLTRW2rdNDi_8lKhzIl0pIDmcjH0KfXdLydg\",\"expires\":{\"seconds\":1733925298,\"nanos\":217905698},\"metrics\":{\"client_drops\":\"2\",\"client_interface\":\"VIRTIO/tap5\",\"client_rx_bytes\":\"796\",\"client_rx_packets\":\"6\",\"client_tx_bytes\":\"1592\",\"client_tx_packets\":\"12\",\"server_drops\":\"0\",\"server_interface\":\"VXLAN/vxlan_tunnel0\",\"server_rx_bytes\":\"1592\",\"server_rx_packets\":\"12\",\"server_tx_bytes\":\"680\",\"server_tx_packets\":\"4\"}},{\"name\":\"stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj\",\"id\":\"5b366298-52bd-4279-b86f-9ef127bb42d8\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9kZWZhdWx0L3NhL21lcmlkaW8tZmVzIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS9zYS9kZWZhdWx0Il0sImV4cCI6MTczMzkyNTE3OH0.pvLXzyEVpjvH6Dsfjzw_6TX0vuCmn10eBzFneNh26eLPw_CA5WW_Vnvw1w0IQ1LCZoD_CUKMu0Qjh8-LuLfpqg\",\"expires\":{\"seconds\":1733925178,\"nanos\":94371081},\"metrics\":{\"server_interface\":\"KERNEL/load-balan-YvpQ\"}}]},\"network_service_endpoint_name\":\"stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj\",\"payload\":\"ETHERNET\"},\"mechanism_preferences\":[{\"cls\":\"LOCAL\",\"type\":\"MEMIF\",\"parameters\":{\"inodeURL\":\"\"}},{\"cls\":\"LOCAL\",\"type\":\"KERNEL\"}]}","version":"1.0.0","extra_data":{"subsystem":"NSM","id":"proxy-load-balancer-a1-4k9cv-load-balancer-a1.trench-a.default-stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj","type":"networkService"}}
{"severity":"info","timestamp":"2024-12-11T13:44:58.263+00:00","service_id":"Meridio-LB","message":"server-request-response={\"id\":\"5b366298-52bd-4279-b86f-9ef127bb42d8\",\"network_service\":\"load-balancer-a1.trench-a.default\",\"mechanism\":{\"cls\":\"LOCAL\",\"type\":\"KERNEL\",\"parameters\":{\"inodeURL\":\"file:///proc/thread-self/ns/net\",\"name\":\"load-balan-YvpQ\"}},\"context\":{\"ip_context\":{\"src_ip_addrs\":[\"172.16.3.11/24\",\"fd00:0:0:3::b/64\"],\"dst_ip_addrs\":[\"172.16.3.10/24\",\"fd00:0:0:3::a/64\"],\"excluded_prefixes\":[\"10.96.0.0/16\",\"10.244.0.0/16\"]},\"MTU\":1446},\"labels\":{\"nodeName\":\"kind-worker\"},\"path\":{\"index\":5,\"path_segments\":[{\"name\":\"proxy-load-balancer-a1-4k9cv\",\"id\":\"proxy-load-balancer-a1-4k9cv-load-balancer-a1.trench-a.default-stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9kZWZhdWx0L3NhL2RlZmF1bHQiLCJhdWQiOlsic3BpZmZlOi8vZXhhbXBsZS5vcmcvbnMvbnNtL3NhL25zbWdyLXNhIl0sImV4cCI6MTczMzkyNTI5OH0.tab53g-WP6kcpWjFsEgHhhlRpU5U0MoM41kfCM7rd7QEKCORHsavjTLHz6NJHYj2S7KFpciry4r11dMYQVyQiw\",\"expires\":{\"seconds\":1733925298,\"nanos\":145603346},\"metrics\":{\"client_interface\":\"KERNEL/unknown\"}},{\"name\":\"nsmgr-bz69x\",\"id\":\"eaab4ec7-e7b6-4e23-999e-927cb1db3b8f\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvbnNtZ3Itc2EiLCJhdWQiOlsic3BpZmZlOi8vZXhhbXBsZS5vcmcvbnMvbnNtL3NhL2RlZmF1bHQiXSwiZXhwIjoxNzMzOTI1Mjk4fQ.9Zj4vCJ46CEFnGqT8jdUzmAC_6vj8-6uSi2MoTHRJEvujEe33pxTglUx0jV9K4O7I1d3PWTLrA5tLcckbXNYKA\",\"expires\":{\"seconds\":1733925298,\"nanos\":166444238}},{\"name\":\"forwarder-vpp-dmgsg\",\"id\":\"888c90a0-491d-4e62-825b-0313cde7333f\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvZGVmYXVsdCIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvbnNtZ3Itc2EiXSwiZXhwIjoxNzMzOTI1Mjk4fQ.xux7fOCRyQSvslWEgEnyBJtpyYPsNqp-AiSW-V6mg9cuLjhQHC9FfUMVQNx8NolCSLM7qOqKM2KBgmMUBQVoRQ\",\"expires\":{\"seconds\":1733925298,\"nanos\":197477981},\"metrics\":{\"client_drops\":\"0\",\"client_interface\":\"VXLAN/vxlan_tunnel3\",\"client_rx_bytes\":\"536\",\"client_rx_packets\":\"4\",\"client_tx_bytes\":\"2024\",\"client_tx_packets\":\"12\",\"server_drops\":\"0\",\"server_interface\":\"VIRTIO/tap5\",\"server_rx_bytes\":\"1592\",\"server_rx_packets\":\"12\",\"server_tx_bytes\":\"536\",\"server_tx_packets\":\"4\"}},{\"name\":\"nsmgr-knw5h\",\"id\":\"15da2be1-142d-402c-94ed-c323555b58c7\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvbnNtZ3Itc2EiLCJhdWQiOlsic3BpZmZlOi8vZXhhbXBsZS5vcmcvbnMvbnNtL3NhL2RlZmF1bHQiXSwiZXhwIjoxNzMzOTI1Mjk4fQ.36txbhp6tsdaWDiPb7SGF4ZmUN8nrPhI73epKeIEuEzcalu6rtD_a4YABF1W2DNK1qHb76TiPe5zpZ8ErOddPw\",\"expires\":{\"seconds\":1733925298,\"nanos\":217239030}},{\"name\":\"forwarder-vpp-wgf9s\",\"id\":\"fcc409af-6493-4538-9fc0-8601a6279b51\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9uc20vc2EvZGVmYXVsdCIsImF1ZCI6WyJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9kZWZhdWx0L3NhL21lcmlkaW8tZmVzIl0sImV4cCI6MTczMzkyNTI5OH0._MhyPjc4b_eP04b_dGM96PN_12WWJzKgicv3f5oQZc9ykmzbjBk6GuLLlIrAdv3BiLGCVl6gi_vO6RHkpT_8Gw\",\"expires\":{\"seconds\":1733925298,\"nanos\":260743445},\"metrics\":{\"client_drops\":\"2\",\"client_interface\":\"VIRTIO/tap5\",\"client_rx_bytes\":\"796\",\"client_rx_packets\":\"6\",\"client_tx_bytes\":\"1592\",\"client_tx_packets\":\"12\",\"server_drops\":\"0\",\"server_interface\":\"VXLAN/vxlan_tunnel0\",\"server_rx_bytes\":\"1592\",\"server_rx_packets\":\"12\",\"server_tx_bytes\":\"680\",\"server_tx_packets\":\"4\"}},{\"name\":\"stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj\",\"id\":\"5b366298-52bd-4279-b86f-9ef127bb42d8\",\"token\":\"eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJzdWIiOiJzcGlmZmU6Ly9leGFtcGxlLm9yZy9ucy9kZWZhdWx0L3NhL21lcmlkaW8tZmVzIiwiYXVkIjpbInNwaWZmZTovL2V4YW1wbGUub3JnL25zL25zbS9zYS9kZWZhdWx0Il0sImV4cCI6MTczMzkyNTI5OH0._DRQXN0obUkJCDMgadmRgWqjxlh9Dv4xmLVltkTz7WmFGmWxdINCoIonN2YQwWdMrsAB8HeBN9OqocdIHEXJsw\",\"expires\":{\"seconds\":1733925298,\"nanos\":261352151},\"metrics\":{\"server_interface\":\"KERNEL/load-balan-YvpQ\"}}]},\"network_service_endpoint_name\":\"stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj\",\"payload\":\"ETHERNET\"}","version":"1.0.0","extra_data":{"subsystem":"NSM","id":"proxy-load-balancer-a1-4k9cv-load-balancer-a1.trench-a.default-stateless-lb-frontend-attr-a1-6bbbfddfd4-78wqj","type":"networkService"}}

[denis-tingaikin]

Thanks for letting us know.
Fixed by f1bf307

[zolug]

Thanks for letting us know. Fixed by f1bf307

I gave it a quick try, but haven't noticed any change. (Rebuilt my components based on sdk optimize-trace-chain-element branch)