{"level":"warn","ts":"2026-04-23T06:41:14.558537Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T06:41:14.558623Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T06:41:14.558692Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T06:41:14.558705Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-23T06:41:14.558727Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T06:41:14.559418Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-04-23T06:41:14.55949Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T06:41:14.715189Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"155.501738ms"} {"level":"info","ts":"2026-04-23T06:41:14.768018Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-04-23T06:41:14.768168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T06:41:14.768196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-04-23T06:41:14.76821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T06:41:14.768235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-04-23T06:41:14.768288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-04-23T06:41:14.789022Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T06:41:14.791668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T06:41:14.794454Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T06:41:14.79702Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T06:41:14.799069Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T06:41:14.799133Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T06:41:14.799166Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T06:41:14.800367Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T06:41:14.801702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-04-23T06:41:14.802715Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-23T06:41:14.804093Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T06:41:14.804183Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-23T06:41:14.804231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-23T06:41:14.804482Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T06:41:14.80455Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T06:41:15.069026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T06:41:15.069086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T06:41:15.069114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-04-23T06:41:15.069126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-04-23T06:41:15.069132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-23T06:41:15.069239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-04-23T06:41:15.069255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-23T06:41:15.071248Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T06:41:15.071342Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T06:41:15.071503Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T06:41:15.07158Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T06:41:15.071811Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T06:41:15.071254Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T06:41:15.072706Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T06:41:15.072931Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T06:41:15.072984Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T06:41:15.076399Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T06:41:15.07872Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-04-23T06:41:17.570505Z","caller":"traceutil/trace.go:171","msg":"trace[1552222847] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"113.775854ms","start":"2026-04-23T06:41:17.456693Z","end":"2026-04-23T06:41:17.570469Z","steps":["trace[1552222847] 'process raft request' (duration: 43.24898ms)","trace[1552222847] 'compare' (duration: 70.22946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:27.808504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.185368ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:41:27.808653Z","caller":"traceutil/trace.go:171","msg":"trace[732200463] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:360; }","duration":"615.385131ms","start":"2026-04-23T06:41:27.193249Z","end":"2026-04-23T06:41:27.808635Z","steps":["trace[732200463] 'range keys from in-memory index tree' (duration: 615.092437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:28.686407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389969714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:41:29.00307Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.183819223s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T06:41:29.003521Z","caller":"traceutil/trace.go:171","msg":"trace[1501968260] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"817.756993ms","start":"2026-04-23T06:41:28.185733Z","end":"2026-04-23T06:41:29.00349Z","steps":["trace[1501968260] 'read index received' (duration: 817.48373ms)","trace[1501968260] 'applied index is now lower than readState.Index' (duration: 271.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:41:29.003666Z","caller":"traceutil/trace.go:171","msg":"trace[471848641] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"1.184441121s","start":"2026-04-23T06:41:27.819171Z","end":"2026-04-23T06:41:29.003613Z","steps":["trace[471848641] 'process raft request' (duration: 1.184135087s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:29.003796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.855787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T06:41:29.003856Z","caller":"traceutil/trace.go:171","msg":"trace[293347366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:361; }","duration":"778.047309ms","start":"2026-04-23T06:41:28.225791Z","end":"2026-04-23T06:41:29.003838Z","steps":["trace[293347366] 'agreement among raft nodes before linearized reading' (duration: 777.893577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:29.003905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:28.225766Z","time spent":"778.1265ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T06:41:29.004066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.3085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T06:41:29.00416Z","caller":"traceutil/trace.go:171","msg":"trace[1614311889] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:361; }","duration":"818.472202ms","start":"2026-04-23T06:41:28.185666Z","end":"2026-04-23T06:41:29.004138Z","steps":["trace[1614311889] 'agreement among raft nodes before linearized reading' (duration: 817.971256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:29.004203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:28.185643Z","time spent":"818.548543ms","remote":"127.0.0.1:58524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-04-23T06:41:29.004297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.83746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T06:41:29.004353Z","caller":"traceutil/trace.go:171","msg":"trace[1724081954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"272.927522ms","start":"2026-04-23T06:41:28.731412Z","end":"2026-04-23T06:41:29.00434Z","steps":["trace[1724081954] 'agreement among raft nodes before linearized reading' (duration: 272.832591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:29.004427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:27.819158Z","time spent":"1.184620574s","remote":"127.0.0.1:58698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:29.004522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.956099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T06:41:29.004587Z","caller":"traceutil/trace.go:171","msg":"trace[619421277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"222.02197ms","start":"2026-04-23T06:41:28.782548Z","end":"2026-04-23T06:41:29.00457Z","steps":["trace[619421277] 'agreement among raft nodes before linearized reading' (duration: 221.173009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:30.065069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.436365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:41:30.065297Z","caller":"traceutil/trace.go:171","msg":"trace[1893090143] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"242.218089ms","start":"2026-04-23T06:41:29.823051Z","end":"2026-04-23T06:41:30.065269Z","steps":["trace[1893090143] 'process raft request' (duration: 59.226647ms)","trace[1893090143] 'compare' (duration: 182.266233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:30.4849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.358608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:41:30.485505Z","caller":"traceutil/trace.go:171","msg":"trace[40260390] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"344.926117ms","start":"2026-04-23T06:41:30.140545Z","end":"2026-04-23T06:41:30.485471Z","steps":["trace[40260390] 'process raft request' (duration: 106.85744ms)","trace[40260390] 'compare' (duration: 237.198986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:30.485787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:30.140516Z","time spent":"345.211301ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:31.240734Z","caller":"traceutil/trace.go:171","msg":"trace[512513886] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"221.139456ms","start":"2026-04-23T06:41:31.019564Z","end":"2026-04-23T06:41:31.240703Z","steps":["trace[512513886] 'process raft request' (duration: 220.994413ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:31.388091Z","caller":"traceutil/trace.go:171","msg":"trace[1724447666] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:381; }","duration":"360.343674ms","start":"2026-04-23T06:41:31.027725Z","end":"2026-04-23T06:41:31.388069Z","steps":["trace[1724447666] 'read index received' (duration: 212.834069ms)","trace[1724447666] 'applied index is now lower than readState.Index' (duration: 147.508755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:31.388219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.475476ms","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-04-23T06:41:31.388249Z","caller":"traceutil/trace.go:171","msg":"trace[1487733930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"360.591067ms","start":"2026-04-23T06:41:31.02765Z","end":"2026-04-23T06:41:31.388241Z","steps":["trace[1487733930] 'agreement among raft nodes before linearized reading' (duration: 360.501946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:31.388286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:31.027641Z","time spent":"360.637558ms","remote":"127.0.0.1:58772","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":"info","ts":"2026-04-23T06:41:31.388332Z","caller":"traceutil/trace.go:171","msg":"trace[294304428] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"368.371397ms","start":"2026-04-23T06:41:31.019938Z","end":"2026-04-23T06:41:31.388309Z","steps":["trace[294304428] 'process raft request' (duration: 268.236654ms)","trace[294304428] 'compare' (duration: 99.723118ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:41:31.388429Z","caller":"traceutil/trace.go:171","msg":"trace[675421708] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"366.813977ms","start":"2026-04-23T06:41:31.021587Z","end":"2026-04-23T06:41:31.388401Z","steps":["trace[675421708] 'process raft request' (duration: 366.434863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:31.388464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:31.019921Z","time spent":"368.463648ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:31.388579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:31.021573Z","time spent":"366.926728ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:33.483353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.167402ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-23T06:41:40.010434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389969830,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T06:41:40.270055Z","caller":"traceutil/trace.go:171","msg":"trace[1509875020] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"759.683431ms","start":"2026-04-23T06:41:39.510346Z","end":"2026-04-23T06:41:40.270029Z","steps":["trace[1509875020] 'read index received' (duration: 759.472778ms)","trace[1509875020] 'applied index is now lower than readState.Index' (duration: 209.593µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:41:40.270136Z","caller":"traceutil/trace.go:171","msg":"trace[1651261361] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"873.137648ms","start":"2026-04-23T06:41:39.396973Z","end":"2026-04-23T06:41:40.270111Z","steps":["trace[1651261361] 'process raft request' (duration: 872.917785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:40.270247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.102494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-23T06:41:40.270269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:39.396961Z","time spent":"873.235459ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:40.270293Z","caller":"traceutil/trace.go:171","msg":"trace[1793551629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"759.194765ms","start":"2026-04-23T06:41:39.511085Z","end":"2026-04-23T06:41:40.270279Z","steps":["trace[1793551629] 'agreement among raft nodes before linearized reading' (duration: 759.099824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:40.270329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:39.511072Z","time spent":"759.247806ms","remote":"127.0.0.1:58772","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-04-23T06:41:40.270353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.970094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T06:41:40.270426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.733551ms","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-04-23T06:41:40.270394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.440695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:41:40.270462Z","caller":"traceutil/trace.go:171","msg":"trace[1718712625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"760.141647ms","start":"2026-04-23T06:41:39.510294Z","end":"2026-04-23T06:41:40.270436Z","steps":["trace[1718712625] 'agreement among raft nodes before linearized reading' (duration: 759.887734ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:40.270514Z","caller":"traceutil/trace.go:171","msg":"trace[1567130533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"361.638047ms","start":"2026-04-23T06:41:39.908851Z","end":"2026-04-23T06:41:40.270489Z","steps":["trace[1567130533] 'agreement among raft nodes before linearized reading' (duration: 361.397554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:40.270524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:39.510274Z","time spent":"760.238487ms","remote":"127.0.0.1:58772","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-04-23T06:41:40.270562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:39.908831Z","time spent":"361.724889ms","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T06:41:40.27046Z","caller":"traceutil/trace.go:171","msg":"trace[1863307312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"754.785252ms","start":"2026-04-23T06:41:39.515663Z","end":"2026-04-23T06:41:40.270449Z","steps":["trace[1863307312] 'agreement among raft nodes before linearized reading' (duration: 754.706611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:40.270641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:39.515653Z","time spent":"754.979224ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T06:41:41.066597Z","caller":"traceutil/trace.go:171","msg":"trace[1678655991] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"792.387593ms","start":"2026-04-23T06:41:40.274183Z","end":"2026-04-23T06:41:41.06657Z","steps":["trace[1678655991] 'process raft request' (duration: 792.234521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:41.066777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:40.274157Z","time spent":"792.526826ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:41.06763Z","caller":"traceutil/trace.go:171","msg":"trace[1542591318] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"791.28256ms","start":"2026-04-23T06:41:40.276334Z","end":"2026-04-23T06:41:41.067617Z","steps":["trace[1542591318] 'process raft request' (duration: 791.234229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:41.06768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:40.276314Z","time spent":"791.34259ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:41.067637Z","caller":"traceutil/trace.go:171","msg":"trace[678331256] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"792.232491ms","start":"2026-04-23T06:41:40.275385Z","end":"2026-04-23T06:41:41.067618Z","steps":["trace[678331256] 'process raft request' (duration: 792.128709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:41.067897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:40.275364Z","time spent":"792.488565ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:41.067866Z","caller":"traceutil/trace.go:171","msg":"trace[955785017] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"475.074052ms","start":"2026-04-23T06:41:40.592757Z","end":"2026-04-23T06:41:41.067831Z","steps":["trace[955785017] 'process raft request' (duration: 474.837038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:41.068037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:40.592728Z","time spent":"475.287945ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:46.424544Z","caller":"traceutil/trace.go:171","msg":"trace[808033823] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"129.886367ms","start":"2026-04-23T06:41:46.294016Z","end":"2026-04-23T06:41:46.423902Z","steps":["trace[808033823] 'process raft request' (duration: 129.690884ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:46.672694Z","caller":"traceutil/trace.go:171","msg":"trace[205047601] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"115.859153ms","start":"2026-04-23T06:41:46.556811Z","end":"2026-04-23T06:41:46.67267Z","steps":["trace[205047601] 'process raft request' (duration: 115.567139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:49.415083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389969947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:41:49.915512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389969947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:41:49.987984Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350318867s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T06:41:49.988356Z","caller":"traceutil/trace.go:171","msg":"trace[342495005] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.350765252s","start":"2026-04-23T06:41:48.637568Z","end":"2026-04-23T06:41:49.988333Z","steps":["trace[342495005] 'process raft request' (duration: 1.35060151s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:49.988492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.637549Z","time spent":"1.350877273s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:50.000066Z","caller":"traceutil/trace.go:171","msg":"trace[2015168708] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.362244113s","start":"2026-04-23T06:41:48.637794Z","end":"2026-04-23T06:41:50.000038Z","steps":["trace[2015168708] 'process raft request' (duration: 1.36200625s)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:50.000114Z","caller":"traceutil/trace.go:171","msg":"trace[175157785] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.361892839s","start":"2026-04-23T06:41:48.638198Z","end":"2026-04-23T06:41:50.000091Z","steps":["trace[175157785] 'process raft request' (duration: 1.361715137s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.000201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.638191Z","time spent":"1.36195939s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T06:41:50.000201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.637787Z","time spent":"1.362355654s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:50.000238Z","caller":"traceutil/trace.go:171","msg":"trace[1396753831] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.354509704s","start":"2026-04-23T06:41:48.645706Z","end":"2026-04-23T06:41:50.000216Z","steps":["trace[1396753831] 'process raft request' (duration: 1.354283401s)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:50.000341Z","caller":"traceutil/trace.go:171","msg":"trace[1824456303] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.354492624s","start":"2026-04-23T06:41:48.645799Z","end":"2026-04-23T06:41:50.000292Z","steps":["trace[1824456303] 'process raft request' (duration: 1.354266471s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.000375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.645687Z","time spent":"1.354647595s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:50.000264Z","caller":"traceutil/trace.go:171","msg":"trace[1893958075] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.357073193s","start":"2026-04-23T06:41:48.643171Z","end":"2026-04-23T06:41:50.000244Z","steps":["trace[1893958075] 'process raft request' (duration: 1.35677904s)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:50.000402Z","caller":"traceutil/trace.go:171","msg":"trace[1245641269] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.354510174s","start":"2026-04-23T06:41:48.645865Z","end":"2026-04-23T06:41:50.000376Z","steps":["trace[1245641269] 'process raft request' (duration: 1.354248581s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.000507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.643165Z","time spent":"1.357307556s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T06:41:50.00055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.645794Z","time spent":"1.354636555s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T06:41:50.000587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.645845Z","time spent":"1.354693565s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:50.001294Z","caller":"traceutil/trace.go:171","msg":"trace[995248304] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.347849247s","start":"2026-04-23T06:41:48.653423Z","end":"2026-04-23T06:41:50.001272Z","steps":["trace[995248304] 'process raft request' (duration: 1.347482322s)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:50.001343Z","caller":"traceutil/trace.go:171","msg":"trace[1078806452] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:490; }","duration":"1.086946398s","start":"2026-04-23T06:41:48.914381Z","end":"2026-04-23T06:41:50.001327Z","steps":["trace[1078806452] 'read index received' (duration: 1.073910758s)","trace[1078806452] 'applied index is now lower than readState.Index' (duration: 13.03485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:50.0014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.653415Z","time spent":"1.347941878s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:50.001458Z","caller":"traceutil/trace.go:171","msg":"trace[971261867] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.34726788s","start":"2026-04-23T06:41:48.65418Z","end":"2026-04-23T06:41:50.001447Z","steps":["trace[971261867] 'process raft request' (duration: 1.346919926s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.00157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.654175Z","time spent":"1.347343341s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:50.001604Z","caller":"traceutil/trace.go:171","msg":"trace[201918895] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.347905658s","start":"2026-04-23T06:41:48.653683Z","end":"2026-04-23T06:41:50.001589Z","steps":["trace[201918895] 'process raft request' (duration: 1.34731379s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.001669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.653678Z","time spent":"1.34796657s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:50.001814Z","caller":"traceutil/trace.go:171","msg":"trace[841561331] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.182093575s","start":"2026-04-23T06:41:48.819711Z","end":"2026-04-23T06:41:50.001805Z","steps":["trace[841561331] 'process raft request' (duration: 1.181561179s)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:50.001821Z","caller":"traceutil/trace.go:171","msg":"trace[570283733] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.312117075s","start":"2026-04-23T06:41:48.689694Z","end":"2026-04-23T06:41:50.001811Z","steps":["trace[570283733] 'process raft request' (duration: 1.311481667s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.001866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.68968Z","time spent":"1.312165776s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T06:41:50.001876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.819693Z","time spent":"1.182144746s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T06:41:50.002151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087766487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:41:50.002182Z","caller":"traceutil/trace.go:171","msg":"trace[208970564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"1.087838109s","start":"2026-04-23T06:41:48.914336Z","end":"2026-04-23T06:41:50.002175Z","steps":["trace[208970564] 'agreement among raft nodes before linearized reading' (duration: 1.087736997s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.002225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:48.914316Z","time spent":"1.087903229s","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T06:41:50.007282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.330892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T06:41:50.007422Z","caller":"traceutil/trace.go:171","msg":"trace[1981352033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:488; }","duration":"852.492754ms","start":"2026-04-23T06:41:49.15489Z","end":"2026-04-23T06:41:50.007383Z","steps":["trace[1981352033] 'agreement among raft nodes before linearized reading' (duration: 852.297742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.007519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:49.154869Z","time spent":"852.641505ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T06:41:50.007788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.793651ms","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-04-23T06:41:50.007823Z","caller":"traceutil/trace.go:171","msg":"trace[711307174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"855.854192ms","start":"2026-04-23T06:41:49.15196Z","end":"2026-04-23T06:41:50.007814Z","steps":["trace[711307174] 'agreement among raft nodes before linearized reading' (duration: 855.743491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.008041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.713186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T06:41:50.008118Z","caller":"traceutil/trace.go:171","msg":"trace[933140847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"894.812138ms","start":"2026-04-23T06:41:49.113278Z","end":"2026-04-23T06:41:50.008091Z","steps":["trace[933140847] 'agreement among raft nodes before linearized reading' (duration: 894.722677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:50.008708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:49.113257Z","time spent":"895.442314ms","remote":"127.0.0.1:58772","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-04-23T06:41:50.008141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:49.151947Z","time spent":"856.183315ms","remote":"127.0.0.1:58772","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-04-23T06:41:56.622999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970183,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:41:57.128303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.061038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:41:57.128439Z","caller":"traceutil/trace.go:171","msg":"trace[518644884] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.006967823s","start":"2026-04-23T06:41:56.121447Z","end":"2026-04-23T06:41:57.128415Z","steps":["trace[518644884] 'process raft request' (duration: 832.691642ms)","trace[518644884] 'compare' (duration: 173.935027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:57.128528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:56.121431Z","time spent":"1.007047393s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:57.521519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.908906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:41:57.521792Z","caller":"traceutil/trace.go:171","msg":"trace[1513582812] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.399553957s","start":"2026-04-23T06:41:56.122187Z","end":"2026-04-23T06:41:57.521741Z","steps":["trace[1513582812] 'process raft request' (duration: 1.201309798s)","trace[1513582812] 'compare' (duration: 197.752924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:57.521985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:56.122161Z","time spent":"1.39972266s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:57.522484Z","caller":"traceutil/trace.go:171","msg":"trace[251930801] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:600; }","duration":"1.400187303s","start":"2026-04-23T06:41:56.122277Z","end":"2026-04-23T06:41:57.522464Z","steps":["trace[251930801] 'read index received' (duration: 831.956634ms)","trace[251930801] 'applied index is now lower than readState.Index' (duration: 568.229869ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:41:57.522604Z","caller":"traceutil/trace.go:171","msg":"trace[1362976736] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.358746888s","start":"2026-04-23T06:41:56.163848Z","end":"2026-04-23T06:41:57.522594Z","steps":["trace[1362976736] 'process raft request' (duration: 1.358021521s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:57.522688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:56.163829Z","time spent":"1.358811509s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:57.522747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052780891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T06:41:57.522799Z","caller":"traceutil/trace.go:171","msg":"trace[487329037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.052873941s","start":"2026-04-23T06:41:56.469913Z","end":"2026-04-23T06:41:57.522787Z","steps":["trace[487329037] 'agreement among raft nodes before linearized reading' (duration: 1.05272724s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:57.522842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:56.469892Z","time spent":"1.052942822s","remote":"127.0.0.1:58772","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":"warn","ts":"2026-04-23T06:41:57.522924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.706098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bfbbw\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-23T06:41:57.522742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.275317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:41:57.52299Z","caller":"traceutil/trace.go:171","msg":"trace[225485799] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bfbbw; range_end:; response_count:1; response_revision:582; }","duration":"640.785448ms","start":"2026-04-23T06:41:56.882185Z","end":"2026-04-23T06:41:57.52297Z","steps":["trace[225485799] 'agreement among raft nodes before linearized reading' (duration: 640.600155ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:57.523015Z","caller":"traceutil/trace.go:171","msg":"trace[1824092608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"615.568841ms","start":"2026-04-23T06:41:56.907431Z","end":"2026-04-23T06:41:57.523Z","steps":["trace[1824092608] 'agreement among raft nodes before linearized reading' (duration: 615.260806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:57.523035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:56.882169Z","time spent":"640.853549ms","remote":"127.0.0.1:58698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-bfbbw\" "} {"level":"warn","ts":"2026-04-23T06:41:57.523059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:56.90742Z","time spent":"615.630461ms","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T06:41:57.523066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400675119s","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-04-23T06:41:57.523158Z","caller":"traceutil/trace.go:171","msg":"trace[492648634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.400888232s","start":"2026-04-23T06:41:56.122249Z","end":"2026-04-23T06:41:57.523137Z","steps":["trace[492648634] 'agreement among raft nodes before linearized reading' (duration: 1.400387346s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:57.523206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:56.122239Z","time spent":"1.400955973s","remote":"127.0.0.1:58772","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":"info","ts":"2026-04-23T06:41:57.923775Z","caller":"traceutil/trace.go:171","msg":"trace[852608074] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"396.046094ms","start":"2026-04-23T06:41:57.527667Z","end":"2026-04-23T06:41:57.923713Z","steps":["trace[852608074] 'process raft request' (duration: 314.47117ms)","trace[852608074] 'compare' (duration: 81.375362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:57.924101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:57.527643Z","time spent":"396.329486ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:58.410315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970188,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T06:41:58.765427Z","caller":"traceutil/trace.go:171","msg":"trace[1254071701] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.237353122s","start":"2026-04-23T06:41:57.528045Z","end":"2026-04-23T06:41:58.765398Z","steps":["trace[1254071701] 'process raft request' (duration: 1.23712233s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:58.765635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:57.528035Z","time spent":"1.237478653s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:58.766146Z","caller":"traceutil/trace.go:171","msg":"trace[680926507] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.176927331s","start":"2026-04-23T06:41:57.589202Z","end":"2026-04-23T06:41:58.766129Z","steps":["trace[680926507] 'process raft request' (duration: 1.176497736s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:58.766234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:57.589181Z","time spent":"1.177011851s","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T06:41:58.766289Z","caller":"traceutil/trace.go:171","msg":"trace[845357982] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:604; }","duration":"856.99164ms","start":"2026-04-23T06:41:57.909282Z","end":"2026-04-23T06:41:58.766273Z","steps":["trace[845357982] 'read index received' (duration: 855.804898ms)","trace[845357982] 'applied index is now lower than readState.Index' (duration: 1.185572ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:41:58.76641Z","caller":"traceutil/trace.go:171","msg":"trace[712124669] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.030855073s","start":"2026-04-23T06:41:57.735546Z","end":"2026-04-23T06:41:58.766401Z","steps":["trace[712124669] 'process raft request' (duration: 1.030513289s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:58.766491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.204642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T06:41:58.76649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.684907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-23T06:41:58.766538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:57.735519Z","time spent":"1.030918253s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:41:58.766553Z","caller":"traceutil/trace.go:171","msg":"trace[1692611339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"857.331454ms","start":"2026-04-23T06:41:57.909205Z","end":"2026-04-23T06:41:58.766536Z","steps":["trace[1692611339] 'agreement among raft nodes before linearized reading' (duration: 857.249183ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:41:58.766562Z","caller":"traceutil/trace.go:171","msg":"trace[663835901] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:587; }","duration":"579.841769ms","start":"2026-04-23T06:41:58.186704Z","end":"2026-04-23T06:41:58.766546Z","steps":["trace[663835901] 'agreement among raft nodes before linearized reading' (duration: 579.693568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:58.766584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:57.909186Z","time spent":"857.392835ms","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T06:41:58.766609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:58.186677Z","time spent":"579.91751ms","remote":"127.0.0.1:58524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"info","ts":"2026-04-23T06:41:58.765937Z","caller":"traceutil/trace.go:171","msg":"trace[1948545565] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.22716371s","start":"2026-04-23T06:41:57.53874Z","end":"2026-04-23T06:41:58.765904Z","steps":["trace[1948545565] 'process raft request' (duration: 1.226596174s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:58.767354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:57.538724Z","time spent":"1.228164302s","remote":"127.0.0.1:58698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:59.362845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.468268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:41:59.362982Z","caller":"traceutil/trace.go:171","msg":"trace[58086164] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"229.579654ms","start":"2026-04-23T06:41:59.13338Z","end":"2026-04-23T06:41:59.36296Z","steps":["trace[58086164] 'read index received' (duration: 63.461µs)","trace[58086164] 'applied index is now lower than readState.Index' (duration: 229.514393ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:41:59.363022Z","caller":"traceutil/trace.go:171","msg":"trace[476209931] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"513.869159ms","start":"2026-04-23T06:41:58.849118Z","end":"2026-04-23T06:41:59.362988Z","steps":["trace[476209931] 'process raft request' (duration: 40.987096ms)","trace[476209931] 'compare' (duration: 472.318637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:41:59.363194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.812097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T06:41:59.363232Z","caller":"traceutil/trace.go:171","msg":"trace[2100842930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"229.898597ms","start":"2026-04-23T06:41:59.133322Z","end":"2026-04-23T06:41:59.363221Z","steps":["trace[2100842930] 'agreement among raft nodes before linearized reading' (duration: 229.700945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:41:59.363198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:58.849099Z","time spent":"513.99979ms","remote":"127.0.0.1:58524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:41:59.866639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970202,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:42:00.370601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970202,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:42:00.871105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970202,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:42:00.910378Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.638752628s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T06:42:01.839574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.568473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:42:01.839815Z","caller":"traceutil/trace.go:171","msg":"trace[471319147] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"2.473875162s","start":"2026-04-23T06:41:59.365922Z","end":"2026-04-23T06:42:01.839797Z","steps":["trace[471319147] 'read index received' (duration: 1.544944434s)","trace[471319147] 'applied index is now lower than readState.Index' (duration: 928.929348ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:42:01.840459Z","caller":"traceutil/trace.go:171","msg":"trace[1741662500] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.472061591s","start":"2026-04-23T06:41:59.368387Z","end":"2026-04-23T06:42:01.840449Z","steps":["trace[1741662500] 'process raft request' (duration: 2.471330523s)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:01.84046Z","caller":"traceutil/trace.go:171","msg":"trace[290134022] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.571593338s","start":"2026-04-23T06:41:59.268845Z","end":"2026-04-23T06:42:01.840439Z","steps":["trace[290134022] 'process raft request' (duration: 1.642024824s)","trace[290134022] 'compare' (duration: 928.069748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:42:01.840517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:59.368357Z","time spent":"2.472129152s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:42:01.840533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:59.268812Z","time spent":"2.571676069s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:42:01.840633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.47469709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-23T06:42:01.841181Z","caller":"traceutil/trace.go:171","msg":"trace[787636193] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:590; }","duration":"2.475253275s","start":"2026-04-23T06:41:59.365869Z","end":"2026-04-23T06:42:01.841122Z","steps":["trace[787636193] 'agreement among raft nodes before linearized reading' (duration: 2.47470017s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:01.841238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:59.36585Z","time spent":"2.475372387s","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-23T06:42:01.851632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923429424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T06:42:01.851691Z","caller":"traceutil/trace.go:171","msg":"trace[536078413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.923497684s","start":"2026-04-23T06:41:59.928181Z","end":"2026-04-23T06:42:01.851679Z","steps":["trace[536078413] 'agreement among raft nodes before linearized reading' (duration: 1.923345723s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:01.851717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:59.928131Z","time spent":"1.923580965s","remote":"127.0.0.1:58772","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-04-23T06:42:01.851818Z","caller":"traceutil/trace.go:171","msg":"trace[1310868988] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"228.400035ms","start":"2026-04-23T06:42:01.623397Z","end":"2026-04-23T06:42:01.851797Z","steps":["trace[1310868988] 'process raft request' (duration: 227.97497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:01.851629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08172485s","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-04-23T06:42:01.852051Z","caller":"traceutil/trace.go:171","msg":"trace[892210276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.082163955s","start":"2026-04-23T06:42:00.769853Z","end":"2026-04-23T06:42:01.852017Z","steps":["trace[892210276] 'agreement among raft nodes before linearized reading' (duration: 1.08164845s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:01.852069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.325719395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T06:42:01.852096Z","caller":"traceutil/trace.go:171","msg":"trace[1553887651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"2.325789767s","start":"2026-04-23T06:41:59.526297Z","end":"2026-04-23T06:42:01.852087Z","steps":["trace[1553887651] 'agreement among raft nodes before linearized reading' (duration: 2.325713796s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:01.852113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:59.526273Z","time spent":"2.325835197s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T06:42:01.852114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:00.769828Z","time spent":"1.082262946s","remote":"127.0.0.1:58772","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-04-23T06:42:01.851923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.942327058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:42:01.85218Z","caller":"traceutil/trace.go:171","msg":"trace[385786564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.942606511s","start":"2026-04-23T06:41:59.909553Z","end":"2026-04-23T06:42:01.852159Z","steps":["trace[385786564] 'agreement among raft nodes before linearized reading' (duration: 1.942353309s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:01.852248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:41:59.909527Z","time spent":"1.942698032s","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T06:42:03.733846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.371098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T06:42:03.734424Z","caller":"traceutil/trace.go:171","msg":"trace[906595158] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"295.437499ms","start":"2026-04-23T06:42:03.438958Z","end":"2026-04-23T06:42:03.734396Z","steps":["trace[906595158] 'process raft request' (duration: 294.991285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:04.347888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:42:04.848453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:42:05.140346Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.32973929s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T06:42:05.140682Z","caller":"traceutil/trace.go:171","msg":"trace[1546061727] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"1.292852209s","start":"2026-04-23T06:42:03.847808Z","end":"2026-04-23T06:42:05.14066Z","steps":["trace[1546061727] 'read index received' (duration: 1.292646867s)","trace[1546061727] 'applied index is now lower than readState.Index' (duration: 204.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:42:05.140725Z","caller":"traceutil/trace.go:171","msg":"trace[1280212371] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.330216775s","start":"2026-04-23T06:42:03.810474Z","end":"2026-04-23T06:42:05.14069Z","steps":["trace[1280212371] 'process raft request' (duration: 1.330044833s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:05.140896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293077352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T06:42:05.140996Z","caller":"traceutil/trace.go:171","msg":"trace[2002565587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"1.293221184s","start":"2026-04-23T06:42:03.847761Z","end":"2026-04-23T06:42:05.140982Z","steps":["trace[2002565587] 'agreement among raft nodes before linearized reading' (duration: 1.292989791s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:05.141033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:03.847747Z","time spent":"1.293277814s","remote":"127.0.0.1:58772","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-04-23T06:42:05.141079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232631241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bfbbw\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-04-23T06:42:05.141127Z","caller":"traceutil/trace.go:171","msg":"trace[621839065] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bfbbw; range_end:; response_count:1; response_revision:608; }","duration":"1.232722262s","start":"2026-04-23T06:42:03.908392Z","end":"2026-04-23T06:42:05.141115Z","steps":["trace[621839065] 'agreement among raft nodes before linearized reading' (duration: 1.232475749s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:05.141188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:03.908376Z","time spent":"1.232802863s","remote":"127.0.0.1:58698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17543,"request content":"key:\"/registry/pods/kube-system/cilium-bfbbw\" "} {"level":"warn","ts":"2026-04-23T06:42:05.141165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272069739s","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-04-23T06:42:05.141255Z","caller":"traceutil/trace.go:171","msg":"trace[2010023293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"1.272227981s","start":"2026-04-23T06:42:03.869004Z","end":"2026-04-23T06:42:05.141232Z","steps":["trace[2010023293] 'agreement among raft nodes before linearized reading' (duration: 1.271861427s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:05.141299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:03.868993Z","time spent":"1.272297992s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T06:42:05.141386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266062875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T06:42:05.141444Z","caller":"traceutil/trace.go:171","msg":"trace[397949110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"1.266159046s","start":"2026-04-23T06:42:03.875271Z","end":"2026-04-23T06:42:05.14143Z","steps":["trace[397949110] 'agreement among raft nodes before linearized reading' (duration: 1.266006485s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:05.141494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:03.875259Z","time spent":"1.266224917s","remote":"127.0.0.1:58772","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":"warn","ts":"2026-04-23T06:42:05.141502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.777185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:42:05.141581Z","caller":"traceutil/trace.go:171","msg":"trace[866032094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"232.896085ms","start":"2026-04-23T06:42:04.90866Z","end":"2026-04-23T06:42:05.141556Z","steps":["trace[866032094] 'agreement among raft nodes before linearized reading' (duration: 232.769934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:05.141588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266550791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T06:42:05.141689Z","caller":"traceutil/trace.go:171","msg":"trace[851592339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"1.266688472s","start":"2026-04-23T06:42:03.874985Z","end":"2026-04-23T06:42:05.141673Z","steps":["trace[851592339] 'agreement among raft nodes before linearized reading' (duration: 1.26653314s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:05.141737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:03.874958Z","time spent":"1.266764163s","remote":"127.0.0.1:58772","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-04-23T06:42:05.142145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:03.810444Z","time spent":"1.330365727s","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T06:42:08.411334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.612978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T06:42:08.411411Z","caller":"traceutil/trace.go:171","msg":"trace[500558671] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:664; }","duration":"224.734939ms","start":"2026-04-23T06:42:08.186659Z","end":"2026-04-23T06:42:08.411394Z","steps":["trace[500558671] 'range keys from in-memory index tree' (duration: 224.410266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:08.411518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.356659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-23T06:42:08.411612Z","caller":"traceutil/trace.go:171","msg":"trace[457991704] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:664; }","duration":"236.49328ms","start":"2026-04-23T06:42:08.175092Z","end":"2026-04-23T06:42:08.411585Z","steps":["trace[457991704] 'range keys from in-memory index tree' (duration: 236.170597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:19.720336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954230389970525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T06:42:20.087703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081262091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8drjj\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-04-23T06:42:20.087733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179551569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:42:20.087784Z","caller":"traceutil/trace.go:171","msg":"trace[310164761] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8drjj; range_end:; response_count:1; response_revision:751; }","duration":"1.081355772s","start":"2026-04-23T06:42:19.006408Z","end":"2026-04-23T06:42:20.087763Z","steps":["trace[310164761] 'range keys from in-memory index tree' (duration: 1.081089589s)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:20.087809Z","caller":"traceutil/trace.go:171","msg":"trace[1891975357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"1.17966331s","start":"2026-04-23T06:42:18.90812Z","end":"2026-04-23T06:42:20.087783Z","steps":["trace[1891975357] 'range keys from in-memory index tree' (duration: 1.179453558s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.087828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.006343Z","time spent":"1.081475273s","remote":"127.0.0.1:58698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8drjj\" "} {"level":"warn","ts":"2026-04-23T06:42:20.087843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:18.908096Z","time spent":"1.179739231s","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T06:42:20.087866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030370082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T06:42:20.089335Z","caller":"traceutil/trace.go:171","msg":"trace[1883090901] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:751; }","duration":"1.030545594s","start":"2026-04-23T06:42:19.057413Z","end":"2026-04-23T06:42:20.087959Z","steps":["trace[1883090901] 'count revisions from in-memory index tree' (duration: 1.030268252s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.08946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.057393Z","time spent":"1.032027598s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-23T06:42:20.151327Z","caller":"traceutil/trace.go:171","msg":"trace[2025576733] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"955.964731ms","start":"2026-04-23T06:42:19.195335Z","end":"2026-04-23T06:42:20.1513Z","steps":["trace[2025576733] 'process raft request' (duration: 955.754639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.15147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.195313Z","time spent":"956.080612ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:42:20.151756Z","caller":"traceutil/trace.go:171","msg":"trace[1799811447] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"931.298029ms","start":"2026-04-23T06:42:19.219973Z","end":"2026-04-23T06:42:20.151272Z","steps":["trace[1799811447] 'read index received' (duration: 931.143077ms)","trace[1799811447] 'applied index is now lower than readState.Index' (duration: 154.432µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:42:20.151895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.936024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T06:42:20.151921Z","caller":"traceutil/trace.go:171","msg":"trace[2111136057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"931.964425ms","start":"2026-04-23T06:42:19.21995Z","end":"2026-04-23T06:42:20.151914Z","steps":["trace[2111136057] 'agreement among raft nodes before linearized reading' (duration: 931.853334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.151942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.219905Z","time spent":"932.032585ms","remote":"127.0.0.1:58772","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-04-23T06:42:20.366816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13039126s","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-04-23T06:42:20.366838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.247561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T06:42:20.366864Z","caller":"traceutil/trace.go:171","msg":"trace[327063827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"1.13046786s","start":"2026-04-23T06:42:19.236385Z","end":"2026-04-23T06:42:20.366853Z","steps":["trace[327063827] 'agreement among raft nodes before linearized reading' (duration: 1.130304219s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.366891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.236377Z","time spent":"1.130507581s","remote":"127.0.0.1:58772","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":"info","ts":"2026-04-23T06:42:20.366892Z","caller":"traceutil/trace.go:171","msg":"trace[1442821017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"921.335722ms","start":"2026-04-23T06:42:19.445543Z","end":"2026-04-23T06:42:20.366879Z","steps":["trace[1442821017] 'agreement among raft nodes before linearized reading' (duration: 921.17875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.366933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.445529Z","time spent":"921.393202ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T06:42:20.367064Z","caller":"traceutil/trace.go:171","msg":"trace[1454588930] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"269.181855ms","start":"2026-04-23T06:42:20.097867Z","end":"2026-04-23T06:42:20.367049Z","steps":["trace[1454588930] 'process raft request' (duration: 268.58834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.367332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13855736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T06:42:20.367367Z","caller":"traceutil/trace.go:171","msg":"trace[1922129677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"1.13861885s","start":"2026-04-23T06:42:19.22874Z","end":"2026-04-23T06:42:20.367359Z","steps":["trace[1922129677] 'agreement among raft nodes before linearized reading' (duration: 1.1385402s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.36739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.228726Z","time spent":"1.13865855s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T06:42:20.366647Z","caller":"traceutil/trace.go:171","msg":"trace[1496967967] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"214.825926ms","start":"2026-04-23T06:42:20.151782Z","end":"2026-04-23T06:42:20.366608Z","steps":["trace[1496967967] 'read index received' (duration: 214.643874ms)","trace[1496967967] 'applied index is now lower than readState.Index' (duration: 181.132µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:42:20.367548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135401839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T06:42:20.36766Z","caller":"traceutil/trace.go:171","msg":"trace[1468143935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"1.135497s","start":"2026-04-23T06:42:19.232105Z","end":"2026-04-23T06:42:20.367602Z","steps":["trace[1468143935] 'agreement among raft nodes before linearized reading' (duration: 1.134679132s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:20.367757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:42:19.232094Z","time spent":"1.13560825s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T06:42:20.367573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.8799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:42:20.368324Z","caller":"traceutil/trace.go:171","msg":"trace[179694188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"279.007062ms","start":"2026-04-23T06:42:20.089306Z","end":"2026-04-23T06:42:20.368313Z","steps":["trace[179694188] 'agreement among raft nodes before linearized reading' (duration: 277.920801ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:20.380194Z","caller":"traceutil/trace.go:171","msg":"trace[676105607] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"223.918374ms","start":"2026-04-23T06:42:20.156262Z","end":"2026-04-23T06:42:20.38018Z","steps":["trace[676105607] 'process raft request' (duration: 223.831783ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:23.152421Z","caller":"traceutil/trace.go:171","msg":"trace[1658496361] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"122.753872ms","start":"2026-04-23T06:42:23.029637Z","end":"2026-04-23T06:42:23.152391Z","steps":["trace[1658496361] 'process raft request' (duration: 122.570749ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:28.574055Z","caller":"traceutil/trace.go:171","msg":"trace[1832323439] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"100.167535ms","start":"2026-04-23T06:42:28.473873Z","end":"2026-04-23T06:42:28.574041Z","steps":["trace[1832323439] 'process raft request' (duration: 100.022514ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:28.574059Z","caller":"traceutil/trace.go:171","msg":"trace[1550173050] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"100.286847ms","start":"2026-04-23T06:42:28.473747Z","end":"2026-04-23T06:42:28.574034Z","steps":["trace[1550173050] 'process raft request' (duration: 96.201598ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:28.574143Z","caller":"traceutil/trace.go:171","msg":"trace[1892513470] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"100.206326ms","start":"2026-04-23T06:42:28.473919Z","end":"2026-04-23T06:42:28.574125Z","steps":["trace[1892513470] 'process raft request' (duration: 100.016194ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:42.752939Z","caller":"traceutil/trace.go:171","msg":"trace[75874946] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:987; }","duration":"107.411932ms","start":"2026-04-23T06:42:42.645499Z","end":"2026-04-23T06:42:42.752911Z","steps":["trace[75874946] 'read index received' (duration: 78.594484ms)","trace[75874946] 'applied index is now lower than readState.Index' (duration: 28.816078ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:42:42.753101Z","caller":"traceutil/trace.go:171","msg":"trace[178165294] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"107.987357ms","start":"2026-04-23T06:42:42.645075Z","end":"2026-04-23T06:42:42.753062Z","steps":["trace[178165294] 'process raft request' (duration: 107.732565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:42.753292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.753235ms","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-04-23T06:42:42.753369Z","caller":"traceutil/trace.go:171","msg":"trace[489908320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"107.869857ms","start":"2026-04-23T06:42:42.645479Z","end":"2026-04-23T06:42:42.753349Z","steps":["trace[489908320] 'agreement among raft nodes before linearized reading' (duration: 107.549563ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:42:48.945236Z","caller":"traceutil/trace.go:171","msg":"trace[981050898] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"164.96986ms","start":"2026-04-23T06:42:48.780247Z","end":"2026-04-23T06:42:48.945217Z","steps":["trace[981050898] 'read index received' (duration: 156.788918ms)","trace[981050898] 'applied index is now lower than readState.Index' (duration: 8.180292ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:42:48.945275Z","caller":"traceutil/trace.go:171","msg":"trace[1977541435] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"189.714518ms","start":"2026-04-23T06:42:48.755531Z","end":"2026-04-23T06:42:48.945246Z","steps":["trace[1977541435] 'process raft request' (duration: 181.533325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:48.945421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.157992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T06:42:48.945442Z","caller":"traceutil/trace.go:171","msg":"trace[1340026918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"165.215812ms","start":"2026-04-23T06:42:48.78022Z","end":"2026-04-23T06:42:48.945435Z","steps":["trace[1340026918] 'agreement among raft nodes before linearized reading' (duration: 165.112971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:42:48.945459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.966355ms","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-04-23T06:42:48.945484Z","caller":"traceutil/trace.go:171","msg":"trace[1790566173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"160.031827ms","start":"2026-04-23T06:42:48.785447Z","end":"2026-04-23T06:42:48.945478Z","steps":["trace[1790566173] 'agreement among raft nodes before linearized reading' (duration: 159.941746ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:43:19.310198Z","caller":"traceutil/trace.go:171","msg":"trace[1438477311] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"109.489278ms","start":"2026-04-23T06:43:19.200656Z","end":"2026-04-23T06:43:19.310145Z","steps":["trace[1438477311] 'process raft request' (duration: 45.726171ms)","trace[1438477311] 'compare' (duration: 63.509825ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:43:19.785643Z","caller":"traceutil/trace.go:171","msg":"trace[1115355235] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"116.688237ms","start":"2026-04-23T06:43:19.668934Z","end":"2026-04-23T06:43:19.785622Z","steps":["trace[1115355235] 'process raft request' (duration: 116.558906ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:43:39.380098Z","caller":"traceutil/trace.go:171","msg":"trace[162476525] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"111.16386ms","start":"2026-04-23T06:43:39.268906Z","end":"2026-04-23T06:43:39.38007Z","steps":["trace[162476525] 'process raft request' (duration: 96.825128ms)","trace[162476525] 'compare' (duration: 14.13041ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:43:47.408529Z","caller":"traceutil/trace.go:171","msg":"trace[2130198790] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"101.834057ms","start":"2026-04-23T06:43:47.306677Z","end":"2026-04-23T06:43:47.408511Z","steps":["trace[2130198790] 'process raft request' (duration: 101.728537ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:43:48.351423Z","caller":"traceutil/trace.go:171","msg":"trace[1344260017] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"154.460353ms","start":"2026-04-23T06:43:48.19694Z","end":"2026-04-23T06:43:48.351401Z","steps":["trace[1344260017] 'process raft request' (duration: 67.993515ms)","trace[1344260017] 'compare' (duration: 86.299426ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:43:51.853726Z","caller":"traceutil/trace.go:171","msg":"trace[1241741412] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"146.175253ms","start":"2026-04-23T06:43:51.707536Z","end":"2026-04-23T06:43:51.853711Z","steps":["trace[1241741412] 'process raft request' (duration: 144.641192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:43:55.744662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.685123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T06:43:55.744717Z","caller":"traceutil/trace.go:171","msg":"trace[1493330610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"137.795194ms","start":"2026-04-23T06:43:55.606909Z","end":"2026-04-23T06:43:55.744704Z","steps":["trace[1493330610] 'range keys from in-memory index tree' (duration: 137.528022ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:43:55.912297Z","caller":"traceutil/trace.go:171","msg":"trace[1738368972] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"163.180898ms","start":"2026-04-23T06:43:55.74909Z","end":"2026-04-23T06:43:55.912271Z","steps":["trace[1738368972] 'process raft request' (duration: 163.026237ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:43:55.929375Z","caller":"traceutil/trace.go:171","msg":"trace[1193291888] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1243; }","duration":"141.384171ms","start":"2026-04-23T06:43:55.787967Z","end":"2026-04-23T06:43:55.929351Z","steps":["trace[1193291888] 'read index received' (duration: 124.19266ms)","trace[1193291888] 'applied index is now lower than readState.Index' (duration: 17.190451ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:43:55.929439Z","caller":"traceutil/trace.go:171","msg":"trace[1934946498] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"180.296999ms","start":"2026-04-23T06:43:55.749121Z","end":"2026-04-23T06:43:55.929418Z","steps":["trace[1934946498] 'process raft request' (duration: 179.994427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:43:55.929592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.609153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T06:43:55.929634Z","caller":"traceutil/trace.go:171","msg":"trace[1626784550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"141.699883ms","start":"2026-04-23T06:43:55.787924Z","end":"2026-04-23T06:43:55.929623Z","steps":["trace[1626784550] 'agreement among raft nodes before linearized reading' (duration: 141.547362ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:43:57.709521Z","caller":"traceutil/trace.go:171","msg":"trace[547394170] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"113.016571ms","start":"2026-04-23T06:43:57.59647Z","end":"2026-04-23T06:43:57.709487Z","steps":["trace[547394170] 'process raft request' (duration: 112.83953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:43:58.179896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.303863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:43:58.180049Z","caller":"traceutil/trace.go:171","msg":"trace[504187613] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"146.018552ms","start":"2026-04-23T06:43:58.033991Z","end":"2026-04-23T06:43:58.180009Z","steps":["trace[504187613] 'read index received' (duration: 15.493287ms)","trace[504187613] 'applied index is now lower than readState.Index' (duration: 130.523045ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:43:58.180055Z","caller":"traceutil/trace.go:171","msg":"trace[993457431] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"204.882341ms","start":"2026-04-23T06:43:57.975129Z","end":"2026-04-23T06:43:58.180011Z","steps":["trace[993457431] 'process raft request' (duration: 74.358306ms)","trace[993457431] 'compare' (duration: 130.128592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:43:58.180302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.314125ms","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-04-23T06:43:58.180362Z","caller":"traceutil/trace.go:171","msg":"trace[1418667032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"146.435265ms","start":"2026-04-23T06:43:58.033912Z","end":"2026-04-23T06:43:58.180347Z","steps":["trace[1418667032] 'agreement among raft nodes before linearized reading' (duration: 146.188884ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:00.288153Z","caller":"traceutil/trace.go:171","msg":"trace[1931184852] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"112.221803ms","start":"2026-04-23T06:44:00.175866Z","end":"2026-04-23T06:44:00.288088Z","steps":["trace[1931184852] 'process raft request' (duration: 112.104062ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:00.434085Z","caller":"traceutil/trace.go:171","msg":"trace[559163207] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1263; }","duration":"141.916759ms","start":"2026-04-23T06:44:00.292151Z","end":"2026-04-23T06:44:00.434067Z","steps":["trace[559163207] 'read index received' (duration: 18.786642ms)","trace[559163207] 'applied index is now lower than readState.Index' (duration: 123.129287ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:44:00.434109Z","caller":"traceutil/trace.go:171","msg":"trace[2046176920] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"142.04031ms","start":"2026-04-23T06:44:00.292042Z","end":"2026-04-23T06:44:00.434082Z","steps":["trace[2046176920] 'process raft request' (duration: 91.096602ms)","trace[2046176920] 'compare' (duration: 50.789377ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:44:00.434496Z","caller":"traceutil/trace.go:171","msg":"trace[92163922] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"142.358403ms","start":"2026-04-23T06:44:00.292129Z","end":"2026-04-23T06:44:00.434487Z","steps":["trace[92163922] 'process raft request' (duration: 141.901518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:44:00.43465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.485264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-04-23T06:44:00.434681Z","caller":"traceutil/trace.go:171","msg":"trace[1018904086] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1216; }","duration":"142.539664ms","start":"2026-04-23T06:44:00.292134Z","end":"2026-04-23T06:44:00.434674Z","steps":["trace[1018904086] 'agreement among raft nodes before linearized reading' (duration: 142.381633ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:00.443361Z","caller":"traceutil/trace.go:171","msg":"trace[590795406] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"113.790396ms","start":"2026-04-23T06:44:00.329553Z","end":"2026-04-23T06:44:00.443343Z","steps":["trace[590795406] 'process raft request' (duration: 113.638444ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:00.83175Z","caller":"traceutil/trace.go:171","msg":"trace[27245056] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"231.902423ms","start":"2026-04-23T06:44:00.599818Z","end":"2026-04-23T06:44:00.83172Z","steps":["trace[27245056] 'process raft request' (duration: 208.252493ms)","trace[27245056] 'compare' (duration: 23.487388ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:44:00.996269Z","caller":"traceutil/trace.go:171","msg":"trace[1311272104] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"150.218382ms","start":"2026-04-23T06:44:00.846025Z","end":"2026-04-23T06:44:00.996243Z","steps":["trace[1311272104] 'read index received' (duration: 140.73301ms)","trace[1311272104] 'applied index is now lower than readState.Index' (duration: 9.484222ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:44:00.996391Z","caller":"traceutil/trace.go:171","msg":"trace[94129188] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"153.565988ms","start":"2026-04-23T06:44:00.842812Z","end":"2026-04-23T06:44:00.996378Z","steps":["trace[94129188] 'process raft request' (duration: 144.021835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:44:00.99667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.615775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1891cf1d-7c39-4bc5-9cc5-388d13950147\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-04-23T06:44:00.996778Z","caller":"traceutil/trace.go:171","msg":"trace[68331725] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1891cf1d-7c39-4bc5-9cc5-388d13950147; range_end:; response_count:1; response_revision:1222; }","duration":"150.772336ms","start":"2026-04-23T06:44:00.845981Z","end":"2026-04-23T06:44:00.996754Z","steps":["trace[68331725] 'agreement among raft nodes before linearized reading' (duration: 150.563815ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:01.775009Z","caller":"traceutil/trace.go:171","msg":"trace[1888556807] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"235.864961ms","start":"2026-04-23T06:44:01.539115Z","end":"2026-04-23T06:44:01.77498Z","steps":["trace[1888556807] 'process raft request' (duration: 233.969026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:44:02.486355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.405724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T06:44:02.486522Z","caller":"traceutil/trace.go:171","msg":"trace[1678893142] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"346.336847ms","start":"2026-04-23T06:44:02.140169Z","end":"2026-04-23T06:44:02.486505Z","steps":["trace[1678893142] 'read index received' (duration: 155.641361ms)","trace[1678893142] 'applied index is now lower than readState.Index' (duration: 190.693966ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:44:02.486821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.638659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:44:02.48662Z","caller":"traceutil/trace.go:171","msg":"trace[1784536920] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"435.668914ms","start":"2026-04-23T06:44:02.050916Z","end":"2026-04-23T06:44:02.486585Z","steps":["trace[1784536920] 'process raft request' (duration: 244.912137ms)","trace[1784536920] 'compare' (duration: 190.187862ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:44:02.486892Z","caller":"traceutil/trace.go:171","msg":"trace[1300781093] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1228; }","duration":"346.74357ms","start":"2026-04-23T06:44:02.140135Z","end":"2026-04-23T06:44:02.486878Z","steps":["trace[1300781093] 'agreement among raft nodes before linearized reading' (duration: 346.589179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:44:02.486961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:44:02.14012Z","time spent":"346.827901ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T06:44:02.487184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:44:02.050906Z","time spent":"435.995696ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T06:44:02.489531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.567484ms","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-04-23T06:44:02.4896Z","caller":"traceutil/trace.go:171","msg":"trace[257701513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"174.665774ms","start":"2026-04-23T06:44:02.314917Z","end":"2026-04-23T06:44:02.489583Z","steps":["trace[257701513] 'agreement among raft nodes before linearized reading' (duration: 174.443523ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:03.955999Z","caller":"traceutil/trace.go:171","msg":"trace[11489332] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"143.009374ms","start":"2026-04-23T06:44:03.812974Z","end":"2026-04-23T06:44:03.955983Z","steps":["trace[11489332] 'process raft request' (duration: 142.865963ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:09.430611Z","caller":"traceutil/trace.go:171","msg":"trace[1895289250] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"149.748527ms","start":"2026-04-23T06:44:09.280836Z","end":"2026-04-23T06:44:09.430585Z","steps":["trace[1895289250] 'process raft request' (duration: 149.615446ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:12.203264Z","caller":"traceutil/trace.go:171","msg":"trace[90169089] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"182.827633ms","start":"2026-04-23T06:44:12.020401Z","end":"2026-04-23T06:44:12.203228Z","steps":["trace[90169089] 'process raft request' (duration: 182.630052ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:14.966176Z","caller":"traceutil/trace.go:171","msg":"trace[1510718875] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"105.45892ms","start":"2026-04-23T06:44:14.860651Z","end":"2026-04-23T06:44:14.96611Z","steps":["trace[1510718875] 'process raft request' (duration: 105.326079ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:22.591817Z","caller":"traceutil/trace.go:171","msg":"trace[2029686293] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"279.561919ms","start":"2026-04-23T06:44:22.312231Z","end":"2026-04-23T06:44:22.591793Z","steps":["trace[2029686293] 'read index received' (duration: 279.361588ms)","trace[2029686293] 'applied index is now lower than readState.Index' (duration: 199.511µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:44:22.59209Z","caller":"traceutil/trace.go:171","msg":"trace[1144391321] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"340.19224ms","start":"2026-04-23T06:44:22.251883Z","end":"2026-04-23T06:44:22.592075Z","steps":["trace[1144391321] 'process raft request' (duration: 339.766157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:44:22.592133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.882391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T06:44:22.59224Z","caller":"traceutil/trace.go:171","msg":"trace[338408847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"280.046123ms","start":"2026-04-23T06:44:22.312177Z","end":"2026-04-23T06:44:22.592223Z","steps":["trace[338408847] 'agreement among raft nodes before linearized reading' (duration: 279.718011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T06:44:22.592209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T06:44:22.25187Z","time spent":"340.269411ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T06:44:37.240005Z","caller":"traceutil/trace.go:171","msg":"trace[452942593] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"102.379433ms","start":"2026-04-23T06:44:37.13759Z","end":"2026-04-23T06:44:37.239969Z","steps":["trace[452942593] 'process raft request' (duration: 57.539803ms)","trace[452942593] 'compare' (duration: 44.664718ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T06:44:45.139651Z","caller":"traceutil/trace.go:171","msg":"trace[142507924] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1457; }","duration":"102.471369ms","start":"2026-04-23T06:44:45.037164Z","end":"2026-04-23T06:44:45.139635Z","steps":["trace[142507924] 'read index received' (duration: 87.54478ms)","trace[142507924] 'applied index is now lower than readState.Index' (duration: 14.925879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T06:44:45.139787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.60526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/local-path-storage/\" range_end:\"/registry/jobs/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T06:44:45.139811Z","caller":"traceutil/trace.go:171","msg":"trace[1750524049] range","detail":"{range_begin:/registry/jobs/local-path-storage/; range_end:/registry/jobs/local-path-storage0; response_count:0; response_revision:1398; }","duration":"102.642181ms","start":"2026-04-23T06:44:45.03716Z","end":"2026-04-23T06:44:45.139803Z","steps":["trace[1750524049] 'agreement among raft nodes before linearized reading' (duration: 102.57094ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T06:44:45.139798Z","caller":"traceutil/trace.go:171","msg":"trace[1524607638] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"104.257392ms","start":"2026-04-23T06:44:45.035484Z","end":"2026-04-23T06:44:45.139741Z","steps":["trace[1524607638] 'process raft request' (duration: 89.268183ms)","trace[1524607638] 'compare' (duration: 14.805128ms)"],"step_count":2}