{"level":"warn","ts":"2026-04-16T16:31:00.271729Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:31:00.271835Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.75:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.75:2380","--initial-cluster=instance=https://199.19.213.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.75:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T16:31:00.27193Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:31:00.271945Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-16T16:31:00.271995Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T16:31:00.275704Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"]} {"level":"info","ts":"2026-04-16T16:31:00.276237Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.75:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T16:31:00.279717Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.162197ms"} {"level":"info","ts":"2026-04-16T16:31:00.285555Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"42c325ad8bcf8012","cluster-id":"1e2ab336c559df5e"} {"level":"info","ts":"2026-04-16T16:31:00.285782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:31:00.285817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:31:00.285832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 42c325ad8bcf8012 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:31:00.285841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:31:00.285923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"warn","ts":"2026-04-16T16:31:00.291939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:31:00.293914Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:31:00.297136Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T16:31:00.299024Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"42c325ad8bcf8012","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:31:00.29916Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"42c325ad8bcf8012","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:31:00.299373Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T16:31:00.299424Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T16:31:00.299433Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T16:31:00.300928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"info","ts":"2026-04-16T16:31:00.301337Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","added-peer-id":"42c325ad8bcf8012","added-peer-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-16T16:31:00.303971Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T16:31:00.304182Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-16T16:31:00.304259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-16T16:31:00.304476Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"42c325ad8bcf8012","initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:31:00.30453Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:31:01.087212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:31:01.087288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:31:01.087402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgPreVoteResp from 42c325ad8bcf8012 at term 1"} {"level":"info","ts":"2026-04-16T16:31:01.087435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:31:01.087447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgVoteResp from 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-16T16:31:01.087471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:31:01.087486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 42c325ad8bcf8012 elected leader 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-16T16:31:01.090338Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:31:01.092692Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"42c325ad8bcf8012","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.75:2379]}","request-path":"/0/members/42c325ad8bcf8012/attributes","cluster-id":"1e2ab336c559df5e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:31:01.092741Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:31:01.092815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:31:01.093016Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:31:01.093069Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:31:01.092768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:31:01.094586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.75:2379"} {"level":"info","ts":"2026-04-16T16:31:01.097449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:31:01.097691Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:31:01.104501Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-16T16:31:06.299879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.490412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:06.300012Z","caller":"traceutil/trace.go:171","msg":"trace[43057656] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"185.45717ms","start":"2026-04-16T16:31:06.114539Z","end":"2026-04-16T16:31:06.299996Z","steps":["trace[43057656] 'read index received' (duration: 34.107076ms)","trace[43057656] 'applied index is now lower than readState.Index' (duration: 151.348673ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:06.300095Z","caller":"traceutil/trace.go:171","msg":"trace[1358646326] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"204.060086ms","start":"2026-04-16T16:31:06.095987Z","end":"2026-04-16T16:31:06.300047Z","steps":["trace[1358646326] 'process raft request' (duration: 52.644481ms)","trace[1358646326] 'compare' (duration: 150.37903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:06.300157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.605303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:06.300219Z","caller":"traceutil/trace.go:171","msg":"trace[1938508955] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:286; }","duration":"185.689375ms","start":"2026-04-16T16:31:06.114508Z","end":"2026-04-16T16:31:06.300197Z","steps":["trace[1938508955] 'agreement among raft nodes before linearized reading' (duration: 185.539512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:06.300143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.597363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-16T16:31:06.300275Z","caller":"traceutil/trace.go:171","msg":"trace[621666250] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:286; }","duration":"185.764568ms","start":"2026-04-16T16:31:06.114501Z","end":"2026-04-16T16:31:06.300265Z","steps":["trace[621666250] 'agreement among raft nodes before linearized reading' (duration: 185.572352ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:06.545674Z","caller":"traceutil/trace.go:171","msg":"trace[1235559692] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"430.80816ms","start":"2026-04-16T16:31:06.114846Z","end":"2026-04-16T16:31:06.545655Z","steps":["trace[1235559692] 'process raft request' (duration: 384.401372ms)","trace[1235559692] 'compare' (duration: 46.100161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:06.545818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.114814Z","time spent":"430.922323ms","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":591,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:31:06.545851Z","caller":"traceutil/trace.go:171","msg":"trace[1315379138] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"407.444036ms","start":"2026-04-16T16:31:06.138378Z","end":"2026-04-16T16:31:06.545822Z","steps":["trace[1315379138] 'process raft request' (duration: 407.20628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:06.545969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.138356Z","time spent":"407.562669ms","remote":"127.0.0.1:49810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:31:07.084832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.116084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-16T16:31:07.085398Z","caller":"traceutil/trace.go:171","msg":"trace[1548241960] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"867.445632ms","start":"2026-04-16T16:31:06.217856Z","end":"2026-04-16T16:31:07.085301Z","steps":["trace[1548241960] 'process raft request' (duration: 566.794036ms)","trace[1548241960] 'compare' (duration: 300.049202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:07.086011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.217827Z","time spent":"867.652568ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:31:07.086194Z","caller":"traceutil/trace.go:171","msg":"trace[1535464054] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"780.622392ms","start":"2026-04-16T16:31:06.305546Z","end":"2026-04-16T16:31:07.086168Z","steps":["trace[1535464054] 'process raft request' (duration: 779.462874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.086311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.30552Z","time spent":"780.750946ms","remote":"127.0.0.1:49676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:31:07.086613Z","caller":"traceutil/trace.go:171","msg":"trace[526324869] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"652.09796ms","start":"2026-04-16T16:31:06.434499Z","end":"2026-04-16T16:31:07.086597Z","steps":["trace[526324869] 'process raft request' (duration: 651.904625ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:07.08663Z","caller":"traceutil/trace.go:171","msg":"trace[407520792] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"536.639896ms","start":"2026-04-16T16:31:06.549963Z","end":"2026-04-16T16:31:07.086603Z","steps":["trace[407520792] 'process raft request' (duration: 536.580564ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:07.086687Z","caller":"traceutil/trace.go:171","msg":"trace[310413253] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"652.629162ms","start":"2026-04-16T16:31:06.43401Z","end":"2026-04-16T16:31:07.086639Z","steps":["trace[310413253] 'process raft request' (duration: 651.091655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.086752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.434473Z","time spent":"652.242803ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:31:07.086815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.549938Z","time spent":"536.803239ms","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":624,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:31:07.086894Z","caller":"traceutil/trace.go:171","msg":"trace[1391885308] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"652.282834ms","start":"2026-04-16T16:31:06.434596Z","end":"2026-04-16T16:31:07.086879Z","steps":["trace[1391885308] 'process raft request' (duration: 651.850683ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:07.086903Z","caller":"traceutil/trace.go:171","msg":"trace[2021996973] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"652.280854ms","start":"2026-04-16T16:31:06.434498Z","end":"2026-04-16T16:31:07.086779Z","steps":["trace[2021996973] 'process raft request' (duration: 651.818133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.087073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.434473Z","time spent":"652.54991ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:31:07.087172Z","caller":"traceutil/trace.go:171","msg":"trace[1843721073] transaction","detail":"{read_only:false; number_of_response:0; response_revision:292; }","duration":"652.17211ms","start":"2026-04-16T16:31:06.43499Z","end":"2026-04-16T16:31:07.087162Z","steps":["trace[1843721073] 'process raft request' (duration: 651.522215ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:07.087147Z","caller":"traceutil/trace.go:171","msg":"trace[1736134529] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:292; }","duration":"787.05672ms","start":"2026-04-16T16:31:06.300047Z","end":"2026-04-16T16:31:07.087104Z","steps":["trace[1736134529] 'read index received' (duration: 199.237279ms)","trace[1736134529] 'applied index is now lower than readState.Index' (duration: 587.813921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:07.086974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.434569Z","time spent":"652.374776ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:31:07.087465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.434977Z","time spent":"652.414726ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:31:07.087727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.433975Z","time spent":"653.692589ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:31:07.088045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.227591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-16T16:31:07.08785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.356763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:07.088093Z","caller":"traceutil/trace.go:171","msg":"trace[2103337787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:293; }","duration":"239.62442ms","start":"2026-04-16T16:31:06.848455Z","end":"2026-04-16T16:31:07.08808Z","steps":["trace[2103337787] 'agreement among raft nodes before linearized reading' (duration: 239.350163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.087934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.122978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-16T16:31:07.088157Z","caller":"traceutil/trace.go:171","msg":"trace[105071196] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:293; }","duration":"539.357634ms","start":"2026-04-16T16:31:06.548776Z","end":"2026-04-16T16:31:07.088134Z","steps":["trace[105071196] 'agreement among raft nodes before linearized reading' (duration: 539.107178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.088207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.548758Z","time spent":"539.436975ms","remote":"127.0.0.1:49810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-04-16T16:31:07.087936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.635597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-16T16:31:07.088391Z","caller":"traceutil/trace.go:171","msg":"trace[1991262665] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:293; }","duration":"784.110158ms","start":"2026-04-16T16:31:06.30427Z","end":"2026-04-16T16:31:07.088381Z","steps":["trace[1991262665] 'agreement among raft nodes before linearized reading' (duration: 783.556895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.088424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.304265Z","time spent":"784.149599ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4038,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-16T16:31:07.088232Z","caller":"traceutil/trace.go:171","msg":"trace[1599128530] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:293; }","duration":"784.551759ms","start":"2026-04-16T16:31:06.303664Z","end":"2026-04-16T16:31:07.088216Z","steps":["trace[1599128530] 'agreement among raft nodes before linearized reading' (duration: 784.213941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.088601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.303646Z","time spent":"784.943988ms","remote":"127.0.0.1:50024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-16T16:31:07.090529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.046086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T16:31:07.090582Z","caller":"traceutil/trace.go:171","msg":"trace[1444551842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:293; }","duration":"464.850525ms","start":"2026-04-16T16:31:06.625719Z","end":"2026-04-16T16:31:07.09057Z","steps":["trace[1444551842] 'agreement among raft nodes before linearized reading' (duration: 461.988495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.090617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.625698Z","time spent":"464.907336ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:31:31.357735Z","caller":"traceutil/trace.go:171","msg":"trace[841671297] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"124.441312ms","start":"2026-04-16T16:31:31.233263Z","end":"2026-04-16T16:31:31.357705Z","steps":["trace[841671297] 'read index received' (duration: 124.083124ms)","trace[841671297] 'applied index is now lower than readState.Index' (duration: 357.058µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:31.357831Z","caller":"traceutil/trace.go:171","msg":"trace[1768371190] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"127.694273ms","start":"2026-04-16T16:31:31.230108Z","end":"2026-04-16T16:31:31.357803Z","steps":["trace[1768371190] 'process raft request' (duration: 127.341444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:31.357959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.666058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T16:31:31.358034Z","caller":"traceutil/trace.go:171","msg":"trace[552548693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"124.818682ms","start":"2026-04-16T16:31:31.233204Z","end":"2026-04-16T16:31:31.358023Z","steps":["trace[552548693] 'agreement among raft nodes before linearized reading' (duration: 124.676579ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:33.009925Z","caller":"traceutil/trace.go:171","msg":"trace[1967170789] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"249.114402ms","start":"2026-04-16T16:31:32.760663Z","end":"2026-04-16T16:31:33.009777Z","steps":["trace[1967170789] 'process raft request' (duration: 248.62479ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:33.01605Z","caller":"traceutil/trace.go:171","msg":"trace[1192166390] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"138.262861ms","start":"2026-04-16T16:31:32.877753Z","end":"2026-04-16T16:31:33.016015Z","steps":["trace[1192166390] 'process raft request' (duration: 137.701388ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:37.933793Z","caller":"traceutil/trace.go:171","msg":"trace[1642951265] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"127.697772ms","start":"2026-04-16T16:31:37.802596Z","end":"2026-04-16T16:31:37.930294Z","steps":["trace[1642951265] 'process raft request' (duration: 126.714408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:38.600559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.218643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:38.600722Z","caller":"traceutil/trace.go:171","msg":"trace[324478880] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"615.467491ms","start":"2026-04-16T16:31:37.985232Z","end":"2026-04-16T16:31:38.6007Z","steps":["trace[324478880] 'process raft request' (duration: 359.825798ms)","trace[324478880] 'compare' (duration: 254.870513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:38.600805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:37.985205Z","time spent":"615.567764ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:38.618056Z","caller":"traceutil/trace.go:171","msg":"trace[1631633033] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"374.607681ms","start":"2026-04-16T16:31:38.243396Z","end":"2026-04-16T16:31:38.618003Z","steps":["trace[1631633033] 'read index received' (duration: 101.674624ms)","trace[1631633033] 'applied index is now lower than readState.Index' (duration: 272.931867ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:38.618233Z","caller":"traceutil/trace.go:171","msg":"trace[2145106832] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"594.437764ms","start":"2026-04-16T16:31:38.023785Z","end":"2026-04-16T16:31:38.618222Z","steps":["trace[2145106832] 'process raft request' (duration: 594.034415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:38.618544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.023761Z","time spent":"594.643469ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:31:38.618638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.218586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lzqc2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-16T16:31:38.618837Z","caller":"traceutil/trace.go:171","msg":"trace[1057865974] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lzqc2; range_end:; response_count:1; response_revision:617; }","duration":"375.439861ms","start":"2026-04-16T16:31:38.243339Z","end":"2026-04-16T16:31:38.618779Z","steps":["trace[1057865974] 'agreement among raft nodes before linearized reading' (duration: 375.106943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:38.618915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.243316Z","time spent":"375.588495ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-lzqc2\" "} {"level":"info","ts":"2026-04-16T16:31:53.584446Z","caller":"traceutil/trace.go:171","msg":"trace[1333622544] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"230.223349ms","start":"2026-04-16T16:31:53.354185Z","end":"2026-04-16T16:31:53.584409Z","steps":["trace[1333622544] 'process raft request' (duration: 229.600234ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:28.525841Z","caller":"traceutil/trace.go:171","msg":"trace[120556588] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"144.631693ms","start":"2026-04-16T16:32:28.381176Z","end":"2026-04-16T16:32:28.525808Z","steps":["trace[120556588] 'process raft request' (duration: 144.446498ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:42.017247Z","caller":"traceutil/trace.go:171","msg":"trace[1341519139] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"176.07983ms","start":"2026-04-16T16:32:41.841132Z","end":"2026-04-16T16:32:42.017212Z","steps":["trace[1341519139] 'process raft request' (duration: 175.917506ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:42.017717Z","caller":"traceutil/trace.go:171","msg":"trace[784638893] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"174.276275ms","start":"2026-04-16T16:32:41.843428Z","end":"2026-04-16T16:32:42.017704Z","steps":["trace[784638893] 'process raft request' (duration: 174.219103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:54.92948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.253221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:54.929584Z","caller":"traceutil/trace.go:171","msg":"trace[779449843] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1078; }","duration":"474.374044ms","start":"2026-04-16T16:32:54.455188Z","end":"2026-04-16T16:32:54.929562Z","steps":["trace[779449843] 'count revisions from in-memory index tree' (duration: 474.134968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:54.929638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.455134Z","time spent":"474.488766ms","remote":"127.0.0.1:47998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:32:54.929929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.145157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T16:32:54.929974Z","caller":"traceutil/trace.go:171","msg":"trace[2059811336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"468.215599ms","start":"2026-04-16T16:32:54.461748Z","end":"2026-04-16T16:32:54.929963Z","steps":["trace[2059811336] 'range keys from in-memory index tree' (duration: 467.994563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:54.930017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.461736Z","time spent":"468.27264ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:32:54.929998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.932448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:54.930198Z","caller":"traceutil/trace.go:171","msg":"trace[1933165314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"395.137313ms","start":"2026-04-16T16:32:54.535027Z","end":"2026-04-16T16:32:54.930164Z","steps":["trace[1933165314] 'range keys from in-memory index tree' (duration: 394.876846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:54.93024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.535013Z","time spent":"395.215105ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:32:54.930613Z","caller":"traceutil/trace.go:171","msg":"trace[1854902743] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"342.238281ms","start":"2026-04-16T16:32:54.588361Z","end":"2026-04-16T16:32:54.930599Z","steps":["trace[1854902743] 'process raft request' (duration: 333.699438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:54.930713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.559169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:32:54.930716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.588333Z","time spent":"342.319334ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:54.93076Z","caller":"traceutil/trace.go:171","msg":"trace[2060652743] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1078; }","duration":"376.931459ms","start":"2026-04-16T16:32:54.553818Z","end":"2026-04-16T16:32:54.930749Z","steps":["trace[2060652743] 'count revisions from in-memory index tree' (duration: 376.510148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:54.930794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.553807Z","time spent":"376.97618ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:32:54.960034Z","caller":"traceutil/trace.go:171","msg":"trace[766587712] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"322.848256ms","start":"2026-04-16T16:32:54.637148Z","end":"2026-04-16T16:32:54.959996Z","steps":["trace[766587712] 'process raft request' (duration: 322.487407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:54.960492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.637124Z","time spent":"323.079783ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:56.64907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611858866292533,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T16:32:56.702121Z","caller":"traceutil/trace.go:171","msg":"trace[913102698] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"553.462409ms","start":"2026-04-16T16:32:56.14863Z","end":"2026-04-16T16:32:56.702092Z","steps":["trace[913102698] 'read index received' (duration: 553.243973ms)","trace[913102698] 'applied index is now lower than readState.Index' (duration: 217.675µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:56.70222Z","caller":"traceutil/trace.go:171","msg":"trace[1227202200] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"607.358105ms","start":"2026-04-16T16:32:56.094819Z","end":"2026-04-16T16:32:56.702177Z","steps":["trace[1227202200] 'process raft request' (duration: 607.116599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:56.702331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.973362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:56.702374Z","caller":"traceutil/trace.go:171","msg":"trace[668168377] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1083; }","duration":"107.088635ms","start":"2026-04-16T16:32:56.595274Z","end":"2026-04-16T16:32:56.702363Z","steps":["trace[668168377] 'agreement among raft nodes before linearized reading' (duration: 106.986413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:56.702438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:56.09479Z","time spent":"607.528179ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:56.702468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.240658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T16:32:56.702474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.347056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:56.702583Z","caller":"traceutil/trace.go:171","msg":"trace[382594578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1083; }","duration":"277.406342ms","start":"2026-04-16T16:32:56.42515Z","end":"2026-04-16T16:32:56.702556Z","steps":["trace[382594578] 'agreement among raft nodes before linearized reading' (duration: 277.101824ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:56.702595Z","caller":"traceutil/trace.go:171","msg":"trace[834446187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"166.51683ms","start":"2026-04-16T16:32:56.536047Z","end":"2026-04-16T16:32:56.702564Z","steps":["trace[834446187] 'agreement among raft nodes before linearized reading' (duration: 166.337886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:56.702874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.242058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T16:32:56.702919Z","caller":"traceutil/trace.go:171","msg":"trace[685493022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"554.327751ms","start":"2026-04-16T16:32:56.14858Z","end":"2026-04-16T16:32:56.702907Z","steps":["trace[685493022] 'agreement among raft nodes before linearized reading' (duration: 554.182467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:56.702949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:56.148556Z","time spent":"554.385752ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:32:56.894309Z","caller":"traceutil/trace.go:171","msg":"trace[423726563] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"186.166442ms","start":"2026-04-16T16:32:56.708105Z","end":"2026-04-16T16:32:56.894271Z","steps":["trace[423726563] 'process raft request' (duration: 131.183437ms)","trace[423726563] 'compare' (duration: 54.832021ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:08.871203Z","caller":"traceutil/trace.go:171","msg":"trace[1313746140] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"113.039625ms","start":"2026-04-16T16:33:08.758128Z","end":"2026-04-16T16:33:08.871167Z","steps":["trace[1313746140] 'process raft request' (duration: 112.867611ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:09.21835Z","caller":"traceutil/trace.go:171","msg":"trace[1634520433] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"265.603545ms","start":"2026-04-16T16:33:08.952726Z","end":"2026-04-16T16:33:09.21833Z","steps":["trace[1634520433] 'read index received' (duration: 257.836613ms)","trace[1634520433] 'applied index is now lower than readState.Index' (duration: 7.766362ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:09.218526Z","caller":"traceutil/trace.go:171","msg":"trace[1512529368] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"342.595241ms","start":"2026-04-16T16:33:08.875924Z","end":"2026-04-16T16:33:09.218519Z","steps":["trace[1512529368] 'process raft request' (duration: 334.607954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:09.218775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:08.875891Z","time spent":"342.651182ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:09.218929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.204219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T16:33:09.218976Z","caller":"traceutil/trace.go:171","msg":"trace[1211213697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1174; }","duration":"266.309862ms","start":"2026-04-16T16:33:08.952651Z","end":"2026-04-16T16:33:09.218961Z","steps":["trace[1211213697] 'agreement among raft nodes before linearized reading' (duration: 266.162638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:09.229907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.573148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T16:33:09.229958Z","caller":"traceutil/trace.go:171","msg":"trace[1367939640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"184.63511ms","start":"2026-04-16T16:33:09.04531Z","end":"2026-04-16T16:33:09.229945Z","steps":["trace[1367939640] 'agreement among raft nodes before linearized reading' (duration: 184.470466ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:09.230141Z","caller":"traceutil/trace.go:171","msg":"trace[2005448169] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"216.493174ms","start":"2026-04-16T16:33:09.013642Z","end":"2026-04-16T16:33:09.230135Z","steps":["trace[2005448169] 'process raft request' (duration: 216.050113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:26.758835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.2304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:26.758968Z","caller":"traceutil/trace.go:171","msg":"trace[2094344971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"221.409655ms","start":"2026-04-16T16:33:26.537517Z","end":"2026-04-16T16:33:26.758927Z","steps":["trace[2094344971] 'range keys from in-memory index tree' (duration: 221.143189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:26.759075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.967759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:26.759194Z","caller":"traceutil/trace.go:171","msg":"trace[654972590] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1253; }","duration":"306.149634ms","start":"2026-04-16T16:33:26.453018Z","end":"2026-04-16T16:33:26.759167Z","steps":["trace[654972590] 'count revisions from in-memory index tree' (duration: 305.801155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:26.75925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:26.452991Z","time spent":"306.246816ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:33:42.570328Z","caller":"traceutil/trace.go:171","msg":"trace[2036771166] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"166.631556ms","start":"2026-04-16T16:33:42.403657Z","end":"2026-04-16T16:33:42.570289Z","steps":["trace[2036771166] 'process raft request' (duration: 166.401421ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:42.798558Z","caller":"traceutil/trace.go:171","msg":"trace[1310406054] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"223.081916ms","start":"2026-04-16T16:33:42.575436Z","end":"2026-04-16T16:33:42.798518Z","steps":["trace[1310406054] 'process raft request' (duration: 169.446705ms)","trace[1310406054] 'compare' (duration: 53.405886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:42.845058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.270584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:33:42.845126Z","caller":"traceutil/trace.go:171","msg":"trace[1893478847] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1359; }","duration":"243.396577ms","start":"2026-04-16T16:33:42.601713Z","end":"2026-04-16T16:33:42.84511Z","steps":["trace[1893478847] 'agreement among raft nodes before linearized reading' (duration: 243.256433ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:42.845007Z","caller":"traceutil/trace.go:171","msg":"trace[994836376] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"268.969228ms","start":"2026-04-16T16:33:42.575982Z","end":"2026-04-16T16:33:42.844951Z","steps":["trace[994836376] 'process raft request' (duration: 267.912361ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:42.844838Z","caller":"traceutil/trace.go:171","msg":"trace[583549624] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1408; }","duration":"243.045218ms","start":"2026-04-16T16:33:42.601762Z","end":"2026-04-16T16:33:42.844808Z","steps":["trace[583549624] 'read index received' (duration: 143.144827ms)","trace[583549624] 'applied index is now lower than readState.Index' (duration: 99.898961ms)"],"step_count":2}