{"level":"warn","ts":"2026-02-05T17:35:00.401139Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:35:00.401236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.204.45.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-02-05T17:35:00.401358Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:35:00.401373Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-05T17:35:00.401393Z","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-02-05T17:35:00.402206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-05T17:35:00.40234Z","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":2,"max-cpu-available":2,"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.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-02-05T17:35:00.411591Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.026289ms"} {"level":"info","ts":"2026-02-05T17:35:00.421091Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-05T17:35:00.421213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:35:00.421301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-05T17:35:00.421357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:35:00.421385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-05T17:35:00.421455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-05T17:35:00.431533Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:35:00.435747Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:35:00.44006Z","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-02-05T17:35:00.446218Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:35:00.449386Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:35:00.450498Z","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-02-05T17:35:00.4507Z","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-02-05T17:35:00.450864Z","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-02-05T17:35:00.451657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-05T17:35:00.452311Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-05T17:35:00.474121Z","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-02-05T17:35:00.476715Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:35:00.477286Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:35:00.477422Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-05T17:35:00.477788Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-05T17:35:00.723499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:35:00.723541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:35:00.723562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-05T17:35:00.723574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:35:00.723652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-05T17:35:00.723661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-05T17:35:00.723699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-05T17:35:00.729928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:35:00.730119Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:35:00.730233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:35:00.73071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:35:00.734809Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:35:00.739144Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:35:00.739214Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:35:00.741833Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:35:00.741958Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:35:00.742015Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:35:00.757302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"warn","ts":"2026-02-05T17:35:05.613657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251559907247851,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:35:06.11478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251559907247851,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:35:06.615661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251559907247851,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:35:06.730328Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.620560402s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:35:06.730951Z","caller":"traceutil/trace.go:171","msg":"trace[69625269] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"1.621275327s","start":"2026-02-05T17:35:05.109631Z","end":"2026-02-05T17:35:06.730906Z","steps":["trace[69625269] 'process raft request' (duration: 1.621061165s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:06.73172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:05.109611Z","time spent":"1.621417587s","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:35:07.011519Z","caller":"traceutil/trace.go:171","msg":"trace[759094801] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:214; }","duration":"1.898600305s","start":"2026-02-05T17:35:05.1129Z","end":"2026-02-05T17:35:07.0115Z","steps":["trace[759094801] 'read index received' (duration: 1.618665853s)","trace[759094801] 'applied index is now lower than readState.Index' (duration: 279.933682ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:07.011874Z","caller":"traceutil/trace.go:171","msg":"trace[1005640403] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"704.317004ms","start":"2026-02-05T17:35:06.307548Z","end":"2026-02-05T17:35:07.011865Z","steps":["trace[1005640403] 'process raft request' (duration: 703.854601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:07.012318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:06.307522Z","time spent":"704.763496ms","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3937,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:35:07.012095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.899172098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-05T17:35:07.012618Z","caller":"traceutil/trace.go:171","msg":"trace[657414374] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:210; }","duration":"1.899716001s","start":"2026-02-05T17:35:05.112891Z","end":"2026-02-05T17:35:07.012607Z","steps":["trace[657414374] 'agreement among raft nodes before linearized reading' (duration: 1.899129878s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:07.012719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:05.112851Z","time spent":"1.899859421s","remote":"127.0.0.1:41806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3201,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-05T17:35:07.01214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859524768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T17:35:07.013022Z","caller":"traceutil/trace.go:171","msg":"trace[525078200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:210; }","duration":"1.860435083s","start":"2026-02-05T17:35:05.152566Z","end":"2026-02-05T17:35:07.013001Z","steps":["trace[525078200] 'agreement among raft nodes before linearized reading' (duration: 1.859557248s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:07.014184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:05.152554Z","time spent":"1.861559448s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:07.012207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.401102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:07.015227Z","caller":"traceutil/trace.go:171","msg":"trace[1125466323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:210; }","duration":"353.531539ms","start":"2026-02-05T17:35:06.661667Z","end":"2026-02-05T17:35:07.015198Z","steps":["trace[1125466323] 'agreement among raft nodes before linearized reading' (duration: 350.487403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:07.01537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:06.661614Z","time spent":"353.70624ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:35:07.012236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85786928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T17:35:07.015689Z","caller":"traceutil/trace.go:171","msg":"trace[1635261790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:210; }","duration":"1.861341878s","start":"2026-02-05T17:35:05.154336Z","end":"2026-02-05T17:35:07.015678Z","steps":["trace[1635261790] 'agreement among raft nodes before linearized reading' (duration: 1.85788255s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:07.015792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:05.154321Z","time spent":"1.861461158s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:07.011985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:05.418532Z","time spent":"1.593449672s","remote":"127.0.0.1:41722","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T17:35:18.572565Z","caller":"traceutil/trace.go:171","msg":"trace[1380333770] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"126.603565ms","start":"2026-02-05T17:35:18.44593Z","end":"2026-02-05T17:35:18.572534Z","steps":["trace[1380333770] 'read index received' (duration: 126.324487ms)","trace[1380333770] 'applied index is now lower than readState.Index' (duration: 277.798µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:18.572733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.771365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-05T17:35:18.572769Z","caller":"traceutil/trace.go:171","msg":"trace[2048193878] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:421; }","duration":"126.85856ms","start":"2026-02-05T17:35:18.445899Z","end":"2026-02-05T17:35:18.572758Z","steps":["trace[2048193878] 'agreement among raft nodes before linearized reading' (duration: 126.757804ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:18.573044Z","caller":"traceutil/trace.go:171","msg":"trace[108240223] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"166.88699ms","start":"2026-02-05T17:35:18.406145Z","end":"2026-02-05T17:35:18.573032Z","steps":["trace[108240223] 'process raft request' (duration: 166.222679ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:32.856429Z","caller":"traceutil/trace.go:171","msg":"trace[568957368] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"209.033596ms","start":"2026-02-05T17:35:32.647367Z","end":"2026-02-05T17:35:32.8564Z","steps":["trace[568957368] 'process raft request' (duration: 208.826025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.49662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308006563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:35:34.496681Z","caller":"traceutil/trace.go:171","msg":"trace[1475319034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.308095767s","start":"2026-02-05T17:35:33.188567Z","end":"2026-02-05T17:35:34.496663Z","steps":["trace[1475319034] 'range keys from in-memory index tree' (duration: 1.307901377s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.496707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.188561Z","time spent":"1.30814053s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:34.496722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297892615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:35:34.49683Z","caller":"traceutil/trace.go:171","msg":"trace[1009631578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.298034092s","start":"2026-02-05T17:35:33.19877Z","end":"2026-02-05T17:35:34.496804Z","steps":["trace[1009631578] 'range keys from in-memory index tree' (duration: 1.297811891s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.496879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.198762Z","time spent":"1.298106486s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:34.496916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341280757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:35:34.496938Z","caller":"traceutil/trace.go:171","msg":"trace[1166197836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"1.34133355s","start":"2026-02-05T17:35:33.155598Z","end":"2026-02-05T17:35:34.496932Z","steps":["trace[1166197836] 'range keys from in-memory index tree' (duration: 1.341217683s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.496951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.15558Z","time spent":"1.341367962s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:35:34.497051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246985438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:34.497066Z","caller":"traceutil/trace.go:171","msg":"trace[488614960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"1.247011129s","start":"2026-02-05T17:35:33.250048Z","end":"2026-02-05T17:35:34.49706Z","steps":["trace[488614960] 'range keys from in-memory index tree' (duration: 1.246956136s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.497081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.250042Z","time spent":"1.24703578s","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:34.497199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254971827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:35:34.497213Z","caller":"traceutil/trace.go:171","msg":"trace[1820062003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"1.254998158s","start":"2026-02-05T17:35:33.24221Z","end":"2026-02-05T17:35:34.497209Z","steps":["trace[1820062003] 'range keys from in-memory index tree' (duration: 1.254916944s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.497226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.242204Z","time spent":"1.255018149s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:35:34.715034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.18307924s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:35:34.715445Z","caller":"traceutil/trace.go:171","msg":"trace[1518295478] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.183558674s","start":"2026-02-05T17:35:33.531861Z","end":"2026-02-05T17:35:34.715419Z","steps":["trace[1518295478] 'process raft request' (duration: 1.183356684s)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:34.715621Z","caller":"traceutil/trace.go:171","msg":"trace[249248037] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:534; }","duration":"215.63078ms","start":"2026-02-05T17:35:34.499974Z","end":"2026-02-05T17:35:34.715605Z","steps":["trace[249248037] 'read index received' (duration: 215.624269ms)","trace[249248037] 'applied index is now lower than readState.Index' (duration: 5.191µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:34.715745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.754296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:34.715773Z","caller":"traceutil/trace.go:171","msg":"trace[357765871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"215.807809ms","start":"2026-02-05T17:35:34.499958Z","end":"2026-02-05T17:35:34.715765Z","steps":["trace[357765871] 'agreement among raft nodes before linearized reading' (duration: 215.713774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.715918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.531841Z","time spent":"1.183697611s","remote":"127.0.0.1:41892","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-02-05T17:35:35.008558Z","caller":"traceutil/trace.go:171","msg":"trace[1796418263] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"500.350413ms","start":"2026-02-05T17:35:34.508187Z","end":"2026-02-05T17:35:35.008537Z","steps":["trace[1796418263] 'process raft request' (duration: 500.307841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:35.008716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:34.50818Z","time spent":"500.460388ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:35.009102Z","caller":"traceutil/trace.go:171","msg":"trace[1125145087] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"505.402288ms","start":"2026-02-05T17:35:34.503688Z","end":"2026-02-05T17:35:35.00909Z","steps":["trace[1125145087] 'process raft request' (duration: 504.592407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:35.009161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:34.503676Z","time spent":"505.452601ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:35.009278Z","caller":"traceutil/trace.go:171","msg":"trace[1367906712] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"503.602537ms","start":"2026-02-05T17:35:34.505666Z","end":"2026-02-05T17:35:35.009269Z","steps":["trace[1367906712] 'process raft request' (duration: 502.736253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:35.009323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:34.50566Z","time spent":"503.638459ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:35.009435Z","caller":"traceutil/trace.go:171","msg":"trace[1789587039] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"503.228267ms","start":"2026-02-05T17:35:34.506198Z","end":"2026-02-05T17:35:35.009427Z","steps":["trace[1789587039] 'process raft request' (duration: 502.243858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:35.009482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:34.506191Z","time spent":"503.26598ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:35.015278Z","caller":"traceutil/trace.go:171","msg":"trace[1827905226] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:538; }","duration":"299.606412ms","start":"2026-02-05T17:35:34.715655Z","end":"2026-02-05T17:35:35.015262Z","steps":["trace[1827905226] 'read index received' (duration: 299.599612ms)","trace[1827905226] 'applied index is now lower than readState.Index' (duration: 5.71µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:35.016548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.942586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T17:35:35.016758Z","caller":"traceutil/trace.go:171","msg":"trace[1154478058] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:522; }","duration":"497.125194ms","start":"2026-02-05T17:35:34.519541Z","end":"2026-02-05T17:35:35.016666Z","steps":["trace[1154478058] 'agreement among raft nodes before linearized reading' (duration: 496.937885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:35.017021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:34.519517Z","time spent":"497.313074ms","remote":"127.0.0.1:41666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"info","ts":"2026-02-05T17:35:35.019412Z","caller":"traceutil/trace.go:171","msg":"trace[1086202108] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"187.96324ms","start":"2026-02-05T17:35:34.831441Z","end":"2026-02-05T17:35:35.019405Z","steps":["trace[1086202108] 'process raft request' (duration: 187.909517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:35.019715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.142282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:35.019866Z","caller":"traceutil/trace.go:171","msg":"trace[753334711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"293.335532ms","start":"2026-02-05T17:35:34.726524Z","end":"2026-02-05T17:35:35.019859Z","steps":["trace[753334711] 'agreement among raft nodes before linearized reading' (duration: 293.159533ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:41.322879Z","caller":"traceutil/trace.go:171","msg":"trace[372874897] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"189.837536ms","start":"2026-02-05T17:35:41.133023Z","end":"2026-02-05T17:35:41.32286Z","steps":["trace[372874897] 'process raft request' (duration: 112.523887ms)","trace[372874897] 'compare' (duration: 77.226545ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:41.333674Z","caller":"traceutil/trace.go:171","msg":"trace[1764921870] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"198.632335ms","start":"2026-02-05T17:35:41.135033Z","end":"2026-02-05T17:35:41.333665Z","steps":["trace[1764921870] 'process raft request' (duration: 198.444327ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:42.340151Z","caller":"traceutil/trace.go:171","msg":"trace[1991835642] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"166.2994ms","start":"2026-02-05T17:35:42.173835Z","end":"2026-02-05T17:35:42.340134Z","steps":["trace[1991835642] 'process raft request' (duration: 166.194896ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:43.259693Z","caller":"traceutil/trace.go:171","msg":"trace[1969099415] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"123.597909ms","start":"2026-02-05T17:35:43.136082Z","end":"2026-02-05T17:35:43.25968Z","steps":["trace[1969099415] 'process raft request' (duration: 123.322377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:44.722372Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.358902164s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:35:44.722854Z","caller":"traceutil/trace.go:171","msg":"trace[1542980003] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.359412597s","start":"2026-02-05T17:35:43.36342Z","end":"2026-02-05T17:35:44.722833Z","steps":["trace[1542980003] 'process raft request' (duration: 1.359236429s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:44.722981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:43.363409Z","time spent":"1.359485301s","remote":"127.0.0.1:41892","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-02-05T17:35:45.474049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.562744ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:45.474203Z","caller":"traceutil/trace.go:171","msg":"trace[837062889] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:566; }","duration":"199.721101ms","start":"2026-02-05T17:35:45.274461Z","end":"2026-02-05T17:35:45.474182Z","steps":["trace[837062889] 'range keys from in-memory index tree' (duration: 199.548583ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:45.47661Z","caller":"traceutil/trace.go:171","msg":"trace[1309467271] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"956.695857ms","start":"2026-02-05T17:35:44.519907Z","end":"2026-02-05T17:35:45.476603Z","steps":["trace[1309467271] 'read index received' (duration: 203.420022ms)","trace[1309467271] 'applied index is now lower than readState.Index' (duration: 753.275375ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:45.476718Z","caller":"traceutil/trace.go:171","msg":"trace[129653175] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"2.112896412s","start":"2026-02-05T17:35:43.363816Z","end":"2026-02-05T17:35:45.476712Z","steps":["trace[129653175] 'process raft request' (duration: 2.112705023s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:45.476778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:43.363809Z","time spent":"2.112933814s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:45.476991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.083164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T17:35:45.477011Z","caller":"traceutil/trace.go:171","msg":"trace[1517327345] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:567; }","duration":"957.121486ms","start":"2026-02-05T17:35:44.519881Z","end":"2026-02-05T17:35:45.477002Z","steps":["trace[1517327345] 'agreement among raft nodes before linearized reading' (duration: 957.089484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:45.477025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:44.519868Z","time spent":"957.153648ms","remote":"127.0.0.1:41666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"warn","ts":"2026-02-05T17:35:45.479847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.514184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:45.479897Z","caller":"traceutil/trace.go:171","msg":"trace[1348634878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"510.572717ms","start":"2026-02-05T17:35:44.969312Z","end":"2026-02-05T17:35:45.479885Z","steps":["trace[1348634878] 'agreement among raft nodes before linearized reading' (duration: 510.499483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:45.479923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:44.969274Z","time spent":"510.64098ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:45.479922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.777823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:35:45.479951Z","caller":"traceutil/trace.go:171","msg":"trace[1166539225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"217.815244ms","start":"2026-02-05T17:35:45.262124Z","end":"2026-02-05T17:35:45.47994Z","steps":["trace[1166539225] 'agreement among raft nodes before linearized reading' (duration: 217.740131ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:45.480039Z","caller":"traceutil/trace.go:171","msg":"trace[899366643] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"716.93354ms","start":"2026-02-05T17:35:44.7631Z","end":"2026-02-05T17:35:45.480034Z","steps":["trace[899366643] 'process raft request' (duration: 716.626376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:45.480053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.381328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:45.480069Z","caller":"traceutil/trace.go:171","msg":"trace[653537434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"229.408749ms","start":"2026-02-05T17:35:45.250655Z","end":"2026-02-05T17:35:45.480064Z","steps":["trace[653537434] 'agreement among raft nodes before linearized reading' (duration: 229.384948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:45.480087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:44.76308Z","time spent":"716.973272ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:45.480197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.550002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:35:45.480211Z","caller":"traceutil/trace.go:171","msg":"trace[444015548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"244.610745ms","start":"2026-02-05T17:35:45.235596Z","end":"2026-02-05T17:35:45.480207Z","steps":["trace[444015548] 'agreement among raft nodes before linearized reading' (duration: 244.538112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:47.980876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251559907248610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:35:48.030417Z","caller":"traceutil/trace.go:171","msg":"trace[1528762925] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"549.695799ms","start":"2026-02-05T17:35:47.480697Z","end":"2026-02-05T17:35:48.030393Z","steps":["trace[1528762925] 'read index received' (duration: 549.573833ms)","trace[1528762925] 'applied index is now lower than readState.Index' (duration: 121.466µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:48.030484Z","caller":"traceutil/trace.go:171","msg":"trace[1122717061] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"570.674509ms","start":"2026-02-05T17:35:47.459803Z","end":"2026-02-05T17:35:48.030478Z","steps":["trace[1122717061] 'process raft request' (duration: 570.508482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:48.030539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:47.459785Z","time spent":"570.7135ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:35:48.030902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.029198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:35:48.030923Z","caller":"traceutil/trace.go:171","msg":"trace[1017350305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"534.07992ms","start":"2026-02-05T17:35:47.496837Z","end":"2026-02-05T17:35:48.030917Z","steps":["trace[1017350305] 'agreement among raft nodes before linearized reading' (duration: 533.957715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:48.030938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:47.496823Z","time spent":"534.111632ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:35:48.031125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.42713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:35:48.031144Z","caller":"traceutil/trace.go:171","msg":"trace[1888303162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"550.472742ms","start":"2026-02-05T17:35:47.480667Z","end":"2026-02-05T17:35:48.03114Z","steps":["trace[1888303162] 'agreement among raft nodes before linearized reading' (duration: 550.42348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:48.031157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:47.480654Z","time spent":"550.500262ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:48.031272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.437787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T17:35:48.031288Z","caller":"traceutil/trace.go:171","msg":"trace[1645726344] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:582; }","duration":"338.481529ms","start":"2026-02-05T17:35:47.692802Z","end":"2026-02-05T17:35:48.031284Z","steps":["trace[1645726344] 'agreement among raft nodes before linearized reading' (duration: 338.443477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:48.0313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:47.692789Z","time spent":"338.50821ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T17:35:48.031427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.469424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:35:48.031442Z","caller":"traceutil/trace.go:171","msg":"trace[1483350901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"498.507075ms","start":"2026-02-05T17:35:47.53293Z","end":"2026-02-05T17:35:48.031437Z","steps":["trace[1483350901] 'agreement among raft nodes before linearized reading' (duration: 498.464524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:48.031465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:47.532916Z","time spent":"498.545867ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T17:35:49.677585Z","caller":"traceutil/trace.go:171","msg":"trace[174236863] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"132.820555ms","start":"2026-02-05T17:35:49.54475Z","end":"2026-02-05T17:35:49.67757Z","steps":["trace[174236863] 'process raft request' (duration: 132.789863ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:49.677762Z","caller":"traceutil/trace.go:171","msg":"trace[912154043] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"200.191264ms","start":"2026-02-05T17:35:49.477564Z","end":"2026-02-05T17:35:49.677755Z","steps":["trace[912154043] 'process raft request' (duration: 130.641793ms)","trace[912154043] 'compare' (duration: 69.142823ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:49.927752Z","caller":"traceutil/trace.go:171","msg":"trace[1835187139] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"245.096889ms","start":"2026-02-05T17:35:49.682632Z","end":"2026-02-05T17:35:49.927728Z","steps":["trace[1835187139] 'process raft request' (duration: 163.719407ms)","trace[1835187139] 'compare' (duration: 81.245637ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:49.955038Z","caller":"traceutil/trace.go:171","msg":"trace[642133249] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"269.024754ms","start":"2026-02-05T17:35:49.68599Z","end":"2026-02-05T17:35:49.955015Z","steps":["trace[642133249] 'process raft request' (duration: 268.634708ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:52.447966Z","caller":"traceutil/trace.go:171","msg":"trace[2050057263] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"220.376298ms","start":"2026-02-05T17:35:52.227571Z","end":"2026-02-05T17:35:52.447947Z","steps":["trace[2050057263] 'read index received' (duration: 179.986742ms)","trace[2050057263] 'applied index is now lower than readState.Index' (duration: 40.388756ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:52.448137Z","caller":"traceutil/trace.go:171","msg":"trace[1001315648] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"222.922802ms","start":"2026-02-05T17:35:52.2252Z","end":"2026-02-05T17:35:52.448123Z","steps":["trace[1001315648] 'process raft request' (duration: 182.39459ms)","trace[1001315648] 'compare' (duration: 40.271312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:52.448655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.064766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bmqxt\" ","response":"range_response_count:1 size:18071"} {"level":"info","ts":"2026-02-05T17:35:52.448703Z","caller":"traceutil/trace.go:171","msg":"trace[397822601] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bmqxt; range_end:; response_count:1; response_revision:652; }","duration":"221.1484ms","start":"2026-02-05T17:35:52.227541Z","end":"2026-02-05T17:35:52.44869Z","steps":["trace[397822601] 'agreement among raft nodes before linearized reading' (duration: 220.973192ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:52.609055Z","caller":"traceutil/trace.go:171","msg":"trace[1391829207] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"186.318841ms","start":"2026-02-05T17:35:52.422714Z","end":"2026-02-05T17:35:52.609032Z","steps":["trace[1391829207] 'process raft request' (duration: 143.425782ms)","trace[1391829207] 'compare' (duration: 41.244691ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:52.610167Z","caller":"traceutil/trace.go:171","msg":"trace[269473203] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"162.124709ms","start":"2026-02-05T17:35:52.448029Z","end":"2026-02-05T17:35:52.610153Z","steps":["trace[269473203] 'read index received' (duration: 118.122504ms)","trace[269473203] 'applied index is now lower than readState.Index' (duration: 44.000234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:52.610435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.280724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T17:35:52.610498Z","caller":"traceutil/trace.go:171","msg":"trace[780851101] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:653; }","duration":"201.334376ms","start":"2026-02-05T17:35:52.409132Z","end":"2026-02-05T17:35:52.610466Z","steps":["trace[780851101] 'agreement among raft nodes before linearized reading' (duration: 201.192971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:52.610826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.713096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-05T17:35:52.610868Z","caller":"traceutil/trace.go:171","msg":"trace[1828723531] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"235.774538ms","start":"2026-02-05T17:35:52.375081Z","end":"2026-02-05T17:35:52.610856Z","steps":["trace[1828723531] 'agreement among raft nodes before linearized reading' (duration: 235.646913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:52.611261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.584626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:52.6113Z","caller":"traceutil/trace.go:171","msg":"trace[338996032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"359.646459ms","start":"2026-02-05T17:35:52.251642Z","end":"2026-02-05T17:35:52.611288Z","steps":["trace[338996032] 'agreement among raft nodes before linearized reading' (duration: 359.587607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:52.611468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:52.251628Z","time spent":"359.829726ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:52.615623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.01238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-05T17:35:52.615647Z","caller":"traceutil/trace.go:171","msg":"trace[233423348] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"138.088543ms","start":"2026-02-05T17:35:52.477554Z","end":"2026-02-05T17:35:52.615642Z","steps":["trace[233423348] 'agreement among raft nodes before linearized reading' (duration: 137.990279ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:52.615835Z","caller":"traceutil/trace.go:171","msg":"trace[789612234] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"157.257759ms","start":"2026-02-05T17:35:52.458555Z","end":"2026-02-05T17:35:52.615813Z","steps":["trace[789612234] 'process raft request' (duration: 156.936925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:52.987584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.606734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:35:52.987712Z","caller":"traceutil/trace.go:171","msg":"trace[1234314234] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"127.818113ms","start":"2026-02-05T17:35:52.859881Z","end":"2026-02-05T17:35:52.987699Z","steps":["trace[1234314234] 'process raft request' (duration: 127.791812ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:52.987873Z","caller":"traceutil/trace.go:171","msg":"trace[1328884019] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"168.839905ms","start":"2026-02-05T17:35:52.819027Z","end":"2026-02-05T17:35:52.987867Z","steps":["trace[1328884019] 'process raft request' (duration: 49.812063ms)","trace[1328884019] 'compare' (duration: 118.532191ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:52.987974Z","caller":"traceutil/trace.go:171","msg":"trace[1026302511] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"165.764559ms","start":"2026-02-05T17:35:52.822205Z","end":"2026-02-05T17:35:52.987969Z","steps":["trace[1026302511] 'process raft request' (duration: 165.439295ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:53.220997Z","caller":"traceutil/trace.go:171","msg":"trace[2061029435] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"213.136294ms","start":"2026-02-05T17:35:53.007844Z","end":"2026-02-05T17:35:53.22098Z","steps":["trace[2061029435] 'process raft request' (duration: 205.074977ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:53.363529Z","caller":"traceutil/trace.go:171","msg":"trace[487098822] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"101.631832ms","start":"2026-02-05T17:35:53.261882Z","end":"2026-02-05T17:35:53.363513Z","steps":["trace[487098822] 'process raft request' (duration: 101.599811ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:53.363889Z","caller":"traceutil/trace.go:171","msg":"trace[409678196] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"308.933159ms","start":"2026-02-05T17:35:53.054949Z","end":"2026-02-05T17:35:53.363882Z","steps":["trace[409678196] 'process raft request' (duration: 268.097233ms)","trace[409678196] 'compare' (duration: 40.316955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:53.363954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:53.054934Z","time spent":"308.9755ms","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4681,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:53.364016Z","caller":"traceutil/trace.go:171","msg":"trace[836562287] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"198.325503ms","start":"2026-02-05T17:35:53.165685Z","end":"2026-02-05T17:35:53.36401Z","steps":["trace[836562287] 'read index received' (duration: 47.242926ms)","trace[836562287] 'applied index is now lower than readState.Index' (duration: 151.082057ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:53.364047Z","caller":"traceutil/trace.go:171","msg":"trace[949978046] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"128.129356ms","start":"2026-02-05T17:35:53.235914Z","end":"2026-02-05T17:35:53.364043Z","steps":["trace[949978046] 'process raft request' (duration: 127.529152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:53.364264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.572903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:53.364289Z","caller":"traceutil/trace.go:171","msg":"trace[165832606] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:667; }","duration":"198.619515ms","start":"2026-02-05T17:35:53.165664Z","end":"2026-02-05T17:35:53.364283Z","steps":["trace[165832606] 'agreement among raft nodes before linearized reading' (duration: 198.550422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:53.364442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.720939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:53.364462Z","caller":"traceutil/trace.go:171","msg":"trace[676021354] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:667; }","duration":"198.74242ms","start":"2026-02-05T17:35:53.165715Z","end":"2026-02-05T17:35:53.364458Z","steps":["trace[676021354] 'agreement among raft nodes before linearized reading' (duration: 198.713229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:53.365225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.79683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:53.365248Z","caller":"traceutil/trace.go:171","msg":"trace[1298378371] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:667; }","duration":"194.848052ms","start":"2026-02-05T17:35:53.170395Z","end":"2026-02-05T17:35:53.365243Z","steps":["trace[1298378371] 'agreement among raft nodes before linearized reading' (duration: 194.817401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:53.367596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.217088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:53.36762Z","caller":"traceutil/trace.go:171","msg":"trace[347691071] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:667; }","duration":"197.24443ms","start":"2026-02-05T17:35:53.170371Z","end":"2026-02-05T17:35:53.367616Z","steps":["trace[347691071] 'agreement among raft nodes before linearized reading' (duration: 197.207418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:53.376002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.6413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:53.376041Z","caller":"traceutil/trace.go:171","msg":"trace[1722909740] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:667; }","duration":"205.694332ms","start":"2026-02-05T17:35:53.170341Z","end":"2026-02-05T17:35:53.376035Z","steps":["trace[1722909740] 'agreement among raft nodes before linearized reading' (duration: 205.632819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:53.379446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.712117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:53.379492Z","caller":"traceutil/trace.go:171","msg":"trace[1588908450] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:667; }","duration":"213.765139ms","start":"2026-02-05T17:35:53.165721Z","end":"2026-02-05T17:35:53.379486Z","steps":["trace[1588908450] 'agreement among raft nodes before linearized reading' (duration: 213.705666ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:08.446105Z","caller":"traceutil/trace.go:171","msg":"trace[1817587217] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"216.755818ms","start":"2026-02-05T17:36:08.229317Z","end":"2026-02-05T17:36:08.446073Z","steps":["trace[1817587217] 'read index received' (duration: 216.426066ms)","trace[1817587217] 'applied index is now lower than readState.Index' (duration: 328.362µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:08.446235Z","caller":"traceutil/trace.go:171","msg":"trace[387537770] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"260.396383ms","start":"2026-02-05T17:36:08.185829Z","end":"2026-02-05T17:36:08.446226Z","steps":["trace[387537770] 'process raft request' (duration: 260.052241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:08.446403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.401492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:08.446464Z","caller":"traceutil/trace.go:171","msg":"trace[1893855801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"195.510275ms","start":"2026-02-05T17:36:08.250938Z","end":"2026-02-05T17:36:08.446448Z","steps":["trace[1893855801] 'agreement among raft nodes before linearized reading' (duration: 195.416122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:08.446661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.347958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:36:08.446675Z","caller":"traceutil/trace.go:171","msg":"trace[1812076051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"217.413681ms","start":"2026-02-05T17:36:08.229257Z","end":"2026-02-05T17:36:08.446671Z","steps":["trace[1812076051] 'agreement among raft nodes before linearized reading' (duration: 217.346198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:08.44678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.04224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:36:08.446817Z","caller":"traceutil/trace.go:171","msg":"trace[204844474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"171.145054ms","start":"2026-02-05T17:36:08.275662Z","end":"2026-02-05T17:36:08.446807Z","steps":["trace[204844474] 'agreement among raft nodes before linearized reading' (duration: 170.982789ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:47.02157Z","caller":"traceutil/trace.go:171","msg":"trace[1251007534] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"118.83762ms","start":"2026-02-05T17:36:46.902686Z","end":"2026-02-05T17:36:47.021524Z","steps":["trace[1251007534] 'process raft request' (duration: 59.862772ms)","trace[1251007534] 'compare' (duration: 58.586868ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:55.255644Z","caller":"traceutil/trace.go:171","msg":"trace[776336407] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"134.722236ms","start":"2026-02-05T17:36:55.120883Z","end":"2026-02-05T17:36:55.255605Z","steps":["trace[776336407] 'process raft request' (duration: 109.834994ms)","trace[776336407] 'compare' (duration: 24.78553ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:01.814006Z","caller":"traceutil/trace.go:171","msg":"trace[1157568240] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"170.907947ms","start":"2026-02-05T17:37:01.643075Z","end":"2026-02-05T17:37:01.813983Z","steps":["trace[1157568240] 'process raft request' (duration: 170.727563ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:03.168991Z","caller":"traceutil/trace.go:171","msg":"trace[1145551964] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"120.176302ms","start":"2026-02-05T17:37:03.048788Z","end":"2026-02-05T17:37:03.168964Z","steps":["trace[1145551964] 'process raft request' (duration: 105.200813ms)","trace[1145551964] 'compare' (duration: 14.319905ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:05.046968Z","caller":"traceutil/trace.go:171","msg":"trace[1785361997] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"176.097303ms","start":"2026-02-05T17:37:04.870844Z","end":"2026-02-05T17:37:05.046941Z","steps":["trace[1785361997] 'process raft request' (duration: 175.527901ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:07.310737Z","caller":"traceutil/trace.go:171","msg":"trace[1125191926] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"100.77256ms","start":"2026-02-05T17:37:07.209942Z","end":"2026-02-05T17:37:07.310715Z","steps":["trace[1125191926] 'process raft request' (duration: 100.629347ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:17.829776Z","caller":"traceutil/trace.go:171","msg":"trace[656119283] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"647.339221ms","start":"2026-02-05T17:37:17.182416Z","end":"2026-02-05T17:37:17.829756Z","steps":["trace[656119283] 'process raft request' (duration: 646.733819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:17.82992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:17.182402Z","time spent":"647.428732ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:17.830218Z","caller":"traceutil/trace.go:171","msg":"trace[1474869212] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"477.739244ms","start":"2026-02-05T17:37:17.351525Z","end":"2026-02-05T17:37:17.829264Z","steps":["trace[1474869212] 'read index received' (duration: 477.570461ms)","trace[1474869212] 'applied index is now lower than readState.Index' (duration: 167.953µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:17.830418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.890927ms","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-02-05T17:37:17.830447Z","caller":"traceutil/trace.go:171","msg":"trace[1905384603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"478.999568ms","start":"2026-02-05T17:37:17.351439Z","end":"2026-02-05T17:37:17.830438Z","steps":["trace[1905384603] 'agreement among raft nodes before linearized reading' (duration: 478.852275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:17.830467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:17.35142Z","time spent":"479.04211ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:37:17.830619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.997961ms","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-02-05T17:37:17.830638Z","caller":"traceutil/trace.go:171","msg":"trace[2002451409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"352.062292ms","start":"2026-02-05T17:37:17.47857Z","end":"2026-02-05T17:37:17.830632Z","steps":["trace[2002451409] 'agreement among raft nodes before linearized reading' (duration: 352.005151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:17.830654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:17.478545Z","time spent":"352.105222ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:17.830975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.745079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:37:17.831Z","caller":"traceutil/trace.go:171","msg":"trace[18728245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"293.80946ms","start":"2026-02-05T17:37:17.537185Z","end":"2026-02-05T17:37:17.830994Z","steps":["trace[18728245] 'agreement among raft nodes before linearized reading' (duration: 293.566425ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:52.30226Z","caller":"traceutil/trace.go:171","msg":"trace[1904804027] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"100.750411ms","start":"2026-02-05T17:37:52.201487Z","end":"2026-02-05T17:37:52.302237Z","steps":["trace[1904804027] 'process raft request' (duration: 99.870747ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:52.303087Z","caller":"traceutil/trace.go:171","msg":"trace[65979104] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"100.356115ms","start":"2026-02-05T17:37:52.202717Z","end":"2026-02-05T17:37:52.303073Z","steps":["trace[65979104] 'process raft request' (duration: 100.187012ms)"],"step_count":1}