{"level":"warn","ts":"2026-02-25T02:10:23.459097Z","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-25T02:10:23.459268Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-25T02:10:23.459413Z","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-25T02:10:23.459426Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-25T02:10:23.459454Z","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-25T02:10:23.460748Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-02-25T02:10:23.460863Z","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.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-25T02:10:23.46885Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.672053ms"} {"level":"info","ts":"2026-02-25T02:10:23.482728Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-02-25T02:10:23.482905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:10:23.482947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-02-25T02:10:23.482969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:10:23.482988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-02-25T02:10:23.483058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-02-25T02:10:23.492747Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:10:23.494883Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:10:23.497742Z","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-25T02:10:23.502788Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:10:23.503342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:10:23.503495Z","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-25T02:10:23.503746Z","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-25T02:10:23.50376Z","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-25T02:10:23.504085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-02-25T02:10:23.504238Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-25T02:10:23.505404Z","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-25T02:10:23.505544Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-25T02:10:23.505585Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-25T02:10:23.50583Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:10:23.505863Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:10:24.184725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:10:24.184817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:10:24.184845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-02-25T02:10:24.184866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:10:24.184878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-25T02:10:24.184894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-02-25T02:10:24.184907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-25T02:10:24.186146Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:10:24.18652Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:10:24.187225Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:10:24.187357Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:10:24.187414Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:10:24.187434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:10:24.188684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:10:24.196102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-02-25T02:10:24.196123Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:10:24.196218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:10:24.200957Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T02:10:28.802046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314124035194093,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:10:28.985438Z","caller":"traceutil/trace.go:171","msg":"trace[374487885] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:208; }","duration":"684.229945ms","start":"2026-02-25T02:10:28.301182Z","end":"2026-02-25T02:10:28.985412Z","steps":["trace[374487885] 'read index received' (duration: 684.199494ms)","trace[374487885] 'applied index is now lower than readState.Index' (duration: 29.181µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:10:28.985538Z","caller":"traceutil/trace.go:171","msg":"trace[1777579566] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"734.179934ms","start":"2026-02-25T02:10:28.251324Z","end":"2026-02-25T02:10:28.985504Z","steps":["trace[1777579566] 'process raft request' (duration: 733.932936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:28.985698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.444001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3181"} {"level":"info","ts":"2026-02-25T02:10:28.985786Z","caller":"traceutil/trace.go:171","msg":"trace[1963265018] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:204; }","duration":"684.626107ms","start":"2026-02-25T02:10:28.301147Z","end":"2026-02-25T02:10:28.985773Z","steps":["trace[1963265018] 'agreement among raft nodes before linearized reading' (duration: 684.371459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:28.985828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:28.301127Z","time spent":"684.69235ms","remote":"127.0.0.1:51262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3205,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T02:10:28.986261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:28.251243Z","time spent":"734.369839ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3937,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:10:29.626991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314124035194102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:10:30.128038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314124035194102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:10:30.628858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314124035194102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:10:30.939756Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.954431663s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:10:30.943392Z","caller":"traceutil/trace.go:171","msg":"trace[1122421658] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"2.080582734s","start":"2026-02-25T02:10:28.862768Z","end":"2026-02-25T02:10:30.94335Z","steps":["trace[1122421658] 'process raft request' (duration: 2.077855918s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:30.943655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:28.862742Z","time spent":"2.08075812s","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:10:30.949664Z","caller":"traceutil/trace.go:171","msg":"trace[195414040] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"1.955323751s","start":"2026-02-25T02:10:28.994316Z","end":"2026-02-25T02:10:30.94964Z","steps":["trace[195414040] 'process raft request' (duration: 1.955144066s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:30.949585Z","caller":"traceutil/trace.go:171","msg":"trace[2132262805] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"1.823021215s","start":"2026-02-25T02:10:29.126542Z","end":"2026-02-25T02:10:30.949563Z","steps":["trace[2132262805] 'read index received' (duration: 1.814430532s)","trace[2132262805] 'applied index is now lower than readState.Index' (duration: 8.589743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:10:30.949849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.823297543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:10:30.949882Z","caller":"traceutil/trace.go:171","msg":"trace[610288655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:206; }","duration":"1.823366576s","start":"2026-02-25T02:10:29.126505Z","end":"2026-02-25T02:10:30.949872Z","steps":["trace[610288655] 'agreement among raft nodes before linearized reading' (duration: 1.823304624s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:30.949907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:29.126486Z","time spent":"1.823414737s","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:10:30.950099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:28.994291Z","time spent":"1.955476576s","remote":"127.0.0.1:51262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3384,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:10:30.950187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.869075ms","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-02-25T02:10:30.950645Z","caller":"traceutil/trace.go:171","msg":"trace[1447752880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:206; }","duration":"807.34859ms","start":"2026-02-25T02:10:30.143282Z","end":"2026-02-25T02:10:30.95063Z","steps":["trace[1447752880] 'agreement among raft nodes before linearized reading' (duration: 806.894225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:30.950706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:30.143234Z","time spent":"807.461964ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:10:30.950289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156323425s","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-25T02:10:30.95084Z","caller":"traceutil/trace.go:171","msg":"trace[1637183012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:206; }","duration":"1.156905263s","start":"2026-02-25T02:10:29.79392Z","end":"2026-02-25T02:10:30.950826Z","steps":["trace[1637183012] 'agreement among raft nodes before linearized reading' (duration: 1.156345925s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:30.950946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:29.793897Z","time spent":"1.157036457s","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:10:30.950159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294772679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:10:30.951133Z","caller":"traceutil/trace.go:171","msg":"trace[1398945689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:206; }","duration":"1.295709108s","start":"2026-02-25T02:10:29.655355Z","end":"2026-02-25T02:10:30.951064Z","steps":["trace[1398945689] 'agreement among raft nodes before linearized reading' (duration: 1.294784868s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:30.951201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:29.655334Z","time spent":"1.295855163s","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T02:10:33.162611Z","caller":"traceutil/trace.go:171","msg":"trace[2100927] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:322; }","duration":"187.413099ms","start":"2026-02-25T02:10:32.975173Z","end":"2026-02-25T02:10:33.162586Z","steps":["trace[2100927] 'read index received' (duration: 73.987462ms)","trace[2100927] 'applied index is now lower than readState.Index' (duration: 113.424747ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:10:33.162792Z","caller":"traceutil/trace.go:171","msg":"trace[888064187] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"165.817022ms","start":"2026-02-25T02:10:32.996964Z","end":"2026-02-25T02:10:33.162781Z","steps":["trace[888064187] 'process raft request' (duration: 165.579044ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:33.162763Z","caller":"traceutil/trace.go:171","msg":"trace[724198209] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"205.439212ms","start":"2026-02-25T02:10:32.957288Z","end":"2026-02-25T02:10:33.162727Z","steps":["trace[724198209] 'process raft request' (duration: 205.019969ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:33.16284Z","caller":"traceutil/trace.go:171","msg":"trace[1963825862] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"205.422922ms","start":"2026-02-25T02:10:32.957387Z","end":"2026-02-25T02:10:33.16281Z","steps":["trace[1963825862] 'process raft request' (duration: 205.04488ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:33.162916Z","caller":"traceutil/trace.go:171","msg":"trace[1384692259] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"199.280547ms","start":"2026-02-25T02:10:32.963617Z","end":"2026-02-25T02:10:33.162898Z","steps":["trace[1384692259] 'process raft request' (duration: 198.841663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:33.163027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.262914ms","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-25T02:10:33.163054Z","caller":"traceutil/trace.go:171","msg":"trace[1138095156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:313; }","duration":"187.340747ms","start":"2026-02-25T02:10:32.975707Z","end":"2026-02-25T02:10:33.163047Z","steps":["trace[1138095156] 'agreement among raft nodes before linearized reading' (duration: 187.268544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:33.163128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.884799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5870"} {"level":"warn","ts":"2026-02-25T02:10:33.163163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.340815ms","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-02-25T02:10:33.16318Z","caller":"traceutil/trace.go:171","msg":"trace[1268784503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:313; }","duration":"186.379387ms","start":"2026-02-25T02:10:32.976797Z","end":"2026-02-25T02:10:33.163176Z","steps":["trace[1268784503] 'agreement among raft nodes before linearized reading' (duration: 186.348086ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:33.163178Z","caller":"traceutil/trace.go:171","msg":"trace[2031167190] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:313; }","duration":"112.978072ms","start":"2026-02-25T02:10:33.050187Z","end":"2026-02-25T02:10:33.163165Z","steps":["trace[2031167190] 'agreement among raft nodes before linearized reading' (duration: 112.861029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:33.163224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.337107ms","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-02-25T02:10:33.16329Z","caller":"traceutil/trace.go:171","msg":"trace[103038246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:313; }","duration":"187.41976ms","start":"2026-02-25T02:10:32.975858Z","end":"2026-02-25T02:10:33.163278Z","steps":["trace[103038246] 'agreement among raft nodes before linearized reading' (duration: 187.323877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:33.163317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.142643ms","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-25T02:10:33.163353Z","caller":"traceutil/trace.go:171","msg":"trace[1854855200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:313; }","duration":"188.208155ms","start":"2026-02-25T02:10:32.975137Z","end":"2026-02-25T02:10:33.163345Z","steps":["trace[1854855200] 'agreement among raft nodes before linearized reading' (duration: 188.151983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:33.381773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.696959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:10:33.38208Z","caller":"traceutil/trace.go:171","msg":"trace[421297347] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"212.043643ms","start":"2026-02-25T02:10:33.16999Z","end":"2026-02-25T02:10:33.382033Z","steps":["trace[421297347] 'process raft request' (duration: 73.693094ms)","trace[421297347] 'compare' (duration: 137.43184ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:10:33.382188Z","caller":"traceutil/trace.go:171","msg":"trace[1125625795] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"211.930659ms","start":"2026-02-25T02:10:33.170236Z","end":"2026-02-25T02:10:33.382167Z","steps":["trace[1125625795] 'process raft request' (duration: 211.782464ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:33.382176Z","caller":"traceutil/trace.go:171","msg":"trace[393956722] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"212.123225ms","start":"2026-02-25T02:10:33.170009Z","end":"2026-02-25T02:10:33.382132Z","steps":["trace[393956722] 'process raft request' (duration: 211.930099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:33.382222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.259842ms","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-25T02:10:33.38227Z","caller":"traceutil/trace.go:171","msg":"trace[517533624] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:317; }","duration":"208.336275ms","start":"2026-02-25T02:10:33.173922Z","end":"2026-02-25T02:10:33.382258Z","steps":["trace[517533624] 'agreement among raft nodes before linearized reading' (duration: 208.243272ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:33.382121Z","caller":"traceutil/trace.go:171","msg":"trace[41433173] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:327; }","duration":"208.123668ms","start":"2026-02-25T02:10:33.173955Z","end":"2026-02-25T02:10:33.382079Z","steps":["trace[41433173] 'read index received' (duration: 69.747568ms)","trace[41433173] 'applied index is now lower than readState.Index' (duration: 138.37461ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:10:33.382917Z","caller":"traceutil/trace.go:171","msg":"trace[1511941341] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"208.868872ms","start":"2026-02-25T02:10:33.174025Z","end":"2026-02-25T02:10:33.382894Z","steps":["trace[1511941341] 'process raft request' (duration: 208.771879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:58.211621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.042037ms","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-02-25T02:10:58.211687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.89218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:10:58.211696Z","caller":"traceutil/trace.go:171","msg":"trace[919048209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"715.15074ms","start":"2026-02-25T02:10:57.496523Z","end":"2026-02-25T02:10:58.211674Z","steps":["trace[919048209] 'range keys from in-memory index tree' (duration: 714.934923ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:58.211739Z","caller":"traceutil/trace.go:171","msg":"trace[420548393] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:511; }","duration":"919.970153ms","start":"2026-02-25T02:10:57.291749Z","end":"2026-02-25T02:10:58.211719Z","steps":["trace[420548393] 'range keys from in-memory index tree' (duration: 919.809648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:58.21174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:57.496499Z","time spent":"715.232253ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:10:58.211786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:57.291729Z","time spent":"920.047665ms","remote":"127.0.0.1:51112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-02-25T02:10:58.211763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.43403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:10:58.211843Z","caller":"traceutil/trace.go:171","msg":"trace[1579264026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"782.525723ms","start":"2026-02-25T02:10:57.429297Z","end":"2026-02-25T02:10:58.211823Z","steps":["trace[1579264026] 'range keys from in-memory index tree' (duration: 782.312766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:58.211604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.818865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T02:10:58.211882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:57.42924Z","time spent":"782.633746ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:10:58.211926Z","caller":"traceutil/trace.go:171","msg":"trace[788021909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"707.196867ms","start":"2026-02-25T02:10:57.504706Z","end":"2026-02-25T02:10:58.211903Z","steps":["trace[788021909] 'range keys from in-memory index tree' (duration: 706.715232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:58.211982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:57.504686Z","time spent":"707.27918ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:10:58.212035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.419303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T02:10:58.21151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.750544ms","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-25T02:10:58.21212Z","caller":"traceutil/trace.go:171","msg":"trace[1423565557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"712.899278ms","start":"2026-02-25T02:10:57.499193Z","end":"2026-02-25T02:10:58.212092Z","steps":["trace[1423565557] 'range keys from in-memory index tree' (duration: 712.331901ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:58.212127Z","caller":"traceutil/trace.go:171","msg":"trace[1502654048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"930.422966ms","start":"2026-02-25T02:10:57.281684Z","end":"2026-02-25T02:10:58.212107Z","steps":["trace[1502654048] 'range keys from in-memory index tree' (duration: 929.609049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:58.212163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:57.499181Z","time spent":"712.972541ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:10:58.212175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:57.281664Z","time spent":"930.499367ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T02:11:04.555271Z","caller":"traceutil/trace.go:171","msg":"trace[1030586868] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"308.312364ms","start":"2026-02-25T02:11:04.246936Z","end":"2026-02-25T02:11:04.555249Z","steps":["trace[1030586868] 'read index received' (duration: 307.793987ms)","trace[1030586868] 'applied index is now lower than readState.Index' (duration: 517.477µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:04.555407Z","caller":"traceutil/trace.go:171","msg":"trace[1251355694] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"496.87532ms","start":"2026-02-25T02:11:04.058506Z","end":"2026-02-25T02:11:04.555381Z","steps":["trace[1251355694] 'process raft request' (duration: 496.272981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:04.55553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.206949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T02:11:04.555553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:04.058463Z","time spent":"497.004824ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:11:04.555555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.603383ms","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-02-25T02:11:04.555657Z","caller":"traceutil/trace.go:171","msg":"trace[199797679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"307.319762ms","start":"2026-02-25T02:11:04.248264Z","end":"2026-02-25T02:11:04.555584Z","steps":["trace[199797679] 'agreement among raft nodes before linearized reading' (duration: 307.184478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:04.55572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:04.24824Z","time spent":"307.468567ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:04.555725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.881091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T02:11:04.555769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.517685ms","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-25T02:11:04.555795Z","caller":"traceutil/trace.go:171","msg":"trace[1219705666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"302.999715ms","start":"2026-02-25T02:11:04.252781Z","end":"2026-02-25T02:11:04.55578Z","steps":["trace[1219705666] 'agreement among raft nodes before linearized reading' (duration: 302.8723ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:04.555819Z","caller":"traceutil/trace.go:171","msg":"trace[979706944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"305.607117ms","start":"2026-02-25T02:11:04.2502Z","end":"2026-02-25T02:11:04.555807Z","steps":["trace[979706944] 'agreement among raft nodes before linearized reading' (duration: 305.521945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:04.555857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:04.252761Z","time spent":"303.080387ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:11:04.555871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:04.25018Z","time spent":"305.68019ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:11:04.555657Z","caller":"traceutil/trace.go:171","msg":"trace[1056993686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"308.756468ms","start":"2026-02-25T02:11:04.246878Z","end":"2026-02-25T02:11:04.555635Z","steps":["trace[1056993686] 'agreement among raft nodes before linearized reading' (duration: 308.540851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:04.556089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:04.246858Z","time spent":"309.212593ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T02:11:04.691577Z","caller":"traceutil/trace.go:171","msg":"trace[280228312] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"132.556594ms","start":"2026-02-25T02:11:04.55899Z","end":"2026-02-25T02:11:04.691547Z","steps":["trace[280228312] 'process raft request' (duration: 132.367818ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:04.775899Z","caller":"traceutil/trace.go:171","msg":"trace[1677282858] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"214.419798ms","start":"2026-02-25T02:11:04.561454Z","end":"2026-02-25T02:11:04.775874Z","steps":["trace[1677282858] 'process raft request' (duration: 214.359866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:04.775994Z","caller":"traceutil/trace.go:171","msg":"trace[789946134] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"215.087909ms","start":"2026-02-25T02:11:04.560879Z","end":"2026-02-25T02:11:04.775967Z","steps":["trace[789946134] 'process raft request' (duration: 214.82436ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:04.776026Z","caller":"traceutil/trace.go:171","msg":"trace[750307209] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"215.470752ms","start":"2026-02-25T02:11:04.560519Z","end":"2026-02-25T02:11:04.775989Z","steps":["trace[750307209] 'process raft request' (duration: 145.28951ms)","trace[750307209] 'compare' (duration: 69.650655ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:07.700306Z","caller":"traceutil/trace.go:171","msg":"trace[1367753349] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:553; }","duration":"920.155939ms","start":"2026-02-25T02:11:06.780121Z","end":"2026-02-25T02:11:07.700277Z","steps":["trace[1367753349] 'read index received' (duration: 225.037µs)","trace[1367753349] 'applied index is now lower than readState.Index' (duration: 919.929722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:07.700603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.134929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T02:11:07.700614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.215274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:07.700651Z","caller":"traceutil/trace.go:171","msg":"trace[1085372380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"920.222191ms","start":"2026-02-25T02:11:06.780416Z","end":"2026-02-25T02:11:07.700638Z","steps":["trace[1085372380] 'agreement among raft nodes before linearized reading' (duration: 920.126638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:07.700664Z","caller":"traceutil/trace.go:171","msg":"trace[709080216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"271.304327ms","start":"2026-02-25T02:11:07.429346Z","end":"2026-02-25T02:11:07.70065Z","steps":["trace[709080216] 'agreement among raft nodes before linearized reading' (duration: 271.212384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:07.70066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.115036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T02:11:07.700658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.122048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:11:07.700691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:06.780388Z","time spent":"920.292754ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T02:11:07.700713Z","caller":"traceutil/trace.go:171","msg":"trace[1932317016] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:535; }","duration":"408.21377ms","start":"2026-02-25T02:11:07.292483Z","end":"2026-02-25T02:11:07.700697Z","steps":["trace[1932317016] 'agreement among raft nodes before linearized reading' (duration: 408.095886ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:07.700719Z","caller":"traceutil/trace.go:171","msg":"trace[1024118768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"920.20138ms","start":"2026-02-25T02:11:06.780503Z","end":"2026-02-25T02:11:07.700704Z","steps":["trace[1024118768] 'agreement among raft nodes before linearized reading' (duration: 920.111428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:07.700759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:07.292456Z","time spent":"408.287683ms","remote":"127.0.0.1:51112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-02-25T02:11:07.700757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:06.780493Z","time spent":"920.253962ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:11:07.700893Z","caller":"traceutil/trace.go:171","msg":"trace[1912810539] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"953.416307ms","start":"2026-02-25T02:11:06.74746Z","end":"2026-02-25T02:11:07.700876Z","steps":["trace[1912810539] 'process raft request' (duration: 952.682884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:07.70098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.854471ms","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-25T02:11:07.701025Z","caller":"traceutil/trace.go:171","msg":"trace[1863374039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"920.931093ms","start":"2026-02-25T02:11:06.780084Z","end":"2026-02-25T02:11:07.701015Z","steps":["trace[1863374039] 'agreement among raft nodes before linearized reading' (duration: 920.863011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:07.701054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:06.780067Z","time spent":"920.980925ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:07.701044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:06.747433Z","time spent":"953.49638ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:09.012896Z","caller":"traceutil/trace.go:171","msg":"trace[1241780829] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"224.910822ms","start":"2026-02-25T02:11:08.787954Z","end":"2026-02-25T02:11:09.012865Z","steps":["trace[1241780829] 'process raft request' (duration: 224.599772ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:19.024564Z","caller":"traceutil/trace.go:171","msg":"trace[1917654699] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"212.505797ms","start":"2026-02-25T02:11:18.812032Z","end":"2026-02-25T02:11:19.024538Z","steps":["trace[1917654699] 'read index received' (duration: 212.233188ms)","trace[1917654699] 'applied index is now lower than readState.Index' (duration: 271.749µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:19.024747Z","caller":"traceutil/trace.go:171","msg":"trace[325689868] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"409.615106ms","start":"2026-02-25T02:11:18.615067Z","end":"2026-02-25T02:11:19.024683Z","steps":["trace[325689868] 'process raft request' (duration: 409.254704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:19.024813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.763756ms","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-02-25T02:11:19.024865Z","caller":"traceutil/trace.go:171","msg":"trace[1025844758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:658; }","duration":"212.877129ms","start":"2026-02-25T02:11:18.811973Z","end":"2026-02-25T02:11:19.02485Z","steps":["trace[1025844758] 'agreement among raft nodes before linearized reading' (duration: 212.667752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:19.02494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:18.615042Z","time spent":"409.7882ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:24.757624Z","caller":"traceutil/trace.go:171","msg":"trace[211030823] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"514.476543ms","start":"2026-02-25T02:11:24.243127Z","end":"2026-02-25T02:11:24.757603Z","steps":["trace[211030823] 'process raft request' (duration: 514.35521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:24.757772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:24.243092Z","time spent":"514.627787ms","remote":"127.0.0.1:51152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:11:25.346913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.190969ms","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-25T02:11:25.347001Z","caller":"traceutil/trace.go:171","msg":"trace[384943707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:716; }","duration":"292.323202ms","start":"2026-02-25T02:11:25.054659Z","end":"2026-02-25T02:11:25.346982Z","steps":["trace[384943707] 'range keys from in-memory index tree' (duration: 292.011315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:25.347365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.245877ms","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-25T02:11:25.347445Z","caller":"traceutil/trace.go:171","msg":"trace[1620211499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:716; }","duration":"254.37691ms","start":"2026-02-25T02:11:25.093048Z","end":"2026-02-25T02:11:25.347425Z","steps":["trace[1620211499] 'range keys from in-memory index tree' (duration: 253.95623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:25.927807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314124035195105,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:11:26.166332Z","caller":"traceutil/trace.go:171","msg":"trace[505395347] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"814.299642ms","start":"2026-02-25T02:11:25.352011Z","end":"2026-02-25T02:11:26.16631Z","steps":["trace[505395347] 'process raft request' (duration: 814.174249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:26.166487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.351961Z","time spent":"814.455485ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:26.320828Z","caller":"traceutil/trace.go:171","msg":"trace[887898142] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"967.819851ms","start":"2026-02-25T02:11:25.352977Z","end":"2026-02-25T02:11:26.320797Z","steps":["trace[887898142] 'process raft request' (duration: 955.197795ms)","trace[887898142] 'compare' (duration: 12.297009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:26.32105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.35294Z","time spent":"968.017185ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:26.321252Z","caller":"traceutil/trace.go:171","msg":"trace[1790602514] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"893.277048ms","start":"2026-02-25T02:11:25.427432Z","end":"2026-02-25T02:11:26.320709Z","steps":["trace[1790602514] 'read index received' (duration: 739.256827ms)","trace[1790602514] 'applied index is now lower than readState.Index' (duration: 154.018671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:26.32137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.918043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:11:26.321456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.850563ms","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-02-25T02:11:26.321481Z","caller":"traceutil/trace.go:171","msg":"trace[2016134861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"894.038257ms","start":"2026-02-25T02:11:25.427417Z","end":"2026-02-25T02:11:26.321455Z","steps":["trace[2016134861] 'agreement among raft nodes before linearized reading' (duration: 893.871643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:26.321496Z","caller":"traceutil/trace.go:171","msg":"trace[899496887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"534.945514ms","start":"2026-02-25T02:11:25.786537Z","end":"2026-02-25T02:11:26.321483Z","steps":["trace[899496887] 'agreement among raft nodes before linearized reading' (duration: 534.838332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:26.321502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.962754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:11:26.321547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.786517Z","time spent":"535.022057ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:26.321549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.427329Z","time spent":"894.205531ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:11:26.321559Z","caller":"traceutil/trace.go:171","msg":"trace[1399028046] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:718; }","duration":"124.067267ms","start":"2026-02-25T02:11:26.197474Z","end":"2026-02-25T02:11:26.321542Z","steps":["trace[1399028046] 'agreement among raft nodes before linearized reading' (duration: 123.976105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:26.321568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.586859ms","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-25T02:11:26.321621Z","caller":"traceutil/trace.go:171","msg":"trace[919829185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"526.64109ms","start":"2026-02-25T02:11:25.794966Z","end":"2026-02-25T02:11:26.321607Z","steps":["trace[919829185] 'agreement among raft nodes before linearized reading' (duration: 526.543118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:26.321655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.79489Z","time spent":"526.756552ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:26.321739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.060808ms","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-02-25T02:11:26.32182Z","caller":"traceutil/trace.go:171","msg":"trace[1477856247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"535.192821ms","start":"2026-02-25T02:11:25.786606Z","end":"2026-02-25T02:11:26.321799Z","steps":["trace[1477856247] 'agreement among raft nodes before linearized reading' (duration: 534.933415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:26.3219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.786585Z","time spent":"535.288773ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:11:26.544382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.608014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:26.544485Z","caller":"traceutil/trace.go:171","msg":"trace[267018493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"114.755288ms","start":"2026-02-25T02:11:26.429703Z","end":"2026-02-25T02:11:26.544458Z","steps":["trace[267018493] 'range keys from in-memory index tree' (duration: 114.516162ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:27.537261Z","caller":"traceutil/trace.go:171","msg":"trace[1477466388] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"107.21497ms","start":"2026-02-25T02:11:27.430018Z","end":"2026-02-25T02:11:27.537233Z","steps":["trace[1477466388] 'read index received' (duration: 106.931263ms)","trace[1477466388] 'applied index is now lower than readState.Index' (duration: 280.947µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:27.537393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.349743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:27.537428Z","caller":"traceutil/trace.go:171","msg":"trace[1499701366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"107.406344ms","start":"2026-02-25T02:11:27.430012Z","end":"2026-02-25T02:11:27.537418Z","steps":["trace[1499701366] 'agreement among raft nodes before linearized reading' (duration: 107.320632ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:50.475542Z","caller":"traceutil/trace.go:171","msg":"trace[1227994112] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"169.057627ms","start":"2026-02-25T02:11:50.306434Z","end":"2026-02-25T02:11:50.475492Z","steps":["trace[1227994112] 'process raft request' (duration: 168.660604ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:50.915755Z","caller":"traceutil/trace.go:171","msg":"trace[431132143] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"435.697499ms","start":"2026-02-25T02:11:50.480028Z","end":"2026-02-25T02:11:50.915725Z","steps":["trace[431132143] 'process raft request' (duration: 435.432141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:50.915923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:50.480002Z","time spent":"435.840384ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:50.926164Z","caller":"traceutil/trace.go:171","msg":"trace[978401629] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"432.421756ms","start":"2026-02-25T02:11:50.493718Z","end":"2026-02-25T02:11:50.926139Z","steps":["trace[978401629] 'read index received' (duration: 421.793341ms)","trace[978401629] 'applied index is now lower than readState.Index' (duration: 10.627435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:50.926512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.7829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T02:11:50.926519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.791627ms","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-02-25T02:11:50.926557Z","caller":"traceutil/trace.go:171","msg":"trace[418953913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"427.856242ms","start":"2026-02-25T02:11:50.498688Z","end":"2026-02-25T02:11:50.926545Z","steps":["trace[418953913] 'agreement among raft nodes before linearized reading' (duration: 427.707997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:50.926587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:50.498676Z","time spent":"427.904664ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:11:50.92659Z","caller":"traceutil/trace.go:171","msg":"trace[1806392654] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"446.407407ms","start":"2026-02-25T02:11:50.480146Z","end":"2026-02-25T02:11:50.926553Z","steps":["trace[1806392654] 'process raft request' (duration: 445.87311ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:50.926693Z","caller":"traceutil/trace.go:171","msg":"trace[1650216415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"432.912241ms","start":"2026-02-25T02:11:50.493652Z","end":"2026-02-25T02:11:50.926564Z","steps":["trace[1650216415] 'agreement among raft nodes before linearized reading' (duration: 432.691644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:50.926781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:50.493627Z","time spent":"433.143439ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:50.927135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:50.480109Z","time spent":"446.640874ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:11:52.672618Z","caller":"traceutil/trace.go:171","msg":"trace[244225898] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"188.538971ms","start":"2026-02-25T02:11:52.484057Z","end":"2026-02-25T02:11:52.672596Z","steps":["trace[244225898] 'process raft request' (duration: 188.410787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:53.401839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.132224ms","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-25T02:11:53.401912Z","caller":"traceutil/trace.go:171","msg":"trace[1405687614] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:796; }","duration":"185.216307ms","start":"2026-02-25T02:11:53.216685Z","end":"2026-02-25T02:11:53.401901Z","steps":["trace[1405687614] 'range keys from in-memory index tree' (duration: 185.119843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:53.434892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.73488ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:11:53.434986Z","caller":"traceutil/trace.go:171","msg":"trace[535812494] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"514.832862ms","start":"2026-02-25T02:11:52.920138Z","end":"2026-02-25T02:11:53.434971Z","steps":["trace[535812494] 'read index received' (duration: 164.937817ms)","trace[535812494] 'applied index is now lower than readState.Index' (duration: 349.893935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:53.435161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.027361ms","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-02-25T02:11:53.43519Z","caller":"traceutil/trace.go:171","msg":"trace[1492359746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"504.094964ms","start":"2026-02-25T02:11:52.931085Z","end":"2026-02-25T02:11:53.43518Z","steps":["trace[1492359746] 'agreement among raft nodes before linearized reading' (duration: 503.97146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:53.435215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:52.931059Z","time spent":"504.149725ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:11:53.435251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.076259ms","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-02-25T02:11:53.435331Z","caller":"traceutil/trace.go:171","msg":"trace[1251976611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"515.216844ms","start":"2026-02-25T02:11:52.920096Z","end":"2026-02-25T02:11:53.435313Z","steps":["trace[1251976611] 'agreement among raft nodes before linearized reading' (duration: 514.931295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:53.435388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:52.920076Z","time spent":"515.299617ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:53.435468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.767774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:53.4355Z","caller":"traceutil/trace.go:171","msg":"trace[1448982791] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:796; }","duration":"411.821716ms","start":"2026-02-25T02:11:53.023671Z","end":"2026-02-25T02:11:53.435492Z","steps":["trace[1448982791] 'agreement among raft nodes before linearized reading' (duration: 411.768655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:53.435521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:53.02366Z","time spent":"411.855247ms","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:11:53.435611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.139936ms","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-02-25T02:11:53.435679Z","caller":"traceutil/trace.go:171","msg":"trace[725312550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"412.24899ms","start":"2026-02-25T02:11:53.023415Z","end":"2026-02-25T02:11:53.435664Z","steps":["trace[725312550] 'agreement among raft nodes before linearized reading' (duration: 412.039613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:53.435716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:53.023396Z","time spent":"412.312841ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:11:53.435764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.316811ms","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-25T02:11:53.435821Z","caller":"traceutil/trace.go:171","msg":"trace[1396187657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"412.419865ms","start":"2026-02-25T02:11:53.023387Z","end":"2026-02-25T02:11:53.435807Z","steps":["trace[1396187657] 'agreement among raft nodes before linearized reading' (duration: 412.223799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:53.435857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:53.023348Z","time spent":"412.501678ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:11:53.557466Z","caller":"traceutil/trace.go:171","msg":"trace[1575910572] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"117.355228ms","start":"2026-02-25T02:11:53.440084Z","end":"2026-02-25T02:11:53.557439Z","steps":["trace[1575910572] 'process raft request' (duration: 117.274206ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:53.557501Z","caller":"traceutil/trace.go:171","msg":"trace[1757720139] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"117.826053ms","start":"2026-02-25T02:11:53.439638Z","end":"2026-02-25T02:11:53.557464Z","steps":["trace[1757720139] 'process raft request' (duration: 87.655922ms)","trace[1757720139] 'compare' (duration: 29.82855ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:53.557813Z","caller":"traceutil/trace.go:171","msg":"trace[375326194] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"117.652998ms","start":"2026-02-25T02:11:53.440149Z","end":"2026-02-25T02:11:53.557802Z","steps":["trace[375326194] 'process raft request' (duration: 117.252965ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:04.114441Z","caller":"traceutil/trace.go:171","msg":"trace[758531601] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"162.367306ms","start":"2026-02-25T02:12:03.952047Z","end":"2026-02-25T02:12:04.114415Z","steps":["trace[758531601] 'process raft request' (duration: 162.238112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:07.673663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.30744ms","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-25T02:12:07.673744Z","caller":"traceutil/trace.go:171","msg":"trace[1478815974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"947.425484ms","start":"2026-02-25T02:12:06.7263Z","end":"2026-02-25T02:12:07.673725Z","steps":["trace[1478815974] 'range keys from in-memory index tree' (duration: 947.049922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:07.673782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:06.726277Z","time spent":"947.495987ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:12:07.673927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.840083ms","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-02-25T02:12:07.674014Z","caller":"traceutil/trace.go:171","msg":"trace[1600795080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"137.969827ms","start":"2026-02-25T02:12:07.536021Z","end":"2026-02-25T02:12:07.673991Z","steps":["trace[1600795080] 'range keys from in-memory index tree' (duration: 137.651338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:07.67418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.315871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:12:07.674233Z","caller":"traceutil/trace.go:171","msg":"trace[2042003850] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:886; }","duration":"378.391892ms","start":"2026-02-25T02:12:07.295826Z","end":"2026-02-25T02:12:07.674218Z","steps":["trace[2042003850] 'range keys from in-memory index tree' (duration: 377.171395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:07.674262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:07.295811Z","time spent":"378.444035ms","remote":"127.0.0.1:51112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-02-25T02:12:07.673546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.072179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:07.674486Z","caller":"traceutil/trace.go:171","msg":"trace[1989582383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"244.047219ms","start":"2026-02-25T02:12:07.430417Z","end":"2026-02-25T02:12:07.674464Z","steps":["trace[1989582383] 'range keys from in-memory index tree' (duration: 242.977855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:23.976657Z","caller":"traceutil/trace.go:171","msg":"trace[1433823065] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"163.837073ms","start":"2026-02-25T02:12:23.812751Z","end":"2026-02-25T02:12:23.976588Z","steps":["trace[1433823065] 'process raft request' (duration: 86.807126ms)","trace[1433823065] 'compare' (duration: 76.704967ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:23.976854Z","caller":"traceutil/trace.go:171","msg":"trace[829501310] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:992; }","duration":"148.760338ms","start":"2026-02-25T02:12:23.828071Z","end":"2026-02-25T02:12:23.976832Z","steps":["trace[829501310] 'read index received' (duration: 71.498143ms)","trace[829501310] 'applied index is now lower than readState.Index' (duration: 77.261235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:23.977022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.939343ms","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-25T02:12:23.977053Z","caller":"traceutil/trace.go:171","msg":"trace[1441863229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"149.016706ms","start":"2026-02-25T02:12:23.828025Z","end":"2026-02-25T02:12:23.977042Z","steps":["trace[1441863229] 'agreement among raft nodes before linearized reading' (duration: 148.881831ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:23.977179Z","caller":"traceutil/trace.go:171","msg":"trace[903026665] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"163.942576ms","start":"2026-02-25T02:12:23.813223Z","end":"2026-02-25T02:12:23.977166Z","steps":["trace[903026665] 'process raft request' (duration: 163.273355ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:23.977229Z","caller":"traceutil/trace.go:171","msg":"trace[575172092] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"163.491442ms","start":"2026-02-25T02:12:23.813727Z","end":"2026-02-25T02:12:23.977219Z","steps":["trace[575172092] 'process raft request' (duration: 163.012407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:24.931515Z","caller":"traceutil/trace.go:171","msg":"trace[1974345657] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"235.090058ms","start":"2026-02-25T02:12:24.696403Z","end":"2026-02-25T02:12:24.931493Z","steps":["trace[1974345657] 'process raft request' (duration: 234.937653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:25.844169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.386759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:12:25.844229Z","caller":"traceutil/trace.go:171","msg":"trace[848551093] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:957; }","duration":"664.496202ms","start":"2026-02-25T02:12:25.179721Z","end":"2026-02-25T02:12:25.844218Z","steps":["trace[848551093] 'count revisions from in-memory index tree' (duration: 664.298536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:25.844254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:25.179701Z","time spent":"664.547354ms","remote":"127.0.0.1:51552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:12:26.383282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.447129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:26.383478Z","caller":"traceutil/trace.go:171","msg":"trace[561088507] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:998; }","duration":"369.020634ms","start":"2026-02-25T02:12:26.014426Z","end":"2026-02-25T02:12:26.383447Z","steps":["trace[561088507] 'read index received' (duration: 51.497616ms)","trace[561088507] 'applied index is now lower than readState.Index' (duration: 317.522018ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:26.383567Z","caller":"traceutil/trace.go:171","msg":"trace[197346228] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"387.045696ms","start":"2026-02-25T02:12:25.99649Z","end":"2026-02-25T02:12:26.383535Z","steps":["trace[197346228] 'process raft request' (duration: 386.895022ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:26.383532Z","caller":"traceutil/trace.go:171","msg":"trace[228034371] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"387.247852ms","start":"2026-02-25T02:12:25.99621Z","end":"2026-02-25T02:12:26.383458Z","steps":["trace[228034371] 'process raft request' (duration: 107.496704ms)","trace[228034371] 'compare' (duration: 279.1851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:26.383611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.179019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T02:12:26.383749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:25.996483Z","time spent":"387.17078ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:26.383758Z","caller":"traceutil/trace.go:171","msg":"trace[843917027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"369.368835ms","start":"2026-02-25T02:12:26.01437Z","end":"2026-02-25T02:12:26.383739Z","steps":["trace[843917027] 'agreement among raft nodes before linearized reading' (duration: 369.144988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:26.383775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:25.996196Z","time spent":"387.46883ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:26.383822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:26.014359Z","time spent":"369.439227ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:12:28.031718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314124035195722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:12:28.303583Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.005346938s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:12:28.303898Z","caller":"traceutil/trace.go:171","msg":"trace[1985665750] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"772.40586ms","start":"2026-02-25T02:12:27.531475Z","end":"2026-02-25T02:12:28.30388Z","steps":["trace[1985665750] 'read index received' (duration: 772.260886ms)","trace[1985665750] 'applied index is now lower than readState.Index' (duration: 143.794µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:28.303922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:27.298097Z","time spent":"1.005820492s","remote":"127.0.0.1:51112","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:12:28.304018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.522604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:28.304046Z","caller":"traceutil/trace.go:171","msg":"trace[1228166710] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:962; }","duration":"772.597126ms","start":"2026-02-25T02:12:27.531441Z","end":"2026-02-25T02:12:28.304038Z","steps":["trace[1228166710] 'agreement among raft nodes before linearized reading' (duration: 772.528884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:28.304072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:27.531426Z","time spent":"772.639317ms","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:12:28.304124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.186278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:28.30419Z","caller":"traceutil/trace.go:171","msg":"trace[375455092] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:962; }","duration":"185.272851ms","start":"2026-02-25T02:12:28.118891Z","end":"2026-02-25T02:12:28.304164Z","steps":["trace[375455092] 'agreement among raft nodes before linearized reading' (duration: 185.177218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:28.304216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.539106ms","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-02-25T02:12:28.304279Z","caller":"traceutil/trace.go:171","msg":"trace[604637731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"232.639589ms","start":"2026-02-25T02:12:28.071618Z","end":"2026-02-25T02:12:28.304258Z","steps":["trace[604637731] 'agreement among raft nodes before linearized reading' (duration: 232.446083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:28.30461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.493256ms","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-25T02:12:28.304637Z","caller":"traceutil/trace.go:171","msg":"trace[1591238517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"433.557428ms","start":"2026-02-25T02:12:27.871072Z","end":"2026-02-25T02:12:28.30463Z","steps":["trace[1591238517] 'agreement among raft nodes before linearized reading' (duration: 433.452094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:28.304657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:27.871055Z","time spent":"433.597028ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T02:12:28.486636Z","caller":"traceutil/trace.go:171","msg":"trace[1999245559] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"180.687288ms","start":"2026-02-25T02:12:28.305922Z","end":"2026-02-25T02:12:28.48661Z","steps":["trace[1999245559] 'read index received' (duration: 110.815318ms)","trace[1999245559] 'applied index is now lower than readState.Index' (duration: 69.8705ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:28.486647Z","caller":"traceutil/trace.go:171","msg":"trace[1898933625] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"181.133522ms","start":"2026-02-25T02:12:28.305483Z","end":"2026-02-25T02:12:28.486616Z","steps":["trace[1898933625] 'process raft request' (duration: 111.311243ms)","trace[1898933625] 'compare' (duration: 69.706655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:28.486813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.857103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:28.486993Z","caller":"traceutil/trace.go:171","msg":"trace[1464086062] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:963; }","duration":"181.07387ms","start":"2026-02-25T02:12:28.305897Z","end":"2026-02-25T02:12:28.486971Z","steps":["trace[1464086062] 'agreement among raft nodes before linearized reading' (duration: 180.808572ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:28.544096Z","caller":"traceutil/trace.go:171","msg":"trace[481021263] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"234.89734ms","start":"2026-02-25T02:12:28.309171Z","end":"2026-02-25T02:12:28.544069Z","steps":["trace[481021263] 'process raft request' (duration: 234.703944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:28.544447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.044039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T02:12:28.544471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.631177ms","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-02-25T02:12:28.544509Z","caller":"traceutil/trace.go:171","msg":"trace[1751220581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"156.142052ms","start":"2026-02-25T02:12:28.388351Z","end":"2026-02-25T02:12:28.544493Z","steps":["trace[1751220581] 'agreement among raft nodes before linearized reading' (duration: 155.963286ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:28.544542Z","caller":"traceutil/trace.go:171","msg":"trace[970120645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"156.756551ms","start":"2026-02-25T02:12:28.387766Z","end":"2026-02-25T02:12:28.544523Z","steps":["trace[970120645] 'agreement among raft nodes before linearized reading' (duration: 156.582425ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:28.544785Z","caller":"traceutil/trace.go:171","msg":"trace[469937728] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"235.026304ms","start":"2026-02-25T02:12:28.309749Z","end":"2026-02-25T02:12:28.544775Z","steps":["trace[469937728] 'process raft request' (duration: 234.27375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:28.544863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.477015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:28.544934Z","caller":"traceutil/trace.go:171","msg":"trace[114532930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"116.573247ms","start":"2026-02-25T02:12:28.428344Z","end":"2026-02-25T02:12:28.544918Z","steps":["trace[114532930] 'agreement among raft nodes before linearized reading' (duration: 116.476734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:28.797515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.574455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:28.79772Z","caller":"traceutil/trace.go:171","msg":"trace[1059552303] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1006; }","duration":"246.390787ms","start":"2026-02-25T02:12:28.551309Z","end":"2026-02-25T02:12:28.7977Z","steps":["trace[1059552303] 'read index received' (duration: 81.526243ms)","trace[1059552303] 'applied index is now lower than readState.Index' (duration: 164.863824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:28.797816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.501122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T02:12:28.797853Z","caller":"traceutil/trace.go:171","msg":"trace[2082046611] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:968; }","duration":"246.563563ms","start":"2026-02-25T02:12:28.55128Z","end":"2026-02-25T02:12:28.797843Z","steps":["trace[2082046611] 'agreement among raft nodes before linearized reading' (duration: 246.47954ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:28.79818Z","caller":"traceutil/trace.go:171","msg":"trace[917564896] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"248.647929ms","start":"2026-02-25T02:12:28.549478Z","end":"2026-02-25T02:12:28.798126Z","steps":["trace[917564896] 'process raft request' (duration: 83.347151ms)","trace[917564896] 'compare' (duration: 164.452471ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:28.798175Z","caller":"traceutil/trace.go:171","msg":"trace[420514929] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"248.515794ms","start":"2026-02-25T02:12:28.549592Z","end":"2026-02-25T02:12:28.798108Z","steps":["trace[420514929] 'process raft request' (duration: 248.0765ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:28.798511Z","caller":"traceutil/trace.go:171","msg":"trace[342546297] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"248.995949ms","start":"2026-02-25T02:12:28.549473Z","end":"2026-02-25T02:12:28.798469Z","steps":["trace[342546297] 'process raft request' (duration: 248.138793ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:30.032876Z","caller":"traceutil/trace.go:171","msg":"trace[1539210599] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"268.413774ms","start":"2026-02-25T02:12:29.763895Z","end":"2026-02-25T02:12:30.032309Z","steps":["trace[1539210599] 'process raft request' (duration: 268.125875ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:30.747097Z","caller":"traceutil/trace.go:171","msg":"trace[1837276980] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"194.437887ms","start":"2026-02-25T02:12:30.552635Z","end":"2026-02-25T02:12:30.747073Z","steps":["trace[1837276980] 'process raft request' (duration: 185.699184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:31.450965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.123553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:31.451174Z","caller":"traceutil/trace.go:171","msg":"trace[1385009620] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1013; }","duration":"499.011468ms","start":"2026-02-25T02:12:30.952146Z","end":"2026-02-25T02:12:31.451158Z","steps":["trace[1385009620] 'read index received' (duration: 324.615107ms)","trace[1385009620] 'applied index is now lower than readState.Index' (duration: 174.395431ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:31.451218Z","caller":"traceutil/trace.go:171","msg":"trace[1247735086] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"607.604996ms","start":"2026-02-25T02:12:30.843588Z","end":"2026-02-25T02:12:31.451193Z","steps":["trace[1247735086] 'process raft request' (duration: 607.537204ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:31.45122Z","caller":"traceutil/trace.go:171","msg":"trace[692889948] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"607.960977ms","start":"2026-02-25T02:12:30.843199Z","end":"2026-02-25T02:12:31.45116Z","steps":["trace[692889948] 'process raft request' (duration: 433.540265ms)","trace[692889948] 'compare' (duration: 174.02007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:31.451335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.185004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:12:31.451347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:30.843561Z","time spent":"607.71674ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:31.451379Z","caller":"traceutil/trace.go:171","msg":"trace[1842512865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"499.266757ms","start":"2026-02-25T02:12:30.9521Z","end":"2026-02-25T02:12:31.451367Z","steps":["trace[1842512865] 'agreement among raft nodes before linearized reading' (duration: 499.154713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:31.451415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:30.952081Z","time spent":"499.325428ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:12:31.451355Z","caller":"traceutil/trace.go:171","msg":"trace[2070327689] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"607.666858ms","start":"2026-02-25T02:12:30.843531Z","end":"2026-02-25T02:12:31.451198Z","steps":["trace[2070327689] 'process raft request' (duration: 607.545765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:31.45154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:30.843177Z","time spent":"608.156314ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:31.451577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:30.843518Z","time spent":"607.984648ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:50.585309Z","caller":"traceutil/trace.go:171","msg":"trace[1716111431] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"319.738055ms","start":"2026-02-25T02:12:50.265544Z","end":"2026-02-25T02:12:50.585283Z","steps":["trace[1716111431] 'read index received' (duration: 319.423545ms)","trace[1716111431] 'applied index is now lower than readState.Index' (duration: 310.89µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:50.585392Z","caller":"traceutil/trace.go:171","msg":"trace[884322324] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"426.353571ms","start":"2026-02-25T02:12:50.15899Z","end":"2026-02-25T02:12:50.585343Z","steps":["trace[884322324] 'process raft request' (duration: 426.108483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:50.585645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.289256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:50.585796Z","caller":"traceutil/trace.go:171","msg":"trace[1297820672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"156.491522ms","start":"2026-02-25T02:12:50.429282Z","end":"2026-02-25T02:12:50.585774Z","steps":["trace[1297820672] 'agreement among raft nodes before linearized reading' (duration: 156.300556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:50.585687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:50.158964Z","time spent":"426.550117ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:50.585702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.050524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-25T02:12:50.586201Z","caller":"traceutil/trace.go:171","msg":"trace[1397717401] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1074; }","duration":"320.639572ms","start":"2026-02-25T02:12:50.265493Z","end":"2026-02-25T02:12:50.586132Z","steps":["trace[1397717401] 'agreement among raft nodes before linearized reading' (duration: 319.944631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:50.586274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:50.265474Z","time spent":"320.781637ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T02:12:57.714597Z","caller":"traceutil/trace.go:171","msg":"trace[1938551307] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"104.576023ms","start":"2026-02-25T02:12:57.609988Z","end":"2026-02-25T02:12:57.714564Z","steps":["trace[1938551307] 'process raft request' (duration: 104.298414ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:57.807721Z","caller":"traceutil/trace.go:171","msg":"trace[43843875] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"195.615834ms","start":"2026-02-25T02:12:57.61205Z","end":"2026-02-25T02:12:57.807665Z","steps":["trace[43843875] 'process raft request' (duration: 195.558502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:57.807836Z","caller":"traceutil/trace.go:171","msg":"trace[923546006] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"196.354527ms","start":"2026-02-25T02:12:57.611463Z","end":"2026-02-25T02:12:57.807818Z","steps":["trace[923546006] 'process raft request' (duration: 124.178175ms)","trace[923546006] 'compare' (duration: 71.753288ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:44.068988Z","caller":"traceutil/trace.go:171","msg":"trace[1102470398] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"144.026674ms","start":"2026-02-25T02:13:43.924942Z","end":"2026-02-25T02:13:44.068969Z","steps":["trace[1102470398] 'process raft request' (duration: 143.869259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:45.516711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.927291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:13:45.516839Z","caller":"traceutil/trace.go:171","msg":"trace[1592215463] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"334.153426ms","start":"2026-02-25T02:13:45.182665Z","end":"2026-02-25T02:13:45.516818Z","steps":["trace[1592215463] 'read index received' (duration: 180.960106ms)","trace[1592215463] 'applied index is now lower than readState.Index' (duration: 153.19158ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:45.516896Z","caller":"traceutil/trace.go:171","msg":"trace[139190872] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"334.638721ms","start":"2026-02-25T02:13:45.182212Z","end":"2026-02-25T02:13:45.516851Z","steps":["trace[139190872] 'process raft request' (duration: 181.462622ms)","trace[139190872] 'compare' (duration: 152.298592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:45.516976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.3005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:13:45.517101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.612389ms","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-25T02:13:45.517084Z","caller":"traceutil/trace.go:171","msg":"trace[1937651725] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1298; }","duration":"334.434765ms","start":"2026-02-25T02:13:45.182633Z","end":"2026-02-25T02:13:45.517068Z","steps":["trace[1937651725] 'agreement among raft nodes before linearized reading' (duration: 334.29255ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:45.517163Z","caller":"traceutil/trace.go:171","msg":"trace[16496438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"333.693381ms","start":"2026-02-25T02:13:45.183455Z","end":"2026-02-25T02:13:45.517148Z","steps":["trace[16496438] 'agreement among raft nodes before linearized reading' (duration: 333.506625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:45.517196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:45.183451Z","time spent":"333.736982ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:13:45.517199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:45.182615Z","time spent":"334.545798ms","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:13:45.517414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:45.182193Z","time spent":"334.802195ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:32.338285Z","caller":"traceutil/trace.go:171","msg":"trace[325340167] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"107.67722ms","start":"2026-02-25T02:14:32.230583Z","end":"2026-02-25T02:14:32.338261Z","steps":["trace[325340167] 'process raft request' (duration: 107.204915ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:40.980933Z","caller":"traceutil/trace.go:171","msg":"trace[2086331267] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"232.233688ms","start":"2026-02-25T02:15:40.748655Z","end":"2026-02-25T02:15:40.980889Z","steps":["trace[2086331267] 'process raft request' (duration: 232.038252ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:47.150132Z","caller":"traceutil/trace.go:171","msg":"trace[973242446] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"112.09607ms","start":"2026-02-25T02:15:47.038003Z","end":"2026-02-25T02:15:47.150099Z","steps":["trace[973242446] 'process raft request' (duration: 89.139377ms)","trace[973242446] 'compare' (duration: 22.805528ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:47.630664Z","caller":"traceutil/trace.go:171","msg":"trace[1450187174] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"152.419969ms","start":"2026-02-25T02:15:47.478215Z","end":"2026-02-25T02:15:47.630635Z","steps":["trace[1450187174] 'process raft request' (duration: 152.252114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:47.989342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.313807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-25T02:15:47.989414Z","caller":"traceutil/trace.go:171","msg":"trace[844851167] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1828; }","duration":"233.440571ms","start":"2026-02-25T02:15:47.755955Z","end":"2026-02-25T02:15:47.989396Z","steps":["trace[844851167] 'range keys from in-memory index tree' (duration: 233.137402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:47.989517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.150682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-25T02:15:47.989647Z","caller":"traceutil/trace.go:171","msg":"trace[1142524454] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1828; }","duration":"186.287236ms","start":"2026-02-25T02:15:47.803318Z","end":"2026-02-25T02:15:47.989605Z","steps":["trace[1142524454] 'range keys from in-memory index tree' (duration: 185.77717ms)"],"step_count":1}