{"level":"warn","ts":"2026-08-19T09:07:51.793935Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:07:51.794038Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-08-19T09:07:51.794099Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:07:51.794108Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-08-19T09:07:51.794122Z","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-08-19T09:07:51.794706Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-08-19T09:07:51.794774Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-08-19T09:07:51.801255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.232411ms"} {"level":"info","ts":"2026-08-19T09:07:51.818453Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-08-19T09:07:51.818564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:07:51.818587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-08-19T09:07:51.818621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:07:51.818638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-08-19T09:07:51.818681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-08-19T09:07:51.830905Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:07:51.832958Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:07:51.83477Z","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-08-19T09:07:51.837002Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:07:51.837216Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:07:51.837545Z","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-08-19T09:07:51.837828Z","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-08-19T09:07:51.837881Z","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-08-19T09:07:51.840027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-08-19T09:07:51.840572Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-08-19T09:07:51.842415Z","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-08-19T09:07:51.842576Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-08-19T09:07:51.842603Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-08-19T09:07:51.842745Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:07:51.8428Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:07:52.819957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:07:52.82001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:07:52.820026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-08-19T09:07:52.820037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:07:52.820043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-08-19T09:07:52.820051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-08-19T09:07:52.820058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-08-19T09:07:52.820955Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:07:52.821028Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:07:52.821146Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:07:52.821241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:07:52.821561Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:07:52.821588Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:07:52.822474Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:07:52.82258Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:07:52.822616Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:07:52.824161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T09:07:52.825388Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-08-19T09:08:12.471446Z","caller":"traceutil/trace.go:171","msg":"trace[628571109] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"530.164324ms","start":"2026-08-19T09:08:11.94126Z","end":"2026-08-19T09:08:12.471424Z","steps":["trace[628571109] 'process raft request' (duration: 530.034591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:12.471644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.752645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T09:08:12.47169Z","caller":"traceutil/trace.go:171","msg":"trace[2104063972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:384; }","duration":"259.874818ms","start":"2026-08-19T09:08:12.211807Z","end":"2026-08-19T09:08:12.471682Z","steps":["trace[2104063972] 'agreement among raft nodes before linearized reading' (duration: 259.708014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:12.471944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:11.941246Z","time spent":"530.284446ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:12.471468Z","caller":"traceutil/trace.go:171","msg":"trace[1913909733] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:399; }","duration":"259.591771ms","start":"2026-08-19T09:08:12.211849Z","end":"2026-08-19T09:08:12.471441Z","steps":["trace[1913909733] 'read index received' (duration: 259.583391ms)","trace[1913909733] 'applied index is now lower than readState.Index' (duration: 6.93µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:12.472074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.110426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-19T09:08:12.4721Z","caller":"traceutil/trace.go:171","msg":"trace[18002491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"221.184847ms","start":"2026-08-19T09:08:12.250909Z","end":"2026-08-19T09:08:12.472093Z","steps":["trace[18002491] 'agreement among raft nodes before linearized reading' (duration: 221.145436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:15.031611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333890189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:08:15.532448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333890189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:08:16.033636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333890189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:08:16.427444Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.94732209s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:08:16.427832Z","caller":"traceutil/trace.go:171","msg":"trace[509465409] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"1.947785622s","start":"2026-08-19T09:08:14.480024Z","end":"2026-08-19T09:08:16.42781Z","steps":["trace[509465409] 'process raft request' (duration: 1.947626748s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:16.428001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:14.480006Z","time spent":"1.947922264s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:16.552396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999918139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-19T09:08:16.552481Z","caller":"traceutil/trace.go:171","msg":"trace[1843024123] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000060551s","start":"2026-08-19T09:08:14.552398Z","end":"2026-08-19T09:08:16.552459Z","steps":["trace[1843024123] 'agreement among raft nodes before linearized reading' (duration: 1.999943088s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:16.552519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:14.552383Z","time spent":"2.000129023s","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:08:16.603962Z","caller":"traceutil/trace.go:171","msg":"trace[1994403411] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:402; }","duration":"2.073123935s","start":"2026-08-19T09:08:14.530775Z","end":"2026-08-19T09:08:16.603899Z","steps":["trace[1994403411] 'read index received' (duration: 1.896848251s)","trace[1994403411] 'applied index is now lower than readState.Index' (duration: 176.274354ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:16.604291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.047549673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-19T09:08:16.604287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.073498505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T09:08:16.604368Z","caller":"traceutil/trace.go:171","msg":"trace[280540589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"2.047642045s","start":"2026-08-19T09:08:14.556707Z","end":"2026-08-19T09:08:16.604349Z","steps":["trace[280540589] 'agreement among raft nodes before linearized reading' (duration: 2.047464971s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:16.604376Z","caller":"traceutil/trace.go:171","msg":"trace[1088569899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"2.073639348s","start":"2026-08-19T09:08:14.530721Z","end":"2026-08-19T09:08:16.604361Z","steps":["trace[1088569899] 'agreement among raft nodes before linearized reading' (duration: 2.073444194s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:16.604426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:14.5307Z","time spent":"2.07371241s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:16.604473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833507979s","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-08-19T09:08:16.604497Z","caller":"traceutil/trace.go:171","msg":"trace[747825694] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"1.536290989s","start":"2026-08-19T09:08:15.067809Z","end":"2026-08-19T09:08:16.6041Z","steps":["trace[747825694] 'process raft request' (duration: 1.439864733s)","trace[747825694] 'compare' (duration: 96.094328ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:16.604513Z","caller":"traceutil/trace.go:171","msg":"trace[233547989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"1.833647581s","start":"2026-08-19T09:08:14.770851Z","end":"2026-08-19T09:08:16.604499Z","steps":["trace[233547989] 'agreement among raft nodes before linearized reading' (duration: 1.83356771s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:16.604495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.493574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-19T09:08:16.604551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:14.770832Z","time spent":"1.833709413s","remote":"127.0.0.1:40420","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":"info","ts":"2026-08-19T09:08:16.604569Z","caller":"traceutil/trace.go:171","msg":"trace[233016386] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:389; }","duration":"682.616367ms","start":"2026-08-19T09:08:15.921936Z","end":"2026-08-19T09:08:16.604552Z","steps":["trace[233016386] 'agreement among raft nodes before linearized reading' (duration: 682.393212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:16.604617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:15.921916Z","time spent":"682.693219ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-08-19T09:08:16.605366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:14.556662Z","time spent":"2.048689809s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:16.605571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:15.067787Z","time spent":"1.53675405s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:32.820543Z","caller":"traceutil/trace.go:171","msg":"trace[1524908080] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"268.688729ms","start":"2026-08-19T09:08:32.55183Z","end":"2026-08-19T09:08:32.820519Z","steps":["trace[1524908080] 'read index received' (duration: 268.422763ms)","trace[1524908080] 'applied index is now lower than readState.Index' (duration: 265.036µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:32.820702Z","caller":"traceutil/trace.go:171","msg":"trace[1844155573] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"320.975289ms","start":"2026-08-19T09:08:32.499681Z","end":"2026-08-19T09:08:32.820656Z","steps":["trace[1844155573] 'process raft request' (duration: 320.638062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:32.820749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.903584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:32.820946Z","caller":"traceutil/trace.go:171","msg":"trace[1825418702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"269.098638ms","start":"2026-08-19T09:08:32.551815Z","end":"2026-08-19T09:08:32.820914Z","steps":["trace[1825418702] 'agreement among raft nodes before linearized reading' (duration: 268.855343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:32.820984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.49966Z","time spent":"321.203155ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:33.300316Z","caller":"traceutil/trace.go:171","msg":"trace[595583153] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"737.112309ms","start":"2026-08-19T09:08:32.563171Z","end":"2026-08-19T09:08:33.300283Z","steps":["trace[595583153] 'process raft request' (duration: 736.877372ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:33.300557Z","caller":"traceutil/trace.go:171","msg":"trace[1204996114] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"730.104589ms","start":"2026-08-19T09:08:32.570443Z","end":"2026-08-19T09:08:33.300547Z","steps":["trace[1204996114] 'process raft request' (duration: 729.810922ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:33.300582Z","caller":"traceutil/trace.go:171","msg":"trace[404385690] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"701.924706ms","start":"2026-08-19T09:08:32.598645Z","end":"2026-08-19T09:08:33.30057Z","steps":["trace[404385690] 'process raft request' (duration: 701.64745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.300689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.570416Z","time spent":"730.195111ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:33.300726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.598629Z","time spent":"702.002638ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:33.300861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.563148Z","time spent":"737.424475ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:33.300953Z","caller":"traceutil/trace.go:171","msg":"trace[1083924785] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"687.090768ms","start":"2026-08-19T09:08:32.613852Z","end":"2026-08-19T09:08:33.300942Z","steps":["trace[1083924785] 'process raft request' (duration: 686.559046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.080565ms","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-08-19T09:08:33.301049Z","caller":"traceutil/trace.go:171","msg":"trace[1353390717] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"685.239987ms","start":"2026-08-19T09:08:32.6158Z","end":"2026-08-19T09:08:33.30104Z","steps":["trace[1353390717] 'process raft request' (duration: 684.650473ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:33.300944Z","caller":"traceutil/trace.go:171","msg":"trace[1073886009] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"554.99544ms","start":"2026-08-19T09:08:32.745918Z","end":"2026-08-19T09:08:33.300914Z","steps":["trace[1073886009] 'process raft request' (duration: 554.871187ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:33.301098Z","caller":"traceutil/trace.go:171","msg":"trace[1746109671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"592.224679ms","start":"2026-08-19T09:08:32.708861Z","end":"2026-08-19T09:08:33.301086Z","steps":["trace[1746109671] 'agreement among raft nodes before linearized reading' (duration: 592.059375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.301108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.615792Z","time spent":"685.287167ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:33.301139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.708843Z","time spent":"592.28559ms","remote":"127.0.0.1:40420","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":"info","ts":"2026-08-19T09:08:33.30037Z","caller":"traceutil/trace.go:171","msg":"trace[1003520208] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"737.121809ms","start":"2026-08-19T09:08:32.563202Z","end":"2026-08-19T09:08:33.300324Z","steps":["trace[1003520208] 'process raft request' (duration: 736.976875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.30124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.563176Z","time spent":"738.037909ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:33.301253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.745888Z","time spent":"555.277306ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:33.301413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.627852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-19T09:08:33.301455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.909509ms","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-08-19T09:08:33.301501Z","caller":"traceutil/trace.go:171","msg":"trace[1488023658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"583.759304ms","start":"2026-08-19T09:08:32.71772Z","end":"2026-08-19T09:08:33.301479Z","steps":["trace[1488023658] 'agreement among raft nodes before linearized reading' (duration: 583.55686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.30101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.613844Z","time spent":"687.143179ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:33.301523Z","caller":"traceutil/trace.go:171","msg":"trace[2110788061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"576.994141ms","start":"2026-08-19T09:08:32.724508Z","end":"2026-08-19T09:08:33.301502Z","steps":["trace[2110788061] 'agreement among raft nodes before linearized reading' (duration: 576.842448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.301575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.717706Z","time spent":"583.858917ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:33.301618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.724499Z","time spent":"577.106133ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:08:33.300883Z","caller":"traceutil/trace.go:171","msg":"trace[554044223] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"677.859288ms","start":"2026-08-19T09:08:32.623013Z","end":"2026-08-19T09:08:33.300872Z","steps":["trace[554044223] 'process raft request' (duration: 677.480709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.301763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.622999Z","time spent":"678.708528ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:33.300515Z","caller":"traceutil/trace.go:171","msg":"trace[1783563189] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"701.780544ms","start":"2026-08-19T09:08:32.598722Z","end":"2026-08-19T09:08:33.300502Z","steps":["trace[1783563189] 'process raft request' (duration: 701.61094ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:33.300874Z","caller":"traceutil/trace.go:171","msg":"trace[861959428] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:512; }","duration":"480.196477ms","start":"2026-08-19T09:08:32.820655Z","end":"2026-08-19T09:08:33.300851Z","steps":["trace[861959428] 'read index received' (duration: 479.387398ms)","trace[861959428] 'applied index is now lower than readState.Index' (duration: 807.469µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:33.301878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.598704Z","time spent":"703.117183ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:33.300916Z","caller":"traceutil/trace.go:171","msg":"trace[93013654] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"687.446937ms","start":"2026-08-19T09:08:32.613461Z","end":"2026-08-19T09:08:33.300908Z","steps":["trace[93013654] 'process raft request' (duration: 686.907265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.301992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.61344Z","time spent":"688.528531ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:33.30201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.636963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:33.300419Z","caller":"traceutil/trace.go:171","msg":"trace[726913095] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"728.896322ms","start":"2026-08-19T09:08:32.571474Z","end":"2026-08-19T09:08:33.300371Z","steps":["trace[726913095] 'process raft request' (duration: 728.742318ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:33.302056Z","caller":"traceutil/trace.go:171","msg":"trace[2032159084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"479.711845ms","start":"2026-08-19T09:08:32.822331Z","end":"2026-08-19T09:08:33.302042Z","steps":["trace[2032159084] 'agreement among raft nodes before linearized reading' (duration: 479.641523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.302096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.822318Z","time spent":"479.768836ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:08:33.302105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.571467Z","time spent":"730.60628ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:33.301373Z","caller":"traceutil/trace.go:171","msg":"trace[1049114455] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"649.967343ms","start":"2026-08-19T09:08:32.651388Z","end":"2026-08-19T09:08:33.301355Z","steps":["trace[1049114455] 'process raft request' (duration: 649.331248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:33.302264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:32.651365Z","time spent":"650.874993ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:36.201929Z","caller":"traceutil/trace.go:171","msg":"trace[953396999] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"155.182134ms","start":"2026-08-19T09:08:36.046721Z","end":"2026-08-19T09:08:36.201903Z","steps":["trace[953396999] 'process raft request' (duration: 152.608036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:40.154469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.880915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:40.154585Z","caller":"traceutil/trace.go:171","msg":"trace[815617708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"601.052549ms","start":"2026-08-19T09:08:39.553508Z","end":"2026-08-19T09:08:40.15456Z","steps":["trace[815617708] 'range keys from in-memory index tree' (duration: 600.784292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:40.154648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:39.55349Z","time spent":"601.147231ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:08:40.154611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.764584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k2dm7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-19T09:08:40.154876Z","caller":"traceutil/trace.go:171","msg":"trace[2023675937] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k2dm7; range_end:; response_count:1; response_revision:604; }","duration":"580.072212ms","start":"2026-08-19T09:08:39.574788Z","end":"2026-08-19T09:08:40.15486Z","steps":["trace[2023675937] 'range keys from in-memory index tree' (duration: 579.560039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:40.155011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:39.57477Z","time spent":"580.230805ms","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-k2dm7\" "} {"level":"warn","ts":"2026-08-19T09:08:40.65719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333890589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:08:40.753729Z","caller":"traceutil/trace.go:171","msg":"trace[1668269315] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"886.694383ms","start":"2026-08-19T09:08:39.867002Z","end":"2026-08-19T09:08:40.753696Z","steps":["trace[1668269315] 'process raft request' (duration: 882.910287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:40.753882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:39.86697Z","time spent":"886.829967ms","remote":"127.0.0.1:40244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:41.791565Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.044213942s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:08:41.836259Z","caller":"traceutil/trace.go:171","msg":"trace[175942567] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"1.680099174s","start":"2026-08-19T09:08:40.156109Z","end":"2026-08-19T09:08:41.836208Z","steps":["trace[175942567] 'read index received' (duration: 591.322217ms)","trace[175942567] 'applied index is now lower than readState.Index' (duration: 1.088775837s)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:41.836358Z","caller":"traceutil/trace.go:171","msg":"trace[21541518] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.668559792s","start":"2026-08-19T09:08:40.167785Z","end":"2026-08-19T09:08:41.836345Z","steps":["trace[21541518] 'process raft request' (duration: 1.623956146s)","trace[21541518] 'compare' (duration: 44.35595ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:41.836443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68032515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:41.836477Z","caller":"traceutil/trace.go:171","msg":"trace[586538705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.680377911s","start":"2026-08-19T09:08:40.156089Z","end":"2026-08-19T09:08:41.836467Z","steps":["trace[586538705] 'agreement among raft nodes before linearized reading' (duration: 1.680322209s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:41.836499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:40.156077Z","time spent":"1.680417431s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:08:41.836527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:40.167756Z","time spent":"1.668627973s","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:42.336457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333890594,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:08:42.836592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333890594,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:08:43.319535Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.527719803s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:08:43.369697Z","caller":"traceutil/trace.go:171","msg":"trace[350299421] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"2.553627849s","start":"2026-08-19T09:08:40.816036Z","end":"2026-08-19T09:08:43.369664Z","steps":["trace[350299421] 'process raft request' (duration: 2.503698061s)","trace[350299421] 'compare' (duration: 48.932166ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:43.369832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:40.816006Z","time spent":"2.553765622s","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:43.845976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001202737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:08:43.846055Z","caller":"traceutil/trace.go:171","msg":"trace[1007153183] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00133915s","start":"2026-08-19T09:08:41.844699Z","end":"2026-08-19T09:08:43.846039Z","steps":["trace[1007153183] 'agreement among raft nodes before linearized reading' (duration: 2.001243798s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:43.846094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:41.844685Z","time spent":"2.001401461s","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:08:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:08:44.266462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.05735ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-19T09:08:44.266563Z","caller":"traceutil/trace.go:171","msg":"trace[551065942] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"2.430227578s","start":"2026-08-19T09:08:41.836315Z","end":"2026-08-19T09:08:44.266543Z","steps":["trace[551065942] 'read index received' (duration: 1.483361143s)","trace[551065942] 'applied index is now lower than readState.Index' (duration: 946.864785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:44.266712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.561739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:44.266756Z","caller":"traceutil/trace.go:171","msg":"trace[894647799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"420.64157ms","start":"2026-08-19T09:08:43.846102Z","end":"2026-08-19T09:08:44.266743Z","steps":["trace[894647799] 'agreement among raft nodes before linearized reading' (duration: 420.581009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.266766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.870193558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-19T09:08:44.267124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.09455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-19T09:08:44.267133Z","caller":"traceutil/trace.go:171","msg":"trace[744215620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"2.870581546s","start":"2026-08-19T09:08:41.396535Z","end":"2026-08-19T09:08:44.267116Z","steps":["trace[744215620] 'agreement among raft nodes before linearized reading' (duration: 2.870097155s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.266778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.910342933s","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-08-19T09:08:44.2672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:41.39652Z","time spent":"2.870667579s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-19T09:08:44.267263Z","caller":"traceutil/trace.go:171","msg":"trace[266634541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"2.910840804s","start":"2026-08-19T09:08:41.356362Z","end":"2026-08-19T09:08:44.267203Z","steps":["trace[266634541] 'agreement among raft nodes before linearized reading' (duration: 2.910245681s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:44.267352Z","caller":"traceutil/trace.go:171","msg":"trace[774641087] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:607; }","duration":"893.174361ms","start":"2026-08-19T09:08:43.373985Z","end":"2026-08-19T09:08:44.26716Z","steps":["trace[774641087] 'agreement among raft nodes before linearized reading' (duration: 892.69479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.267448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:43.373968Z","time spent":"893.468038ms","remote":"127.0.0.1:40360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-19T09:08:44.267358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:41.356338Z","time spent":"2.910990458s","remote":"127.0.0.1:40420","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-08-19T09:08:44.2669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.916978264s","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-08-19T09:08:44.26763Z","caller":"traceutil/trace.go:171","msg":"trace[2110934793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"2.917799003s","start":"2026-08-19T09:08:41.349816Z","end":"2026-08-19T09:08:44.267615Z","steps":["trace[2110934793] 'agreement among raft nodes before linearized reading' (duration: 2.91680365s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.267681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:41.349788Z","time spent":"2.917878735s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:44.266799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:43.846082Z","time spent":"420.705942ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:08:44.268601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.411518887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T09:08:44.268752Z","caller":"traceutil/trace.go:171","msg":"trace[498185186] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"3.412783886s","start":"2026-08-19T09:08:40.85595Z","end":"2026-08-19T09:08:44.268734Z","steps":["trace[498185186] 'agreement among raft nodes before linearized reading' (duration: 3.410706729s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.268949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:40.855935Z","time spent":"3.413000101s","remote":"127.0.0.1:40420","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-08-19T09:08:44.386341Z","caller":"traceutil/trace.go:171","msg":"trace[2068594829] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"115.171183ms","start":"2026-08-19T09:08:44.271095Z","end":"2026-08-19T09:08:44.386266Z","steps":["trace[2068594829] 'read index received' (duration: 114.936668ms)","trace[2068594829] 'applied index is now lower than readState.Index' (duration: 233.335µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:44.386479Z","caller":"traceutil/trace.go:171","msg":"trace[1333621110] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"115.95196ms","start":"2026-08-19T09:08:44.27047Z","end":"2026-08-19T09:08:44.386422Z","steps":["trace[1333621110] 'process raft request' (duration: 115.581792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.386521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.400858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-19T09:08:44.386571Z","caller":"traceutil/trace.go:171","msg":"trace[825178823] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:608; }","duration":"115.482359ms","start":"2026-08-19T09:08:44.271075Z","end":"2026-08-19T09:08:44.386558Z","steps":["trace[825178823] 'agreement among raft nodes before linearized reading' (duration: 115.381977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.392741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.144219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cd29b7d19a9d1f\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-08-19T09:08:44.392785Z","caller":"traceutil/trace.go:171","msg":"trace[1472289230] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cd29b7d19a9d1f; range_end:; response_count:1; response_revision:611; }","duration":"121.232131ms","start":"2026-08-19T09:08:44.271539Z","end":"2026-08-19T09:08:44.392771Z","steps":["trace[1472289230] 'agreement among raft nodes before linearized reading' (duration: 121.125539ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:44.393063Z","caller":"traceutil/trace.go:171","msg":"trace[1632517681] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"120.490645ms","start":"2026-08-19T09:08:44.272564Z","end":"2026-08-19T09:08:44.393055Z","steps":["trace[1632517681] 'process raft request' (duration: 119.898331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:44.393307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.849806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:44.393324Z","caller":"traceutil/trace.go:171","msg":"trace[746580255] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"120.653368ms","start":"2026-08-19T09:08:44.272657Z","end":"2026-08-19T09:08:44.39331Z","steps":["trace[746580255] 'process raft request' (duration: 119.925812ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:44.393348Z","caller":"traceutil/trace.go:171","msg":"trace[1560707391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"118.926218ms","start":"2026-08-19T09:08:44.274411Z","end":"2026-08-19T09:08:44.393337Z","steps":["trace[1560707391] 'agreement among raft nodes before linearized reading' (duration: 118.839816ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:44.393517Z","caller":"traceutil/trace.go:171","msg":"trace[1377132953] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"119.01225ms","start":"2026-08-19T09:08:44.274491Z","end":"2026-08-19T09:08:44.393503Z","steps":["trace[1377132953] 'process raft request' (duration: 118.14117ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:46.208985Z","caller":"traceutil/trace.go:171","msg":"trace[1871994186] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"285.243486ms","start":"2026-08-19T09:08:45.923719Z","end":"2026-08-19T09:08:46.208963Z","steps":["trace[1871994186] 'read index received' (duration: 285.044541ms)","trace[1871994186] 'applied index is now lower than readState.Index' (duration: 198.025µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:46.209038Z","caller":"traceutil/trace.go:171","msg":"trace[1181518507] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"487.230856ms","start":"2026-08-19T09:08:45.721774Z","end":"2026-08-19T09:08:46.209005Z","steps":["trace[1181518507] 'process raft request' (duration: 487.049151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:46.209281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:45.721759Z","time spent":"487.464312ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:08:46.209321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.577114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T09:08:46.209453Z","caller":"traceutil/trace.go:171","msg":"trace[79325432] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:629; }","duration":"285.767078ms","start":"2026-08-19T09:08:45.923669Z","end":"2026-08-19T09:08:46.209437Z","steps":["trace[79325432] 'agreement among raft nodes before linearized reading' (duration: 285.449991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:48.961584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.644965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-08-19T09:08:48.961696Z","caller":"traceutil/trace.go:171","msg":"trace[2115730129] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:687; }","duration":"141.792828ms","start":"2026-08-19T09:08:48.819873Z","end":"2026-08-19T09:08:48.961666Z","steps":["trace[2115730129] 'range keys from in-memory index tree' (duration: 141.4343ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:56.828158Z","caller":"traceutil/trace.go:171","msg":"trace[37449958] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"388.228982ms","start":"2026-08-19T09:08:56.439899Z","end":"2026-08-19T09:08:56.828128Z","steps":["trace[37449958] 'process raft request' (duration: 388.043948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:56.828331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:56.439873Z","time spent":"388.361234ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:56.830392Z","caller":"traceutil/trace.go:171","msg":"trace[25942609] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:776; }","duration":"385.195722ms","start":"2026-08-19T09:08:56.445177Z","end":"2026-08-19T09:08:56.830373Z","steps":["trace[25942609] 'read index received' (duration: 382.896499ms)","trace[25942609] 'applied index is now lower than readState.Index' (duration: 2.298203ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:56.830514Z","caller":"traceutil/trace.go:171","msg":"trace[621295319] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"389.582352ms","start":"2026-08-19T09:08:56.440899Z","end":"2026-08-19T09:08:56.830481Z","steps":["trace[621295319] 'process raft request' (duration: 388.647631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:56.830736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:56.440882Z","time spent":"389.773047ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:56.830808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.626882ms","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-08-19T09:08:56.83085Z","caller":"traceutil/trace.go:171","msg":"trace[593623242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"385.691123ms","start":"2026-08-19T09:08:56.445147Z","end":"2026-08-19T09:08:56.830838Z","steps":["trace[593623242] 'agreement among raft nodes before linearized reading' (duration: 385.428858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:56.830884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:56.445137Z","time spent":"385.740374ms","remote":"127.0.0.1:40420","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-08-19T09:08:56.83089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.760149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:08:56.830963Z","caller":"traceutil/trace.go:171","msg":"trace[1185239977] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:750; }","duration":"289.890322ms","start":"2026-08-19T09:08:56.54106Z","end":"2026-08-19T09:08:56.830951Z","steps":["trace[1185239977] 'agreement among raft nodes before linearized reading' (duration: 289.775289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:56.83103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.014376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:56.83107Z","caller":"traceutil/trace.go:171","msg":"trace[1388975279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"276.088597ms","start":"2026-08-19T09:08:56.554968Z","end":"2026-08-19T09:08:56.831056Z","steps":["trace[1388975279] 'agreement among raft nodes before linearized reading' (duration: 276.008876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:56.83106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.967791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:08:56.831146Z","caller":"traceutil/trace.go:171","msg":"trace[650657913] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:750; }","duration":"271.089333ms","start":"2026-08-19T09:08:56.56002Z","end":"2026-08-19T09:08:56.831109Z","steps":["trace[650657913] 'agreement among raft nodes before linearized reading' (duration: 270.93509ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:56.830526Z","caller":"traceutil/trace.go:171","msg":"trace[448155211] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"388.018717ms","start":"2026-08-19T09:08:56.442488Z","end":"2026-08-19T09:08:56.830507Z","steps":["trace[448155211] 'process raft request' (duration: 387.756231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:56.831337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:56.442478Z","time spent":"388.810365ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:00.399576Z","caller":"traceutil/trace.go:171","msg":"trace[435602391] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"704.73395ms","start":"2026-08-19T09:08:59.694813Z","end":"2026-08-19T09:09:00.399547Z","steps":["trace[435602391] 'process raft request' (duration: 703.632495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:00.399736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:59.694774Z","time spent":"704.875363ms","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:14.278559Z","caller":"traceutil/trace.go:171","msg":"trace[1708800436] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"105.205903ms","start":"2026-08-19T09:09:14.173326Z","end":"2026-08-19T09:09:14.278532Z","steps":["trace[1708800436] 'process raft request' (duration: 105.06906ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:14.27863Z","caller":"traceutil/trace.go:171","msg":"trace[121383881] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"105.323646ms","start":"2026-08-19T09:09:14.173284Z","end":"2026-08-19T09:09:14.278607Z","steps":["trace[121383881] 'process raft request' (duration: 104.264132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:15.408694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891235,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:09:15.618439Z","caller":"traceutil/trace.go:171","msg":"trace[432221808] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"710.344168ms","start":"2026-08-19T09:09:14.908078Z","end":"2026-08-19T09:09:15.618422Z","steps":["trace[432221808] 'read index received' (duration: 710.189625ms)","trace[432221808] 'applied index is now lower than readState.Index' (duration: 153.823µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:15.618537Z","caller":"traceutil/trace.go:171","msg":"trace[1909439228] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"749.746598ms","start":"2026-08-19T09:09:14.868763Z","end":"2026-08-19T09:09:15.61851Z","steps":["trace[1909439228] 'process raft request' (duration: 749.549004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:15.618671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.572973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-19T09:09:15.618675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:14.868738Z","time spent":"749.859041ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:15.618698Z","caller":"traceutil/trace.go:171","msg":"trace[1926293512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"710.656365ms","start":"2026-08-19T09:09:14.908034Z","end":"2026-08-19T09:09:15.61869Z","steps":["trace[1926293512] 'agreement among raft nodes before linearized reading' (duration: 710.502282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:15.618725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:14.908017Z","time spent":"710.702056ms","remote":"127.0.0.1:40420","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-08-19T09:09:15.618874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.853716ms","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-08-19T09:09:15.618889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.435206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-19T09:09:15.618909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.805785ms","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-08-19T09:09:15.618942Z","caller":"traceutil/trace.go:171","msg":"trace[644285179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:910; }","duration":"709.936338ms","start":"2026-08-19T09:09:14.908972Z","end":"2026-08-19T09:09:15.618908Z","steps":["trace[644285179] 'agreement among raft nodes before linearized reading' (duration: 709.807825ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:15.618969Z","caller":"traceutil/trace.go:171","msg":"trace[1917381757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"709.896417ms","start":"2026-08-19T09:09:14.909063Z","end":"2026-08-19T09:09:15.61896Z","steps":["trace[1917381757] 'agreement among raft nodes before linearized reading' (duration: 709.768514ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:15.618965Z","caller":"traceutil/trace.go:171","msg":"trace[445568497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"709.525019ms","start":"2026-08-19T09:09:14.909426Z","end":"2026-08-19T09:09:15.618951Z","steps":["trace[445568497] 'agreement among raft nodes before linearized reading' (duration: 709.359565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:15.618975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:14.908953Z","time spent":"710.015329ms","remote":"127.0.0.1:40420","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-08-19T09:09:15.618992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:14.909047Z","time spent":"709.938838ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:09:15.619002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:14.909419Z","time spent":"709.57488ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:09:16.206883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:09:16.592478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.606596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:09:16.592662Z","caller":"traceutil/trace.go:171","msg":"trace[677114242] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:943; }","duration":"886.558704ms","start":"2026-08-19T09:09:15.706089Z","end":"2026-08-19T09:09:16.592647Z","steps":["trace[677114242] 'read index received' (duration: 743.733401ms)","trace[677114242] 'applied index is now lower than readState.Index' (duration: 142.824183ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:16.59273Z","caller":"traceutil/trace.go:171","msg":"trace[1841353222] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"969.12488ms","start":"2026-08-19T09:09:15.623598Z","end":"2026-08-19T09:09:16.592723Z","steps":["trace[1841353222] 'process raft request' (duration: 969.001767ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:16.592714Z","caller":"traceutil/trace.go:171","msg":"trace[2022270422] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"969.175111ms","start":"2026-08-19T09:09:15.623517Z","end":"2026-08-19T09:09:16.592692Z","steps":["trace[2022270422] 'process raft request' (duration: 969.050757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:16.592772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:15.623593Z","time spent":"969.15632ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:16.592829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:15.623499Z","time spent":"969.270993ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:16.592925Z","caller":"traceutil/trace.go:171","msg":"trace[299345984] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"968.326421ms","start":"2026-08-19T09:09:15.62459Z","end":"2026-08-19T09:09:16.592916Z","steps":["trace[299345984] 'process raft request' (duration: 968.032954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:16.592966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:15.624582Z","time spent":"968.364243ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:16.592986Z","caller":"traceutil/trace.go:171","msg":"trace[1885847790] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"969.688603ms","start":"2026-08-19T09:09:15.623289Z","end":"2026-08-19T09:09:16.592977Z","steps":["trace[1885847790] 'process raft request' (duration: 826.512843ms)","trace[1885847790] 'compare' (duration: 142.501714ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:09:16.593085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:15.62326Z","time spent":"969.796375ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:16.593158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.370069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-19T09:09:16.593226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.806276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T09:09:16.593179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.096556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:16.593257Z","caller":"traceutil/trace.go:171","msg":"trace[2021593360] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:914; }","duration":"245.867577ms","start":"2026-08-19T09:09:16.347379Z","end":"2026-08-19T09:09:16.593246Z","steps":["trace[2021593360] 'agreement among raft nodes before linearized reading' (duration: 245.825187ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:16.593264Z","caller":"traceutil/trace.go:171","msg":"trace[1354339002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"887.216509ms","start":"2026-08-19T09:09:15.706041Z","end":"2026-08-19T09:09:16.593257Z","steps":["trace[1354339002] 'agreement among raft nodes before linearized reading' (duration: 887.101186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:16.593294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:15.706027Z","time spent":"887.260159ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:09:16.593224Z","caller":"traceutil/trace.go:171","msg":"trace[1357962609] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:914; }","duration":"668.523063ms","start":"2026-08-19T09:09:15.924686Z","end":"2026-08-19T09:09:16.593209Z","steps":["trace[1357962609] 'agreement among raft nodes before linearized reading' (duration: 668.259837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:16.593352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:15.924662Z","time spent":"668.665785ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"info","ts":"2026-08-19T09:09:17.216342Z","caller":"traceutil/trace.go:171","msg":"trace[1898450168] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"618.916419ms","start":"2026-08-19T09:09:16.597411Z","end":"2026-08-19T09:09:17.216327Z","steps":["trace[1898450168] 'process raft request' (duration: 618.808367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:17.216447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:16.597394Z","time spent":"618.998381ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:18.055305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:09:18.55579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:09:18.95428Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.737668657s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-19T09:09:18.956158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269108112s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:18.956267Z","caller":"traceutil/trace.go:171","msg":"trace[540370085] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:915; }","duration":"1.269223905s","start":"2026-08-19T09:09:17.687026Z","end":"2026-08-19T09:09:18.95625Z","steps":["trace[540370085] 'range keys from in-memory index tree' (duration: 1.269094422s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:18.956994Z","caller":"traceutil/trace.go:171","msg":"trace[442125625] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:949; }","duration":"1.402637183s","start":"2026-08-19T09:09:17.554335Z","end":"2026-08-19T09:09:18.956972Z","steps":["trace[442125625] 'read index received' (duration: 1.40031335s)","trace[442125625] 'applied index is now lower than readState.Index' (duration: 2.322183ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:18.95712Z","caller":"traceutil/trace.go:171","msg":"trace[1588646197] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"2.293822411s","start":"2026-08-19T09:09:16.663284Z","end":"2026-08-19T09:09:18.957106Z","steps":["trace[1588646197] 'process raft request' (duration: 2.291340404s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:18.957211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:16.663252Z","time spent":"2.293898263s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:18.957544Z","caller":"traceutil/trace.go:171","msg":"trace[1266610384] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"1.422372084s","start":"2026-08-19T09:09:17.53516Z","end":"2026-08-19T09:09:18.957532Z","steps":["trace[1266610384] 'process raft request' (duration: 1.421707738s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:18.957606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:17.535137Z","time spent":"1.422435025s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:18.957746Z","caller":"traceutil/trace.go:171","msg":"trace[249962787] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.334671181s","start":"2026-08-19T09:09:17.623066Z","end":"2026-08-19T09:09:18.957737Z","steps":["trace[249962787] 'process raft request' (duration: 1.333876313s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:18.957812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:17.623045Z","time spent":"1.334724172s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:18.957944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403614675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:18.957973Z","caller":"traceutil/trace.go:171","msg":"trace[921206080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"1.403674406s","start":"2026-08-19T09:09:17.554289Z","end":"2026-08-19T09:09:18.957964Z","steps":["trace[921206080] 'agreement among raft nodes before linearized reading' (duration: 1.403628645s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:18.958001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:17.554273Z","time spent":"1.403722057s","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:09:18.958417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.701303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T09:09:18.958457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.449217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T09:09:18.958478Z","caller":"traceutil/trace.go:171","msg":"trace[891355234] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:918; }","duration":"150.802875ms","start":"2026-08-19T09:09:18.807659Z","end":"2026-08-19T09:09:18.958462Z","steps":["trace[891355234] 'agreement among raft nodes before linearized reading' (duration: 150.678573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:18.958502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.106099ms","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-08-19T09:09:18.958512Z","caller":"traceutil/trace.go:171","msg":"trace[2112321360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"361.529339ms","start":"2026-08-19T09:09:18.596966Z","end":"2026-08-19T09:09:18.958496Z","steps":["trace[2112321360] 'agreement among raft nodes before linearized reading' (duration: 361.357585ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:18.958538Z","caller":"traceutil/trace.go:171","msg":"trace[1382714679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"361.181291ms","start":"2026-08-19T09:09:18.597346Z","end":"2026-08-19T09:09:18.958527Z","steps":["trace[1382714679] 'agreement among raft nodes before linearized reading' (duration: 361.082259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:18.95854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.542506ms","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-08-19T09:09:18.95854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.992326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T09:09:18.958608Z","caller":"traceutil/trace.go:171","msg":"trace[1756394733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"360.668949ms","start":"2026-08-19T09:09:18.597921Z","end":"2026-08-19T09:09:18.95859Z","steps":["trace[1756394733] 'agreement among raft nodes before linearized reading' (duration: 360.414074ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:18.958614Z","caller":"traceutil/trace.go:171","msg":"trace[698426541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"361.16451ms","start":"2026-08-19T09:09:18.597434Z","end":"2026-08-19T09:09:18.958598Z","steps":["trace[698426541] 'agreement among raft nodes before linearized reading' (duration: 360.944415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:18.958654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.597413Z","time spent":"361.233872ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:09:18.958663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.597887Z","time spent":"360.761802ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:09:18.958565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.597325Z","time spent":"361.233993ms","remote":"127.0.0.1:40420","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-08-19T09:09:18.958561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.596952Z","time spent":"361.59317ms","remote":"127.0.0.1:40420","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-08-19T09:09:19.27342Z","caller":"traceutil/trace.go:171","msg":"trace[824214164] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"310.179715ms","start":"2026-08-19T09:09:18.963221Z","end":"2026-08-19T09:09:19.273401Z","steps":["trace[824214164] 'process raft request' (duration: 257.915561ms)","trace[824214164] 'compare' (duration: 52.10751ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:09:19.273549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.96321Z","time spent":"310.277158ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:19.347223Z","caller":"traceutil/trace.go:171","msg":"trace[1341179765] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"383.857789ms","start":"2026-08-19T09:09:18.963336Z","end":"2026-08-19T09:09:19.347194Z","steps":["trace[1341179765] 'process raft request' (duration: 383.161734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:19.347391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.963248Z","time spent":"384.061674ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:19.351752Z","caller":"traceutil/trace.go:171","msg":"trace[1595101844] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:952; }","duration":"387.643375ms","start":"2026-08-19T09:09:18.964027Z","end":"2026-08-19T09:09:19.351671Z","steps":["trace[1595101844] 'read index received' (duration: 257.102373ms)","trace[1595101844] 'applied index is now lower than readState.Index' (duration: 130.539322ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:19.35205Z","caller":"traceutil/trace.go:171","msg":"trace[1384214180] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"387.987823ms","start":"2026-08-19T09:09:18.964028Z","end":"2026-08-19T09:09:19.352016Z","steps":["trace[1384214180] 'process raft request' (duration: 387.535372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:19.352071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.033504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-19T09:09:19.352133Z","caller":"traceutil/trace.go:171","msg":"trace[1510548144] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:922; }","duration":"388.099726ms","start":"2026-08-19T09:09:18.964008Z","end":"2026-08-19T09:09:19.352108Z","steps":["trace[1510548144] 'agreement among raft nodes before linearized reading' (duration: 388.004964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:19.352158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.964002Z","time spent":"388.150587ms","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-19T09:09:19.35205Z","caller":"traceutil/trace.go:171","msg":"trace[507092980] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"388.69293ms","start":"2026-08-19T09:09:18.963334Z","end":"2026-08-19T09:09:19.352027Z","steps":["trace[507092980] 'process raft request' (duration: 383.266606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:19.352228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.964022Z","time spent":"388.091466ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:19.352291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.963251Z","time spent":"388.998137ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:19.360105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.891715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:19.36015Z","caller":"traceutil/trace.go:171","msg":"trace[1464876631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"392.959616ms","start":"2026-08-19T09:09:18.967178Z","end":"2026-08-19T09:09:19.360138Z","steps":["trace[1464876631] 'agreement among raft nodes before linearized reading' (duration: 392.864604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:19.360174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:18.967159Z","time spent":"393.009718ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:09:19.360189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.237764ms","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-08-19T09:09:19.360221Z","caller":"traceutil/trace.go:171","msg":"trace[1148558730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"140.306755ms","start":"2026-08-19T09:09:19.219905Z","end":"2026-08-19T09:09:19.360212Z","steps":["trace[1148558730] 'agreement among raft nodes before linearized reading' (duration: 140.171182ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:28.214002Z","caller":"traceutil/trace.go:171","msg":"trace[1341472633] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"676.77156ms","start":"2026-08-19T09:09:27.537208Z","end":"2026-08-19T09:09:28.21398Z","steps":["trace[1341472633] 'process raft request' (duration: 676.644337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:28.214132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:27.537184Z","time spent":"676.882533ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:28.624403Z","caller":"traceutil/trace.go:171","msg":"trace[98248691] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.087088593s","start":"2026-08-19T09:09:27.537291Z","end":"2026-08-19T09:09:28.624379Z","steps":["trace[98248691] 'process raft request' (duration: 1.086806107s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:28.624494Z","caller":"traceutil/trace.go:171","msg":"trace[479870596] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.087060453s","start":"2026-08-19T09:09:27.537407Z","end":"2026-08-19T09:09:28.624467Z","steps":["trace[479870596] 'process raft request' (duration: 1.086884809s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:28.624517Z","caller":"traceutil/trace.go:171","msg":"trace[596374945] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.086644764s","start":"2026-08-19T09:09:27.53785Z","end":"2026-08-19T09:09:28.624495Z","steps":["trace[596374945] 'process raft request' (duration: 1.086489129s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:28.624542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:27.537272Z","time spent":"1.087190936s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:28.62463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:27.537385Z","time spent":"1.087176416s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:28.624643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:27.537833Z","time spent":"1.086740436s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:29.61252Z","caller":"traceutil/trace.go:171","msg":"trace[190796998] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"226.434853ms","start":"2026-08-19T09:09:29.386063Z","end":"2026-08-19T09:09:29.612497Z","steps":["trace[190796998] 'read index received' (duration: 226.30933ms)","trace[190796998] 'applied index is now lower than readState.Index' (duration: 124.383µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:29.612592Z","caller":"traceutil/trace.go:171","msg":"trace[1612168341] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"280.269353ms","start":"2026-08-19T09:09:29.332282Z","end":"2026-08-19T09:09:29.612551Z","steps":["trace[1612168341] 'process raft request' (duration: 280.053168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:29.612678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.599557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:29.612713Z","caller":"traceutil/trace.go:171","msg":"trace[1058480858] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:956; }","duration":"226.653868ms","start":"2026-08-19T09:09:29.386049Z","end":"2026-08-19T09:09:29.612703Z","steps":["trace[1058480858] 'agreement among raft nodes before linearized reading' (duration: 226.556866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:29.617466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.689635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T09:09:29.617538Z","caller":"traceutil/trace.go:171","msg":"trace[235388508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"200.813297ms","start":"2026-08-19T09:09:29.416709Z","end":"2026-08-19T09:09:29.617522Z","steps":["trace[235388508] 'agreement among raft nodes before linearized reading' (duration: 200.617073ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:29.803088Z","caller":"traceutil/trace.go:171","msg":"trace[1366278600] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"138.428192ms","start":"2026-08-19T09:09:29.664639Z","end":"2026-08-19T09:09:29.803067Z","steps":["trace[1366278600] 'process raft request' (duration: 79.330312ms)","trace[1366278600] 'compare' (duration: 59.010458ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:31.828897Z","caller":"traceutil/trace.go:171","msg":"trace[549806025] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"196.763093ms","start":"2026-08-19T09:09:31.632114Z","end":"2026-08-19T09:09:31.828877Z","steps":["trace[549806025] 'process raft request' (duration: 196.62449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:32.278345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.237244ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-08-19T09:09:32.537675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.53948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:09:32.53776Z","caller":"traceutil/trace.go:171","msg":"trace[1506055964] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:966; }","duration":"154.654562ms","start":"2026-08-19T09:09:32.383086Z","end":"2026-08-19T09:09:32.537741Z","steps":["trace[1506055964] 'count revisions from in-memory index tree' (duration: 154.446837ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:34.917533Z","caller":"traceutil/trace.go:171","msg":"trace[769831628] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"142.909855ms","start":"2026-08-19T09:09:34.774527Z","end":"2026-08-19T09:09:34.917437Z","steps":["trace[769831628] 'process raft request' (duration: 71.703738ms)","trace[769831628] 'compare' (duration: 70.830178ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:35.417729Z","caller":"traceutil/trace.go:171","msg":"trace[261238318] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"159.036573ms","start":"2026-08-19T09:09:35.258669Z","end":"2026-08-19T09:09:35.417705Z","steps":["trace[261238318] 'process raft request' (duration: 158.90942ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:39.298375Z","caller":"traceutil/trace.go:171","msg":"trace[2016855715] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"339.056875ms","start":"2026-08-19T09:09:38.9593Z","end":"2026-08-19T09:09:39.298357Z","steps":["trace[2016855715] 'read index received' (duration: 338.968493ms)","trace[2016855715] 'applied index is now lower than readState.Index' (duration: 87.512µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:39.298509Z","caller":"traceutil/trace.go:171","msg":"trace[1748083923] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"451.72977ms","start":"2026-08-19T09:09:38.846738Z","end":"2026-08-19T09:09:39.298468Z","steps":["trace[1748083923] 'process raft request' (duration: 451.497504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:39.298614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.27588ms","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-08-19T09:09:39.298669Z","caller":"traceutil/trace.go:171","msg":"trace[818493890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"339.401413ms","start":"2026-08-19T09:09:38.959257Z","end":"2026-08-19T09:09:39.298658Z","steps":["trace[818493890] 'agreement among raft nodes before linearized reading' (duration: 339.231839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:39.298685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:38.846715Z","time spent":"451.876942ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:39.298717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:38.959241Z","time spent":"339.467924ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:09:42.072151Z","caller":"traceutil/trace.go:171","msg":"trace[465854716] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"142.482635ms","start":"2026-08-19T09:09:41.92963Z","end":"2026-08-19T09:09:42.072113Z","steps":["trace[465854716] 'process raft request' (duration: 142.354762ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:43.475488Z","caller":"traceutil/trace.go:171","msg":"trace[1154036928] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"113.465843ms","start":"2026-08-19T09:09:43.361996Z","end":"2026-08-19T09:09:43.475461Z","steps":["trace[1154036928] 'process raft request' (duration: 37.341664ms)","trace[1154036928] 'compare' (duration: 75.575536ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:51.613643Z","caller":"traceutil/trace.go:171","msg":"trace[443777776] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"189.340766ms","start":"2026-08-19T09:09:51.424264Z","end":"2026-08-19T09:09:51.613605Z","steps":["trace[443777776] 'read index received' (duration: 166.185267ms)","trace[443777776] 'applied index is now lower than readState.Index' (duration: 23.151629ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:09:51.613933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.650263ms","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-08-19T09:09:51.614009Z","caller":"traceutil/trace.go:171","msg":"trace[2101175032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"189.777016ms","start":"2026-08-19T09:09:51.424215Z","end":"2026-08-19T09:09:51.613992Z","steps":["trace[2101175032] 'agreement among raft nodes before linearized reading' (duration: 189.55143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:54.054576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891559,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:09:54.173641Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083859269s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:09:54.174025Z","caller":"traceutil/trace.go:171","msg":"trace[1906110387] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"1.08430934s","start":"2026-08-19T09:09:53.089696Z","end":"2026-08-19T09:09:54.174005Z","steps":["trace[1906110387] 'process raft request' (duration: 1.084097405s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:54.174177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:53.08968Z","time spent":"1.084416813s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:54.945152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.231886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:09:54.945471Z","caller":"traceutil/trace.go:171","msg":"trace[378514320] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1078; }","duration":"1.391185039s","start":"2026-08-19T09:09:53.554262Z","end":"2026-08-19T09:09:54.945447Z","steps":["trace[378514320] 'read index received' (duration: 619.574003ms)","trace[378514320] 'applied index is now lower than readState.Index' (duration: 771.608416ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:54.945561Z","caller":"traceutil/trace.go:171","msg":"trace[1518220217] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"1.325745335s","start":"2026-08-19T09:09:53.619789Z","end":"2026-08-19T09:09:54.945534Z","steps":["trace[1518220217] 'process raft request' (duration: 713.001338ms)","trace[1518220217] 'compare' (duration: 612.102273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:09:54.94585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286328625s","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-08-19T09:09:54.945888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.403493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-19T09:09:54.945911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.940353ms","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-08-19T09:09:54.945873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.060974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:09:54.945931Z","caller":"traceutil/trace.go:171","msg":"trace[803508521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"1.286438087s","start":"2026-08-19T09:09:53.659473Z","end":"2026-08-19T09:09:54.945911Z","steps":["trace[803508521] 'agreement among raft nodes before linearized reading' (duration: 1.286240802s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:54.945944Z","caller":"traceutil/trace.go:171","msg":"trace[1668518968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1038; }","duration":"381.497814ms","start":"2026-08-19T09:09:54.564431Z","end":"2026-08-19T09:09:54.945929Z","steps":["trace[1668518968] 'agreement among raft nodes before linearized reading' (duration: 381.30302ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:54.945969Z","caller":"traceutil/trace.go:171","msg":"trace[132368786] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1038; }","duration":"620.201907ms","start":"2026-08-19T09:09:54.325743Z","end":"2026-08-19T09:09:54.945945Z","steps":["trace[132368786] 'agreement among raft nodes before linearized reading' (duration: 620.036624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:54.945995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:54.564412Z","time spent":"381.567506ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:09:54.946028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:54.325724Z","time spent":"620.294149ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:09:54.94595Z","caller":"traceutil/trace.go:171","msg":"trace[1362042006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"684.010285ms","start":"2026-08-19T09:09:54.261925Z","end":"2026-08-19T09:09:54.945936Z","steps":["trace[1362042006] 'agreement among raft nodes before linearized reading' (duration: 683.848122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:54.945976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:53.659462Z","time spent":"1.286505659s","remote":"127.0.0.1:40420","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-08-19T09:09:54.946204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:54.261904Z","time spent":"684.290813ms","remote":"127.0.0.1:40420","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-08-19T09:09:54.946344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:53.61975Z","time spent":"1.326129654s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:54.946399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.734698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:54.946731Z","caller":"traceutil/trace.go:171","msg":"trace[452929595] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1038; }","duration":"209.063256ms","start":"2026-08-19T09:09:54.73761Z","end":"2026-08-19T09:09:54.946674Z","steps":["trace[452929595] 'agreement among raft nodes before linearized reading' (duration: 208.754849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:54.945731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391459516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:54.946862Z","caller":"traceutil/trace.go:171","msg":"trace[1538639094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"1.392638034s","start":"2026-08-19T09:09:53.55421Z","end":"2026-08-19T09:09:54.946848Z","steps":["trace[1538639094] 'agreement among raft nodes before linearized reading' (duration: 1.391447606s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:54.946905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:53.554189Z","time spent":"1.392703615s","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:09:54.946396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286817806s","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-08-19T09:09:54.947079Z","caller":"traceutil/trace.go:171","msg":"trace[836721851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"1.287543643s","start":"2026-08-19T09:09:53.659524Z","end":"2026-08-19T09:09:54.947068Z","steps":["trace[836721851] 'agreement among raft nodes before linearized reading' (duration: 1.286795506s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:54.947203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:53.6595Z","time spent":"1.287689316s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:09:55.158097Z","caller":"traceutil/trace.go:171","msg":"trace[1933358514] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"206.837666ms","start":"2026-08-19T09:09:54.951233Z","end":"2026-08-19T09:09:55.15807Z","steps":["trace[1933358514] 'process raft request' (duration: 130.138994ms)","trace[1933358514] 'compare' (duration: 76.543898ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:55.158291Z","caller":"traceutil/trace.go:171","msg":"trace[730320042] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"205.989775ms","start":"2026-08-19T09:09:54.952283Z","end":"2026-08-19T09:09:55.158273Z","steps":["trace[730320042] 'process raft request' (duration: 205.74961ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:55.158522Z","caller":"traceutil/trace.go:171","msg":"trace[174585259] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"205.653338ms","start":"2026-08-19T09:09:54.952855Z","end":"2026-08-19T09:09:55.158508Z","steps":["trace[174585259] 'process raft request' (duration: 205.361721ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:55.158643Z","caller":"traceutil/trace.go:171","msg":"trace[533310754] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"204.502451ms","start":"2026-08-19T09:09:54.954129Z","end":"2026-08-19T09:09:55.158631Z","steps":["trace[533310754] 'process raft request' (duration: 204.338228ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:55.158641Z","caller":"traceutil/trace.go:171","msg":"trace[1986350186] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1080; }","duration":"204.282677ms","start":"2026-08-19T09:09:54.954338Z","end":"2026-08-19T09:09:55.158621Z","steps":["trace[1986350186] 'read index received' (duration: 127.005522ms)","trace[1986350186] 'applied index is now lower than readState.Index' (duration: 77.276105ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:09:55.158825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.42502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:55.158872Z","caller":"traceutil/trace.go:171","msg":"trace[1055516332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"204.531113ms","start":"2026-08-19T09:09:54.954326Z","end":"2026-08-19T09:09:55.158857Z","steps":["trace[1055516332] 'agreement among raft nodes before linearized reading' (duration: 204.374239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:57.054093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891578,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:09:57.065603Z","caller":"traceutil/trace.go:171","msg":"trace[741153125] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"884.047686ms","start":"2026-08-19T09:09:56.181533Z","end":"2026-08-19T09:09:57.06558Z","steps":["trace[741153125] 'process raft request' (duration: 883.892913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:57.06578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:56.181501Z","time spent":"884.172749ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:57.087462Z","caller":"traceutil/trace.go:171","msg":"trace[1324616977] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"533.668542ms","start":"2026-08-19T09:09:56.55377Z","end":"2026-08-19T09:09:57.087438Z","steps":["trace[1324616977] 'read index received' (duration: 511.71398ms)","trace[1324616977] 'applied index is now lower than readState.Index' (duration: 21.953472ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:57.087537Z","caller":"traceutil/trace.go:171","msg":"trace[333349146] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"134.213346ms","start":"2026-08-19T09:09:56.953305Z","end":"2026-08-19T09:09:57.087518Z","steps":["trace[333349146] 'process raft request' (duration: 134.020352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:57.087623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.833566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:57.087698Z","caller":"traceutil/trace.go:171","msg":"trace[1193549539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"533.967059ms","start":"2026-08-19T09:09:56.553715Z","end":"2026-08-19T09:09:57.087682Z","steps":["trace[1193549539] 'agreement among raft nodes before linearized reading' (duration: 533.820326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:57.08776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:56.553693Z","time spent":"534.052451ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:09:57.322178Z","caller":"traceutil/trace.go:171","msg":"trace[1060154922] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"110.033505ms","start":"2026-08-19T09:09:57.212119Z","end":"2026-08-19T09:09:57.322152Z","steps":["trace[1060154922] 'process raft request' (duration: 109.84675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:57.601376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.418805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:09:57.601558Z","caller":"traceutil/trace.go:171","msg":"trace[2049625622] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"389.230671ms","start":"2026-08-19T09:09:57.212312Z","end":"2026-08-19T09:09:57.601542Z","steps":["trace[2049625622] 'process raft request' (duration: 389.14874ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:57.601565Z","caller":"traceutil/trace.go:171","msg":"trace[1694116376] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"389.313024ms","start":"2026-08-19T09:09:57.212236Z","end":"2026-08-19T09:09:57.601549Z","steps":["trace[1694116376] 'process raft request' (duration: 256.637943ms)","trace[1694116376] 'compare' (duration: 132.155749ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:09:57.60162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:57.212211Z","time spent":"389.380456ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:09:57.601618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:57.212305Z","time spent":"389.284853ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:57.601709Z","caller":"traceutil/trace.go:171","msg":"trace[1715786876] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"389.106228ms","start":"2026-08-19T09:09:57.212595Z","end":"2026-08-19T09:09:57.601701Z","steps":["trace[1715786876] 'process raft request' (duration: 388.917734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:57.601743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:09:57.21257Z","time spent":"389.152569ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:09:59.175508Z","caller":"traceutil/trace.go:171","msg":"trace[707803021] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"100.790362ms","start":"2026-08-19T09:09:59.07469Z","end":"2026-08-19T09:09:59.17548Z","steps":["trace[707803021] 'process raft request' (duration: 100.511636ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:59.328081Z","caller":"traceutil/trace.go:171","msg":"trace[482755467] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"236.163195ms","start":"2026-08-19T09:09:59.091887Z","end":"2026-08-19T09:09:59.32805Z","steps":["trace[482755467] 'process raft request' (duration: 185.984109ms)","trace[482755467] 'compare' (duration: 49.799808ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:15.454585Z","caller":"traceutil/trace.go:171","msg":"trace[913768439] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"209.452788ms","start":"2026-08-19T09:10:15.245108Z","end":"2026-08-19T09:10:15.454561Z","steps":["trace[913768439] 'process raft request' (duration: 209.228143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:17.964832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891843,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:18.465814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891843,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:18.966853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333891843,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:19.033229Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.572818196s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:10:19.033595Z","caller":"traceutil/trace.go:171","msg":"trace[183684357] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"1.573238765s","start":"2026-08-19T09:10:17.460334Z","end":"2026-08-19T09:10:19.033572Z","steps":["trace[183684357] 'process raft request' (duration: 1.573093791s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.033715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.460315Z","time spent":"1.573330837s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:19.040158Z","caller":"traceutil/trace.go:171","msg":"trace[1139058701] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1210; }","duration":"1.576163991s","start":"2026-08-19T09:10:17.463981Z","end":"2026-08-19T09:10:19.040145Z","steps":["trace[1139058701] 'read index received' (duration: 1.569500009s)","trace[1139058701] 'applied index is now lower than readState.Index' (duration: 6.663112ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:19.040208Z","caller":"traceutil/trace.go:171","msg":"trace[1408814241] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"1.578354711s","start":"2026-08-19T09:10:17.461833Z","end":"2026-08-19T09:10:19.040187Z","steps":["trace[1408814241] 'process raft request' (duration: 1.578255189s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.040281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576306374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-19T09:10:19.040287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.461819Z","time spent":"1.578425043s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:19.040337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485551985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:19.040354Z","caller":"traceutil/trace.go:171","msg":"trace[1385408504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1162; }","duration":"1.485625967s","start":"2026-08-19T09:10:17.554723Z","end":"2026-08-19T09:10:19.040349Z","steps":["trace[1385408504] 'agreement among raft nodes before linearized reading' (duration: 1.485597516s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.040374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.554702Z","time spent":"1.485667178s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:10:19.040398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253801145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:19.04047Z","caller":"traceutil/trace.go:171","msg":"trace[458089128] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1162; }","duration":"1.253906418s","start":"2026-08-19T09:10:17.786544Z","end":"2026-08-19T09:10:19.04045Z","steps":["trace[458089128] 'agreement among raft nodes before linearized reading' (duration: 1.253780785s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.040522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27977752s","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-08-19T09:10:19.040523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.786531Z","time spent":"1.253978139s","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:10:19.040551Z","caller":"traceutil/trace.go:171","msg":"trace[2118131934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1162; }","duration":"1.279818931s","start":"2026-08-19T09:10:17.760727Z","end":"2026-08-19T09:10:19.040546Z","steps":["trace[2118131934] 'agreement among raft nodes before linearized reading' (duration: 1.279740679s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.04057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.760719Z","time spent":"1.279846782s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:19.040612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.358518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:19.040648Z","caller":"traceutil/trace.go:171","msg":"trace[1524756784] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1162; }","duration":"536.4347ms","start":"2026-08-19T09:10:18.504202Z","end":"2026-08-19T09:10:19.040637Z","steps":["trace[1524756784] 'agreement among raft nodes before linearized reading' (duration: 536.364759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.040672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279987635s","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-08-19T09:10:19.04068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:18.504184Z","time spent":"536.486841ms","remote":"127.0.0.1:48164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:10:19.040688Z","caller":"traceutil/trace.go:171","msg":"trace[2122383746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1162; }","duration":"1.280041006s","start":"2026-08-19T09:10:17.760642Z","end":"2026-08-19T09:10:19.040683Z","steps":["trace[2122383746] 'agreement among raft nodes before linearized reading' (duration: 1.279995115s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.040704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.760622Z","time spent":"1.280077948s","remote":"127.0.0.1:40420","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":"info","ts":"2026-08-19T09:10:19.040303Z","caller":"traceutil/trace.go:171","msg":"trace[1788300831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1162; }","duration":"1.576368575s","start":"2026-08-19T09:10:17.463927Z","end":"2026-08-19T09:10:19.040295Z","steps":["trace[1788300831] 'agreement among raft nodes before linearized reading' (duration: 1.576284053s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.040769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.463899Z","time spent":"1.576853847s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:19.040811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3556554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T09:10:19.040831Z","caller":"traceutil/trace.go:171","msg":"trace[1656951092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1162; }","duration":"1.355709581s","start":"2026-08-19T09:10:17.685116Z","end":"2026-08-19T09:10:19.040825Z","steps":["trace[1656951092] 'agreement among raft nodes before linearized reading' (duration: 1.355659579s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:19.040864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:17.68509Z","time spent":"1.355769512s","remote":"127.0.0.1:40420","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-08-19T09:10:23.238047Z","caller":"traceutil/trace.go:171","msg":"trace[1101940730] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1226; }","duration":"119.459408ms","start":"2026-08-19T09:10:23.118564Z","end":"2026-08-19T09:10:23.238024Z","steps":["trace[1101940730] 'read index received' (duration: 28.382121ms)","trace[1101940730] 'applied index is now lower than readState.Index' (duration: 91.076477ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:23.238112Z","caller":"traceutil/trace.go:171","msg":"trace[1326529952] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"138.81462ms","start":"2026-08-19T09:10:23.099287Z","end":"2026-08-19T09:10:23.238102Z","steps":["trace[1326529952] 'process raft request' (duration: 93.453131ms)","trace[1326529952] 'compare' (duration: 45.144925ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:23.238138Z","caller":"traceutil/trace.go:171","msg":"trace[1518814528] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"135.957845ms","start":"2026-08-19T09:10:23.102156Z","end":"2026-08-19T09:10:23.238113Z","steps":["trace[1518814528] 'process raft request' (duration: 135.832782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:23.238206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.638821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-19T09:10:23.238229Z","caller":"traceutil/trace.go:171","msg":"trace[1930428372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"119.717213ms","start":"2026-08-19T09:10:23.118505Z","end":"2026-08-19T09:10:23.238222Z","steps":["trace[1930428372] 'agreement among raft nodes before linearized reading' (duration: 119.625471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:23.23838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.207498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T09:10:23.238469Z","caller":"traceutil/trace.go:171","msg":"trace[653574618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"118.29775ms","start":"2026-08-19T09:10:23.120157Z","end":"2026-08-19T09:10:23.238455Z","steps":["trace[653574618] 'agreement among raft nodes before linearized reading' (duration: 118.124776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:33.974096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.716498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:10:33.974214Z","caller":"traceutil/trace.go:171","msg":"trace[29419476] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1229; }","duration":"248.889592ms","start":"2026-08-19T09:10:33.725292Z","end":"2026-08-19T09:10:33.974182Z","steps":["trace[29419476] 'count revisions from in-memory index tree' (duration: 248.636737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:35.76958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333892002,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:10:36.093429Z","caller":"traceutil/trace.go:171","msg":"trace[1035453914] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"823.869146ms","start":"2026-08-19T09:10:35.269544Z","end":"2026-08-19T09:10:36.093414Z","steps":["trace[1035453914] 'read index received' (duration: 823.738773ms)","trace[1035453914] 'applied index is now lower than readState.Index' (duration: 127.473µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:36.093661Z","caller":"traceutil/trace.go:171","msg":"trace[1688009451] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"959.005562ms","start":"2026-08-19T09:10:35.134608Z","end":"2026-08-19T09:10:36.093614Z","steps":["trace[1688009451] 'process raft request' (duration: 958.715216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.093694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.131662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-19T09:10:36.09373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.715671ms","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-08-19T09:10:36.093957Z","caller":"traceutil/trace.go:171","msg":"trace[254420724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"812.992487ms","start":"2026-08-19T09:10:35.280957Z","end":"2026-08-19T09:10:36.093949Z","steps":["trace[254420724] 'agreement among raft nodes before linearized reading' (duration: 812.68562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.093981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:35.280939Z","time spent":"813.037258ms","remote":"127.0.0.1:40420","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":"info","ts":"2026-08-19T09:10:36.093752Z","caller":"traceutil/trace.go:171","msg":"trace[1999610003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"824.243954ms","start":"2026-08-19T09:10:35.269491Z","end":"2026-08-19T09:10:36.093734Z","steps":["trace[1999610003] 'agreement among raft nodes before linearized reading' (duration: 824.013489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.09402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:35.269471Z","time spent":"824.54311ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:36.094012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.434974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T09:10:36.094064Z","caller":"traceutil/trace.go:171","msg":"trace[596635831] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:1234; }","duration":"163.521776ms","start":"2026-08-19T09:10:35.930528Z","end":"2026-08-19T09:10:36.09405Z","steps":["trace[596635831] 'agreement among raft nodes before linearized reading' (duration: 163.353852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.093849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.343812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T09:10:36.094122Z","caller":"traceutil/trace.go:171","msg":"trace[253709948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"812.626869ms","start":"2026-08-19T09:10:35.281478Z","end":"2026-08-19T09:10:36.094105Z","steps":["trace[253709948] 'agreement among raft nodes before linearized reading' (duration: 812.319302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.09383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:35.134585Z","time spent":"959.155086ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:36.093873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.125777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:10:36.09421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:35.281467Z","time spent":"812.726541ms","remote":"127.0.0.1:40420","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-08-19T09:10:36.094269Z","caller":"traceutil/trace.go:171","msg":"trace[1467975879] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1234; }","duration":"616.554796ms","start":"2026-08-19T09:10:35.477706Z","end":"2026-08-19T09:10:36.094261Z","steps":["trace[1467975879] 'agreement among raft nodes before linearized reading' (duration: 616.042624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.09429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:35.477692Z","time spent":"616.591637ms","remote":"127.0.0.1:48202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:10:36.093882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.69454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T09:10:36.094385Z","caller":"traceutil/trace.go:171","msg":"trace[1799686252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"765.213421ms","start":"2026-08-19T09:10:35.329147Z","end":"2026-08-19T09:10:36.09436Z","steps":["trace[1799686252] 'agreement among raft nodes before linearized reading' (duration: 764.643128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.094422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:35.329128Z","time spent":"765.283243ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:36.093916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.69456ms","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-08-19T09:10:36.094481Z","caller":"traceutil/trace.go:171","msg":"trace[1917201637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"765.311034ms","start":"2026-08-19T09:10:35.329165Z","end":"2026-08-19T09:10:36.094476Z","steps":["trace[1917201637] 'agreement among raft nodes before linearized reading' (duration: 764.70435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:36.094499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:35.329141Z","time spent":"765.352435ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:37.054376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333892005,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:37.209537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.112433ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-08-19T09:10:37.209609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.094742Z","time spent":"1.114863793s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-19T09:10:37.549342Z","caller":"traceutil/trace.go:171","msg":"trace[1283082673] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"1.450378599s","start":"2026-08-19T09:10:36.098936Z","end":"2026-08-19T09:10:37.549315Z","steps":["trace[1283082673] 'process raft request' (duration: 1.40895602s)","trace[1283082673] 'compare' (duration: 41.211164ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:37.549491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.098903Z","time spent":"1.450512482s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:37.549564Z","caller":"traceutil/trace.go:171","msg":"trace[557398861] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"1.450411931s","start":"2026-08-19T09:10:36.099132Z","end":"2026-08-19T09:10:37.549544Z","steps":["trace[557398861] 'process raft request' (duration: 1.450118833s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:37.549675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.099122Z","time spent":"1.450496893s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:37.549899Z","caller":"traceutil/trace.go:171","msg":"trace[285323744] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1287; }","duration":"995.837096ms","start":"2026-08-19T09:10:36.554055Z","end":"2026-08-19T09:10:37.549892Z","steps":["trace[285323744] 'read index received' (duration: 521.461147ms)","trace[285323744] 'applied index is now lower than readState.Index' (duration: 474.374369ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:37.549944Z","caller":"traceutil/trace.go:171","msg":"trace[1868360583] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"1.450119054s","start":"2026-08-19T09:10:36.099821Z","end":"2026-08-19T09:10:37.54994Z","steps":["trace[1868360583] 'process raft request' (duration: 1.449662853s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:37.549974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.099811Z","time spent":"1.450147314s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:37.550024Z","caller":"traceutil/trace.go:171","msg":"trace[288454725] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"1.449870649s","start":"2026-08-19T09:10:36.100149Z","end":"2026-08-19T09:10:37.55002Z","steps":["trace[288454725] 'process raft request' (duration: 1.449692645s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:37.550006Z","caller":"traceutil/trace.go:171","msg":"trace[1521058007] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"1.448343513s","start":"2026-08-19T09:10:36.101644Z","end":"2026-08-19T09:10:37.549987Z","steps":["trace[1521058007] 'process raft request' (duration: 1.44822418s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:37.550049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.100144Z","time spent":"1.449892929s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:37.550082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.02054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:10:37.5501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.101626Z","time spent":"1.448423395s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:37.55017Z","caller":"traceutil/trace.go:171","msg":"trace[1780997271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"996.141313ms","start":"2026-08-19T09:10:36.553997Z","end":"2026-08-19T09:10:37.550138Z","steps":["trace[1780997271] 'agreement among raft nodes before linearized reading' (duration: 995.99713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:37.550214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.553974Z","time spent":"996.232995ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:10:37.596727Z","caller":"traceutil/trace.go:171","msg":"trace[672672579] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"386.199998ms","start":"2026-08-19T09:10:37.210491Z","end":"2026-08-19T09:10:37.596691Z","steps":["trace[672672579] 'process raft request' (duration: 386.053275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:37.596784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016414416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:37.596841Z","caller":"traceutil/trace.go:171","msg":"trace[1178815837] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1240; }","duration":"1.016516058s","start":"2026-08-19T09:10:36.580311Z","end":"2026-08-19T09:10:37.596827Z","steps":["trace[1178815837] 'agreement among raft nodes before linearized reading' (duration: 1.016390565s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:37.596869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:36.580299Z","time spent":"1.01656513s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:10:37.596935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:37.210476Z","time spent":"386.341351ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:37.864037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.870122ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:37.864161Z","caller":"traceutil/trace.go:171","msg":"trace[944233581] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1240; }","duration":"177.023595ms","start":"2026-08-19T09:10:37.687119Z","end":"2026-08-19T09:10:37.864143Z","steps":["trace[944233581] 'range keys from in-memory index tree' (duration: 176.853552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:40.921059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333892032,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:41.421454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333892032,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:41.799823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.407247784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:41.79987Z","caller":"traceutil/trace.go:171","msg":"trace[220280936] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1243; }","duration":"2.407337725s","start":"2026-08-19T09:10:39.392521Z","end":"2026-08-19T09:10:41.799859Z","steps":["trace[220280936] 'range keys from in-memory index tree' (duration: 2.407173891s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:41.79989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:39.3925Z","time spent":"2.407384826s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-19T09:10:41.800313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.246177643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T09:10:41.80037Z","caller":"traceutil/trace.go:171","msg":"trace[1637602293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"2.246279505s","start":"2026-08-19T09:10:39.554077Z","end":"2026-08-19T09:10:41.800357Z","steps":["trace[1637602293] 'range keys from in-memory index tree' (duration: 2.245971168s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:41.800398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:39.554056Z","time spent":"2.246335336s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:41.800532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.24605969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:41.800554Z","caller":"traceutil/trace.go:171","msg":"trace[1655156590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"2.246123911s","start":"2026-08-19T09:10:39.554425Z","end":"2026-08-19T09:10:41.800549Z","steps":["trace[1655156590] 'range keys from in-memory index tree' (duration: 2.245981368s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:41.80057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:39.5544Z","time spent":"2.246166832s","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:10:41.800658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.245567569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T09:10:41.800681Z","caller":"traceutil/trace.go:171","msg":"trace[206260281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"2.24561178s","start":"2026-08-19T09:10:39.555063Z","end":"2026-08-19T09:10:41.800675Z","steps":["trace[206260281] 'range keys from in-memory index tree' (duration: 2.245481077s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:41.800696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:39.555052Z","time spent":"2.245640761s","remote":"127.0.0.1:40420","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-08-19T09:10:41.801129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.245919366s","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-08-19T09:10:41.801166Z","caller":"traceutil/trace.go:171","msg":"trace[2052881479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"2.245977998s","start":"2026-08-19T09:10:39.55518Z","end":"2026-08-19T09:10:41.801158Z","steps":["trace[2052881479] 'range keys from in-memory index tree' (duration: 2.245787533s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:41.801189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:39.555166Z","time spent":"2.246018459s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:41.80124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.246000629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-19T09:10:41.801302Z","caller":"traceutil/trace.go:171","msg":"trace[720658371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"2.246093911s","start":"2026-08-19T09:10:39.555194Z","end":"2026-08-19T09:10:41.801287Z","steps":["trace[720658371] 'range keys from in-memory index tree' (duration: 2.245844434s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:41.801341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:39.555177Z","time spent":"2.246154922s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:41.801324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.246139922s","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-08-19T09:10:41.801405Z","caller":"traceutil/trace.go:171","msg":"trace[1715906348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"2.246220304s","start":"2026-08-19T09:10:39.555178Z","end":"2026-08-19T09:10:41.801398Z","steps":["trace[1715906348] 'range keys from in-memory index tree' (duration: 2.245548937s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:41.801431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:39.555144Z","time spent":"2.246282365s","remote":"127.0.0.1:40420","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-08-19T09:10:41.921968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333892032,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:42.089495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.925815222s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:10:42.089846Z","caller":"traceutil/trace.go:171","msg":"trace[1204361203] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"1.926254852s","start":"2026-08-19T09:10:40.163573Z","end":"2026-08-19T09:10:42.089827Z","steps":["trace[1204361203] 'process raft request' (duration: 1.926126939s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.089963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:40.163547Z","time spent":"1.926354755s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:42.205078Z","caller":"traceutil/trace.go:171","msg":"trace[522080035] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"848.851369ms","start":"2026-08-19T09:10:41.356191Z","end":"2026-08-19T09:10:42.205043Z","steps":["trace[522080035] 'process raft request' (duration: 848.558992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.20525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.356159Z","time spent":"849.002991ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:42.205437Z","caller":"traceutil/trace.go:171","msg":"trace[953295776] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"411.58027ms","start":"2026-08-19T09:10:41.793819Z","end":"2026-08-19T09:10:42.205399Z","steps":["trace[953295776] 'process raft request' (duration: 411.389274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.205604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.793794Z","time spent":"411.730213ms","remote":"127.0.0.1:40224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:10:42.205671Z","caller":"traceutil/trace.go:171","msg":"trace[622739285] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"399.617426ms","start":"2026-08-19T09:10:41.806035Z","end":"2026-08-19T09:10:42.205652Z","steps":["trace[622739285] 'process raft request' (duration: 399.490333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.205765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.806018Z","time spent":"399.696668ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:42.205768Z","caller":"traceutil/trace.go:171","msg":"trace[96982440] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"399.645136ms","start":"2026-08-19T09:10:41.806104Z","end":"2026-08-19T09:10:42.205749Z","steps":["trace[96982440] 'process raft request' (duration: 399.508903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.205865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.806097Z","time spent":"399.715398ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:42.205986Z","caller":"traceutil/trace.go:171","msg":"trace[627103319] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"399.843331ms","start":"2026-08-19T09:10:41.806127Z","end":"2026-08-19T09:10:42.205971Z","steps":["trace[627103319] 'process raft request' (duration: 399.542755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.206073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.806125Z","time spent":"399.903353ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:42.206159Z","caller":"traceutil/trace.go:171","msg":"trace[344744051] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"399.785529ms","start":"2026-08-19T09:10:41.806355Z","end":"2026-08-19T09:10:42.20614Z","steps":["trace[344744051] 'process raft request' (duration: 399.566334ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:42.206192Z","caller":"traceutil/trace.go:171","msg":"trace[1368361166] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"399.703458ms","start":"2026-08-19T09:10:41.806474Z","end":"2026-08-19T09:10:42.206177Z","steps":["trace[1368361166] 'process raft request' (duration: 399.570475ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:42.206223Z","caller":"traceutil/trace.go:171","msg":"trace[676848564] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1297; }","duration":"1.785538519s","start":"2026-08-19T09:10:40.420668Z","end":"2026-08-19T09:10:42.206206Z","steps":["trace[676848564] 'read index received' (duration: 1.669021579s)","trace[676848564] 'applied index is now lower than readState.Index' (duration: 116.51567ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:42.206258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.806468Z","time spent":"399.757359ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:10:42.206277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.806349Z","time spent":"399.861252ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:42.206333Z","caller":"traceutil/trace.go:171","msg":"trace[499695046] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"399.744738ms","start":"2026-08-19T09:10:41.806575Z","end":"2026-08-19T09:10:42.206319Z","steps":["trace[499695046] 'process raft request' (duration: 399.528813ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:42.2064Z","caller":"traceutil/trace.go:171","msg":"trace[1126099319] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"302.108952ms","start":"2026-08-19T09:10:41.904276Z","end":"2026-08-19T09:10:42.206385Z","steps":["trace[1126099319] 'process raft request' (duration: 301.880427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.206408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.806544Z","time spent":"399.82148ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:42.206423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785761044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:10:42.206474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.904253Z","time spent":"302.180063ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:42.206481Z","caller":"traceutil/trace.go:171","msg":"trace[799599710] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1253; }","duration":"1.785885647s","start":"2026-08-19T09:10:40.420578Z","end":"2026-08-19T09:10:42.206464Z","steps":["trace[799599710] 'agreement among raft nodes before linearized reading' (duration: 1.785798765s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.206534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:40.420557Z","time spent":"1.785959439s","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:10:42.206632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268152165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:42.206675Z","caller":"traceutil/trace.go:171","msg":"trace[1781139943] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1253; }","duration":"1.268242887s","start":"2026-08-19T09:10:40.938419Z","end":"2026-08-19T09:10:42.206662Z","steps":["trace[1781139943] 'agreement among raft nodes before linearized reading' (duration: 1.268174025s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.206718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:40.938398Z","time spent":"1.268306109s","remote":"127.0.0.1:48072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:10:42.206818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.037743ms","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":"warn","ts":"2026-08-19T09:10:42.206828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.408045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:42.206866Z","caller":"traceutil/trace.go:171","msg":"trace[1227242484] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1253; }","duration":"375.119034ms","start":"2026-08-19T09:10:41.831732Z","end":"2026-08-19T09:10:42.206851Z","steps":["trace[1227242484] 'agreement among raft nodes before linearized reading' (duration: 375.040093ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:42.206882Z","caller":"traceutil/trace.go:171","msg":"trace[212165096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"651.493077ms","start":"2026-08-19T09:10:41.555374Z","end":"2026-08-19T09:10:42.206867Z","steps":["trace[212165096] 'agreement among raft nodes before linearized reading' (duration: 651.407055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.206946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.831719Z","time spent":"375.180766ms","remote":"127.0.0.1:48002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:10:42.206971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:41.555358Z","time spent":"651.60076ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:10:42.336066Z","caller":"traceutil/trace.go:171","msg":"trace[83113125] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"125.209329ms","start":"2026-08-19T09:10:42.210829Z","end":"2026-08-19T09:10:42.336038Z","steps":["trace[83113125] 'process raft request' (duration: 93.188266ms)","trace[83113125] 'compare' (duration: 31.89223ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:42.350274Z","caller":"traceutil/trace.go:171","msg":"trace[383379247] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"137.126201ms","start":"2026-08-19T09:10:42.213124Z","end":"2026-08-19T09:10:42.35025Z","steps":["trace[383379247] 'read index received' (duration: 90.903683ms)","trace[383379247] 'applied index is now lower than readState.Index' (duration: 46.221438ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:42.350312Z","caller":"traceutil/trace.go:171","msg":"trace[2010997023] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"137.210614ms","start":"2026-08-19T09:10:42.213059Z","end":"2026-08-19T09:10:42.350269Z","steps":["trace[2010997023] 'process raft request' (duration: 137.073351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.35049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.846262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:42.350531Z","caller":"traceutil/trace.go:171","msg":"trace[58551654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"135.919744ms","start":"2026-08-19T09:10:42.214602Z","end":"2026-08-19T09:10:42.350522Z","steps":["trace[58551654] 'agreement among raft nodes before linearized reading' (duration: 135.78285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.350598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.443238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-19T09:10:42.350684Z","caller":"traceutil/trace.go:171","msg":"trace[643014775] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1255; }","duration":"137.566142ms","start":"2026-08-19T09:10:42.2131Z","end":"2026-08-19T09:10:42.350666Z","steps":["trace[643014775] 'agreement among raft nodes before linearized reading' (duration: 137.269085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:42.539365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.260506ms","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-08-19T09:10:42.539447Z","caller":"traceutil/trace.go:171","msg":"trace[103509091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1257; }","duration":"103.407179ms","start":"2026-08-19T09:10:42.436018Z","end":"2026-08-19T09:10:42.539425Z","steps":["trace[103509091] 'count revisions from in-memory index tree' (duration: 103.162714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:44.712212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871534333892054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:10:45.125793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030892249s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:10:45.126124Z","caller":"traceutil/trace.go:171","msg":"trace[190583176] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"914.844439ms","start":"2026-08-19T09:10:44.211257Z","end":"2026-08-19T09:10:45.126101Z","steps":["trace[190583176] 'read index received' (duration: 914.643545ms)","trace[190583176] 'applied index is now lower than readState.Index' (duration: 199.794µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:45.126213Z","caller":"traceutil/trace.go:171","msg":"trace[804959548] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"1.03139281s","start":"2026-08-19T09:10:44.094776Z","end":"2026-08-19T09:10:45.126169Z","steps":["trace[804959548] 'process raft request' (duration: 1.031193525s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.126332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.055874ms","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-08-19T09:10:45.126371Z","caller":"traceutil/trace.go:171","msg":"trace[832246939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"915.110075ms","start":"2026-08-19T09:10:44.211251Z","end":"2026-08-19T09:10:45.126361Z","steps":["trace[832246939] 'agreement among raft nodes before linearized reading' (duration: 914.963302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.126385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:44.094743Z","time spent":"1.031540423s","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:45.126494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.553383ms","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-08-19T09:10:45.126407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:44.211198Z","time spent":"915.198218ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:45.126491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.691073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T09:10:45.126529Z","caller":"traceutil/trace.go:171","msg":"trace[26198105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1258; }","duration":"914.604344ms","start":"2026-08-19T09:10:44.211917Z","end":"2026-08-19T09:10:45.126522Z","steps":["trace[26198105] 'agreement among raft nodes before linearized reading' (duration: 914.516702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.126527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.52011ms","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-08-19T09:10:45.126564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:44.211909Z","time spent":"914.649575ms","remote":"127.0.0.1:40420","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":"info","ts":"2026-08-19T09:10:45.126572Z","caller":"traceutil/trace.go:171","msg":"trace[863772542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"913.803136ms","start":"2026-08-19T09:10:44.21275Z","end":"2026-08-19T09:10:45.126553Z","steps":["trace[863772542] 'agreement among raft nodes before linearized reading' (duration: 913.592222ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:45.126604Z","caller":"traceutil/trace.go:171","msg":"trace[654629000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"913.641852ms","start":"2026-08-19T09:10:44.212944Z","end":"2026-08-19T09:10:45.126586Z","steps":["trace[654629000] 'agreement among raft nodes before linearized reading' (duration: 913.382746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.126616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:44.212742Z","time spent":"913.866328ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:10:45.12662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.555224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:10:45.126658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:44.21293Z","time spent":"913.719465ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:10:45.126676Z","caller":"traceutil/trace.go:171","msg":"trace[825659826] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1258; }","duration":"743.612667ms","start":"2026-08-19T09:10:44.383047Z","end":"2026-08-19T09:10:45.12666Z","steps":["trace[825659826] 'agreement among raft nodes before linearized reading' (duration: 743.489714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.126728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:44.383009Z","time spent":"743.700448ms","remote":"127.0.0.1:40602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:10:45.12744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.876864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T09:10:45.127687Z","caller":"traceutil/trace.go:171","msg":"trace[969983259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"916.15144ms","start":"2026-08-19T09:10:44.211518Z","end":"2026-08-19T09:10:45.127669Z","steps":["trace[969983259] 'agreement among raft nodes before linearized reading' (duration: 914.765077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.12782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:44.211505Z","time spent":"916.298414ms","remote":"127.0.0.1:40420","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-08-19T09:10:45.634106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.370165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:10:45.634203Z","caller":"traceutil/trace.go:171","msg":"trace[328897044] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"502.39425ms","start":"2026-08-19T09:10:45.131791Z","end":"2026-08-19T09:10:45.634185Z","steps":["trace[328897044] 'process raft request' (duration: 399.845251ms)","trace[328897044] 'compare' (duration: 102.083078ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:45.634262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:45.131774Z","time spent":"502.461153ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:45.638671Z","caller":"traceutil/trace.go:171","msg":"trace[335461655] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"506.515775ms","start":"2026-08-19T09:10:45.132126Z","end":"2026-08-19T09:10:45.638642Z","steps":["trace[335461655] 'process raft request' (duration: 506.356592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.638861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:45.132105Z","time spent":"506.663958ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:45.638853Z","caller":"traceutil/trace.go:171","msg":"trace[360963708] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"505.862979ms","start":"2026-08-19T09:10:45.132954Z","end":"2026-08-19T09:10:45.638817Z","steps":["trace[360963708] 'process raft request' (duration: 505.660275ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:45.638995Z","caller":"traceutil/trace.go:171","msg":"trace[1703348887] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"505.780047ms","start":"2026-08-19T09:10:45.133198Z","end":"2026-08-19T09:10:45.638978Z","steps":["trace[1703348887] 'process raft request' (duration: 505.46108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.639044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:45.132937Z","time spent":"506.021452ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:45.639052Z","caller":"traceutil/trace.go:171","msg":"trace[850265200] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"506.29493ms","start":"2026-08-19T09:10:45.132741Z","end":"2026-08-19T09:10:45.639036Z","steps":["trace[850265200] 'process raft request' (duration: 505.83902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:45.63907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:45.133187Z","time spent":"505.844829ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:10:45.639128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:45.132729Z","time spent":"506.364101ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:10:47.775755Z","caller":"traceutil/trace.go:171","msg":"trace[809558717] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"106.95442ms","start":"2026-08-19T09:10:47.668768Z","end":"2026-08-19T09:10:47.775723Z","steps":["trace[809558717] 'process raft request' (duration: 104.168306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:52.425572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.808277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-19T09:10:52.425661Z","caller":"traceutil/trace.go:171","msg":"trace[601364752] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1305; }","duration":"288.95212ms","start":"2026-08-19T09:10:52.136689Z","end":"2026-08-19T09:10:52.425641Z","steps":["trace[601364752] 'range keys from in-memory index tree' (duration: 288.638873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:52.425711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.248508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:10:52.4258Z","caller":"traceutil/trace.go:171","msg":"trace[2055065911] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1305; }","duration":"499.385721ms","start":"2026-08-19T09:10:51.926396Z","end":"2026-08-19T09:10:52.425782Z","steps":["trace[2055065911] 'range keys from in-memory index tree' (duration: 499.104785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:52.425841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:10:51.926371Z","time spent":"499.460642ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-08-19T09:10:52.42727Z","caller":"traceutil/trace.go:171","msg":"trace[1497287395] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"196.88747ms","start":"2026-08-19T09:10:52.230364Z","end":"2026-08-19T09:10:52.427252Z","steps":["trace[1497287395] 'process raft request' (duration: 196.772388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:53.972158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.497069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:10:53.97239Z","caller":"traceutil/trace.go:171","msg":"trace[554578217] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"141.516612ms","start":"2026-08-19T09:10:53.830856Z","end":"2026-08-19T09:10:53.972373Z","steps":["trace[554578217] 'read index received' (duration: 24.738366ms)","trace[554578217] 'applied index is now lower than readState.Index' (duration: 116.776646ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:53.972475Z","caller":"traceutil/trace.go:171","msg":"trace[458978695] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"155.607425ms","start":"2026-08-19T09:10:53.816858Z","end":"2026-08-19T09:10:53.972466Z","steps":["trace[458978695] 'process raft request' (duration: 155.419261ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:53.972461Z","caller":"traceutil/trace.go:171","msg":"trace[1861189794] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"156.055116ms","start":"2026-08-19T09:10:53.816357Z","end":"2026-08-19T09:10:53.972412Z","steps":["trace[1861189794] 'process raft request' (duration: 39.220519ms)","trace[1861189794] 'compare' (duration: 116.151312ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:10:53.972761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.915212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-19T09:10:53.972764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.655741ms","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-08-19T09:10:53.972816Z","caller":"traceutil/trace.go:171","msg":"trace[515017680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"141.992014ms","start":"2026-08-19T09:10:53.830809Z","end":"2026-08-19T09:10:53.972801Z","steps":["trace[515017680] 'agreement among raft nodes before linearized reading' (duration: 141.82812ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:53.97282Z","caller":"traceutil/trace.go:171","msg":"trace[952144714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"129.734992ms","start":"2026-08-19T09:10:53.843072Z","end":"2026-08-19T09:10:53.972807Z","steps":["trace[952144714] 'agreement among raft nodes before linearized reading' (duration: 129.579309ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:02.708524Z","caller":"traceutil/trace.go:171","msg":"trace[1478190877] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"111.385962ms","start":"2026-08-19T09:11:02.597104Z","end":"2026-08-19T09:11:02.70849Z","steps":["trace[1478190877] 'process raft request' (duration: 47.073269ms)","trace[1478190877] 'compare' (duration: 64.096488ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:11:02.993381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.428468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:11:02.993773Z","caller":"traceutil/trace.go:171","msg":"trace[452676117] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"236.352845ms","start":"2026-08-19T09:11:02.75738Z","end":"2026-08-19T09:11:02.993733Z","steps":["trace[452676117] 'process raft request' (duration: 81.468886ms)","trace[452676117] 'compare' (duration: 154.273315ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:11:07.687942Z","caller":"traceutil/trace.go:171","msg":"trace[729711786] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"104.080894ms","start":"2026-08-19T09:11:07.583842Z","end":"2026-08-19T09:11:07.687923Z","steps":["trace[729711786] 'read index received' (duration: 103.921451ms)","trace[729711786] 'applied index is now lower than readState.Index' (duration: 158.383µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:11:07.688049Z","caller":"traceutil/trace.go:171","msg":"trace[1007441189] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"127.543292ms","start":"2026-08-19T09:11:07.560467Z","end":"2026-08-19T09:11:07.688011Z","steps":["trace[1007441189] 'process raft request' (duration: 127.335177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:07.688144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.283209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-19T09:11:07.688182Z","caller":"traceutil/trace.go:171","msg":"trace[389978841] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1386; }","duration":"104.380301ms","start":"2026-08-19T09:11:07.583793Z","end":"2026-08-19T09:11:07.688173Z","steps":["trace[389978841] 'agreement among raft nodes before linearized reading' (duration: 104.212127ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:07.846691Z","caller":"traceutil/trace.go:171","msg":"trace[756421769] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"154.630913ms","start":"2026-08-19T09:11:07.692031Z","end":"2026-08-19T09:11:07.846662Z","steps":["trace[756421769] 'process raft request' (duration: 68.766386ms)","trace[756421769] 'compare' (duration: 85.750065ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:11:08.279703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.695575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-19T09:11:08.27979Z","caller":"traceutil/trace.go:171","msg":"trace[538482585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1388; }","duration":"192.807437ms","start":"2026-08-19T09:11:08.086947Z","end":"2026-08-19T09:11:08.279755Z","steps":["trace[538482585] 'range keys from in-memory index tree' (duration: 192.545862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:08.280032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.728595ms","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-08-19T09:11:08.280063Z","caller":"traceutil/trace.go:171","msg":"trace[1423087486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1388; }","duration":"171.779765ms","start":"2026-08-19T09:11:08.108273Z","end":"2026-08-19T09:11:08.280053Z","steps":["trace[1423087486] 'range keys from in-memory index tree' (duration: 171.55729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:08.280396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.922621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T09:11:08.280426Z","caller":"traceutil/trace.go:171","msg":"trace[1060899553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"103.988492ms","start":"2026-08-19T09:11:08.176429Z","end":"2026-08-19T09:11:08.280418Z","steps":["trace[1060899553] 'range keys from in-memory index tree' (duration: 103.744947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:10.568796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.389045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:11:10.569024Z","caller":"traceutil/trace.go:171","msg":"trace[346348929] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"208.62306ms","start":"2026-08-19T09:11:10.360379Z","end":"2026-08-19T09:11:10.569002Z","steps":["trace[346348929] 'process raft request' (duration: 208.522528ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:10.569016Z","caller":"traceutil/trace.go:171","msg":"trace[1925870155] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"208.929996ms","start":"2026-08-19T09:11:10.36004Z","end":"2026-08-19T09:11:10.56897Z","steps":["trace[1925870155] 'process raft request' (duration: 24.270195ms)","trace[1925870155] 'compare' (duration: 184.242462ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:11:10.569086Z","caller":"traceutil/trace.go:171","msg":"trace[882338418] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"208.610489ms","start":"2026-08-19T09:11:10.36045Z","end":"2026-08-19T09:11:10.56906Z","steps":["trace[882338418] 'process raft request' (duration: 208.501837ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:28.982302Z","caller":"traceutil/trace.go:171","msg":"trace[832854012] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"142.288909ms","start":"2026-08-19T09:11:28.839998Z","end":"2026-08-19T09:11:28.982287Z","steps":["trace[832854012] 'process raft request' (duration: 142.153426ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:35.146405Z","caller":"traceutil/trace.go:171","msg":"trace[1446655677] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"122.047957ms","start":"2026-08-19T09:11:35.024327Z","end":"2026-08-19T09:11:35.146374Z","steps":["trace[1446655677] 'process raft request' (duration: 121.7563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:35.312713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.833543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:11:35.313131Z","caller":"traceutil/trace.go:171","msg":"trace[115760318] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"161.214983ms","start":"2026-08-19T09:11:35.151897Z","end":"2026-08-19T09:11:35.313112Z","steps":["trace[115760318] 'process raft request' (duration: 161.172862ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:35.313132Z","caller":"traceutil/trace.go:171","msg":"trace[2121566861] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"162.227367ms","start":"2026-08-19T09:11:35.150877Z","end":"2026-08-19T09:11:35.313104Z","steps":["trace[2121566861] 'process raft request' (duration: 49.925634ms)","trace[2121566861] 'compare' (duration: 111.72251ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:11:35.313233Z","caller":"traceutil/trace.go:171","msg":"trace[1017466455] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"161.573851ms","start":"2026-08-19T09:11:35.151637Z","end":"2026-08-19T09:11:35.31321Z","steps":["trace[1017466455] 'process raft request' (duration: 161.169172ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:43.508601Z","caller":"traceutil/trace.go:171","msg":"trace[1864360427] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"124.708558ms","start":"2026-08-19T09:11:43.383866Z","end":"2026-08-19T09:11:43.508574Z","steps":["trace[1864360427] 'process raft request' (duration: 116.9707ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:11.707448Z","caller":"traceutil/trace.go:171","msg":"trace[1744065578] linearizableReadLoop","detail":"{readStateIndex:1747; appliedIndex:1746; }","duration":"197.900354ms","start":"2026-08-19T09:12:11.509524Z","end":"2026-08-19T09:12:11.707425Z","steps":["trace[1744065578] 'read index received' (duration: 197.657449ms)","trace[1744065578] 'applied index is now lower than readState.Index' (duration: 241.445µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:11.707632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.085199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:12:11.707658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.731013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:11.707737Z","caller":"traceutil/trace.go:171","msg":"trace[1559724164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1674; }","duration":"153.846715ms","start":"2026-08-19T09:12:11.553877Z","end":"2026-08-19T09:12:11.707723Z","steps":["trace[1559724164] 'agreement among raft nodes before linearized reading' (duration: 153.711182ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:11.707695Z","caller":"traceutil/trace.go:171","msg":"trace[328294058] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1674; }","duration":"198.203952ms","start":"2026-08-19T09:12:11.509477Z","end":"2026-08-19T09:12:11.707681Z","steps":["trace[328294058] 'agreement among raft nodes before linearized reading' (duration: 198.075319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:11.707806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.153612ms","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-08-19T09:12:11.707871Z","caller":"traceutil/trace.go:171","msg":"trace[1969733677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1674; }","duration":"147.258965ms","start":"2026-08-19T09:12:11.560598Z","end":"2026-08-19T09:12:11.707857Z","steps":["trace[1969733677] 'agreement among raft nodes before linearized reading' (duration: 146.978828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:11.707863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.256899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-19T09:12:11.707905Z","caller":"traceutil/trace.go:171","msg":"trace[1834929190] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1674; }","duration":"180.334262ms","start":"2026-08-19T09:12:11.52756Z","end":"2026-08-19T09:12:11.707894Z","steps":["trace[1834929190] 'agreement among raft nodes before linearized reading' (duration: 179.992904ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:11.708091Z","caller":"traceutil/trace.go:171","msg":"trace[1150554458] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"339.284833ms","start":"2026-08-19T09:12:11.36824Z","end":"2026-08-19T09:12:11.707525Z","steps":["trace[1150554458] 'process raft request' (duration: 339.030068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:11.708176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.368219Z","time spent":"339.905697ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:13:51.962483Z","caller":"traceutil/trace.go:171","msg":"trace[881327875] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"223.324564ms","start":"2026-08-19T09:13:51.739132Z","end":"2026-08-19T09:13:51.962457Z","steps":["trace[881327875] 'process raft request' (duration: 223.017497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:13:52.611192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.291119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:13:52.611297Z","caller":"traceutil/trace.go:171","msg":"trace[1050074809] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"158.980471ms","start":"2026-08-19T09:13:52.452302Z","end":"2026-08-19T09:13:52.611282Z","steps":["trace[1050074809] 'process raft request' (duration: 17.520778ms)","trace[1050074809] 'compare' (duration: 141.180897ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:13:52.774619Z","caller":"traceutil/trace.go:171","msg":"trace[1280540544] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"322.279941ms","start":"2026-08-19T09:13:52.452303Z","end":"2026-08-19T09:13:52.774583Z","steps":["trace[1280540544] 'process raft request' (duration: 300.439215ms)","trace[1280540544] 'compare' (duration: 21.647622ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:13:52.774627Z","caller":"traceutil/trace.go:171","msg":"trace[1456182233] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"322.129797ms","start":"2026-08-19T09:13:52.452454Z","end":"2026-08-19T09:13:52.774584Z","steps":["trace[1456182233] 'process raft request' (duration: 322.064266ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:52.774718Z","caller":"traceutil/trace.go:171","msg":"trace[651196414] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"322.213579ms","start":"2026-08-19T09:13:52.452478Z","end":"2026-08-19T09:13:52.774691Z","steps":["trace[651196414] 'process raft request' (duration: 322.075476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:13:52.774775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:52.452439Z","time spent":"322.264941ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:13:52.774954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:52.45246Z","time spent":"322.319121ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:13:52.774972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:52.452276Z","time spent":"322.512916ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}