{"level":"warn","ts":"2026-08-11T07:38:48.202963Z","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-11T07:38:48.203093Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.119:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.119:2380","--initial-cluster=instance=https://199.19.213.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.119: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-11T07:38:48.203177Z","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-11T07:38:48.203195Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-08-11T07:38:48.203219Z","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-11T07:38:48.20415Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"]} {"level":"info","ts":"2026-08-11T07:38:48.204254Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.119: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-11T07:38:48.207341Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.815507ms"} {"level":"info","ts":"2026-08-11T07:38:48.21429Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ec9077c81c499df2","cluster-id":"7e82d3f5f740d3fb"} {"level":"info","ts":"2026-08-11T07:38:48.214366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T07:38:48.214393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 0"} {"level":"info","ts":"2026-08-11T07:38:48.214404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ec9077c81c499df2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T07:38:48.214418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 1"} {"level":"info","ts":"2026-08-11T07:38:48.214458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"warn","ts":"2026-08-11T07:38:48.21992Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T07:38:48.221607Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T07:38:48.222986Z","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-11T07:38:48.226836Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ec9077c81c499df2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T07:38:48.228111Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ec9077c81c499df2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T07:38:48.22981Z","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-11T07:38:48.229921Z","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-11T07:38:48.229933Z","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-11T07:38:48.2303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"info","ts":"2026-08-11T07:38:48.230549Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","added-peer-id":"ec9077c81c499df2","added-peer-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-08-11T07:38:48.231931Z","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-11T07:38:48.232126Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ec9077c81c499df2","initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T07:38:48.232165Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T07:38:48.232721Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-08-11T07:38:48.232819Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-08-11T07:38:49.115647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T07:38:49.115695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T07:38:49.115727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgPreVoteResp from ec9077c81c499df2 at term 1"} {"level":"info","ts":"2026-08-11T07:38:49.115746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became candidate at term 2"} {"level":"info","ts":"2026-08-11T07:38:49.11577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgVoteResp from ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-08-11T07:38:49.115782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became leader at term 2"} {"level":"info","ts":"2026-08-11T07:38:49.115794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ec9077c81c499df2 elected leader ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-08-11T07:38:49.116972Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ec9077c81c499df2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.119:2379]}","request-path":"/0/members/ec9077c81c499df2/attributes","cluster-id":"7e82d3f5f740d3fb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T07:38:49.117173Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:38:49.117849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T07:38:49.118197Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T07:38:49.118349Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T07:38:49.118447Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T07:38:49.120851Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T07:38:49.12105Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.119:2379"} {"level":"info","ts":"2026-08-11T07:38:49.124424Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:38:49.124509Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:38:49.124533Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:39:04.53425Z","caller":"traceutil/trace.go:171","msg":"trace[367908856] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"323.448378ms","start":"2026-08-11T07:39:04.210777Z","end":"2026-08-11T07:39:04.534225Z","steps":["trace[367908856] 'read index received' (duration: 323.153613ms)","trace[367908856] 'applied index is now lower than readState.Index' (duration: 293.855µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:04.534401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.605321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-11T07:39:04.534456Z","caller":"traceutil/trace.go:171","msg":"trace[1385952924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:363; }","duration":"323.717994ms","start":"2026-08-11T07:39:04.210727Z","end":"2026-08-11T07:39:04.534445Z","steps":["trace[1385952924] 'agreement among raft nodes before linearized reading' (duration: 323.613322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:04.534491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:04.210701Z","time spent":"323.782276ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T07:39:04.534628Z","caller":"traceutil/trace.go:171","msg":"trace[1679159069] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"434.637241ms","start":"2026-08-11T07:39:04.099954Z","end":"2026-08-11T07:39:04.534591Z","steps":["trace[1679159069] 'process raft request' (duration: 434.036548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:04.535226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:04.09993Z","time spent":"434.771653ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:39:05.277066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.686044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:39:05.277168Z","caller":"traceutil/trace.go:171","msg":"trace[250824626] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"550.692521ms","start":"2026-08-11T07:39:04.726458Z","end":"2026-08-11T07:39:05.277151Z","steps":["trace[250824626] 'read index received' (duration: 75.655459ms)","trace[250824626] 'applied index is now lower than readState.Index' (duration: 475.035962ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:05.277256Z","caller":"traceutil/trace.go:171","msg":"trace[1372434570] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"738.46091ms","start":"2026-08-11T07:39:04.538784Z","end":"2026-08-11T07:39:05.277245Z","steps":["trace[1372434570] 'process raft request' (duration: 263.350016ms)","trace[1372434570] 'compare' (duration: 474.45385ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:05.277332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:04.53876Z","time spent":"738.540162ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:39:05.277566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.103399ms","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-11T07:39:05.277598Z","caller":"traceutil/trace.go:171","msg":"trace[231729155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:364; }","duration":"551.160841ms","start":"2026-08-11T07:39:04.726427Z","end":"2026-08-11T07:39:05.277588Z","steps":["trace[231729155] 'agreement among raft nodes before linearized reading' (duration: 551.066009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:05.277627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:04.726412Z","time spent":"551.208681ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:39:05.277842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.161337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T07:39:05.277871Z","caller":"traceutil/trace.go:171","msg":"trace[1168741715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:364; }","duration":"332.234098ms","start":"2026-08-11T07:39:04.945628Z","end":"2026-08-11T07:39:05.277862Z","steps":["trace[1168741715] 'agreement among raft nodes before linearized reading' (duration: 332.180857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:05.277903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:04.945607Z","time spent":"332.290199ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:39:05.884829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427577677,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:39:06.307719Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.025290424s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-11T07:39:06.44544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.396735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:39:06.445645Z","caller":"traceutil/trace.go:171","msg":"trace[1472504797] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:376; }","duration":"1.061112283s","start":"2026-08-11T07:39:05.384515Z","end":"2026-08-11T07:39:06.445628Z","steps":["trace[1472504797] 'read index received' (duration: 923.456603ms)","trace[1472504797] 'applied index is now lower than readState.Index' (duration: 137.65479ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:06.44586Z","caller":"traceutil/trace.go:171","msg":"trace[766520267] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"1.163541537s","start":"2026-08-11T07:39:05.282306Z","end":"2026-08-11T07:39:06.445848Z","steps":["trace[766520267] 'process raft request' (duration: 1.025648972s)","trace[766520267] 'compare' (duration: 137.271282ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:06.445942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:05.282279Z","time spent":"1.163627738s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:06.446101Z","caller":"traceutil/trace.go:171","msg":"trace[895308593] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.163508295s","start":"2026-08-11T07:39:05.282584Z","end":"2026-08-11T07:39:06.446092Z","steps":["trace[895308593] 'process raft request' (duration: 1.162986274s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:06.44615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:05.282558Z","time spent":"1.163564936s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:39:06.44629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.499029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-08-11T07:39:06.44626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061719335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T07:39:06.446329Z","caller":"traceutil/trace.go:171","msg":"trace[30525502] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:366; }","duration":"765.558071ms","start":"2026-08-11T07:39:05.680762Z","end":"2026-08-11T07:39:06.44632Z","steps":["trace[30525502] 'agreement among raft nodes before linearized reading' (duration: 765.48566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:06.446357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:05.680747Z","time spent":"765.603612ms","remote":"127.0.0.1:58210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-08-11T07:39:06.44636Z","caller":"traceutil/trace.go:171","msg":"trace[1269946826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"1.061870168s","start":"2026-08-11T07:39:05.384465Z","end":"2026-08-11T07:39:06.446335Z","steps":["trace[1269946826] 'agreement among raft nodes before linearized reading' (duration: 1.061697475s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:06.446416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:05.384445Z","time spent":"1.06195704s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:39:10.641826Z","caller":"traceutil/trace.go:171","msg":"trace[1612073812] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"174.328286ms","start":"2026-08-11T07:39:10.467478Z","end":"2026-08-11T07:39:10.641806Z","steps":["trace[1612073812] 'read index received' (duration: 174.219044ms)","trace[1612073812] 'applied index is now lower than readState.Index' (duration: 108.542µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:10.641914Z","caller":"traceutil/trace.go:171","msg":"trace[699125147] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"175.01808ms","start":"2026-08-11T07:39:10.466884Z","end":"2026-08-11T07:39:10.641902Z","steps":["trace[699125147] 'process raft request' (duration: 174.793286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:10.642133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.625392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T07:39:10.642238Z","caller":"traceutil/trace.go:171","msg":"trace[948847948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:379; }","duration":"174.764935ms","start":"2026-08-11T07:39:10.46744Z","end":"2026-08-11T07:39:10.642205Z","steps":["trace[948847948] 'agreement among raft nodes before linearized reading' (duration: 174.52254ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:10.652677Z","caller":"traceutil/trace.go:171","msg":"trace[2120627072] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"184.556754ms","start":"2026-08-11T07:39:10.468082Z","end":"2026-08-11T07:39:10.652639Z","steps":["trace[2120627072] 'process raft request' (duration: 184.352489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.142603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427577930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:39:29.610495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.196910516s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T07:39:29.610904Z","caller":"traceutil/trace.go:171","msg":"trace[528030951] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.197407327s","start":"2026-08-11T07:39:28.413474Z","end":"2026-08-11T07:39:29.610882Z","steps":["trace[528030951] 'process raft request' (duration: 1.197195552s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.611038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.413456Z","time spent":"1.19750924s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:39:29.618733Z","caller":"traceutil/trace.go:171","msg":"trace[1332609571] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.201244737s","start":"2026-08-11T07:39:28.417447Z","end":"2026-08-11T07:39:29.618692Z","steps":["trace[1332609571] 'process raft request' (duration: 1.201108264s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:29.61875Z","caller":"traceutil/trace.go:171","msg":"trace[654976138] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.205052506s","start":"2026-08-11T07:39:28.413668Z","end":"2026-08-11T07:39:29.61872Z","steps":["trace[654976138] 'process raft request' (duration: 1.204849621s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.618901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.41366Z","time spent":"1.205168418s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:29.618914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.417435Z","time spent":"1.2013907s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:39:29.618993Z","caller":"traceutil/trace.go:171","msg":"trace[1942838951] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:503; }","duration":"976.803271ms","start":"2026-08-11T07:39:28.642177Z","end":"2026-08-11T07:39:29.61898Z","steps":["trace[1942838951] 'read index received' (duration: 968.61575ms)","trace[1942838951] 'applied index is now lower than readState.Index' (duration: 8.186811ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:29.619071Z","caller":"traceutil/trace.go:171","msg":"trace[2071453242] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.053941107s","start":"2026-08-11T07:39:28.565123Z","end":"2026-08-11T07:39:29.619064Z","steps":["trace[2071453242] 'process raft request' (duration: 1.053799624s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:29.619086Z","caller":"traceutil/trace.go:171","msg":"trace[809557662] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.144119268s","start":"2026-08-11T07:39:28.474945Z","end":"2026-08-11T07:39:29.619064Z","steps":["trace[809557662] 'process raft request' (duration: 1.143915013s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:29.619084Z","caller":"traceutil/trace.go:171","msg":"trace[674391119] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.195687178s","start":"2026-08-11T07:39:28.423384Z","end":"2026-08-11T07:39:29.619072Z","steps":["trace[674391119] 'process raft request' (duration: 1.195246548s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.619134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.565097Z","time spent":"1.054007528s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:29.619164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.474918Z","time spent":"1.144215369s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:29.619186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.423373Z","time spent":"1.1957798s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:39:29.619192Z","caller":"traceutil/trace.go:171","msg":"trace[274850566] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.195659998s","start":"2026-08-11T07:39:28.423441Z","end":"2026-08-11T07:39:29.619101Z","steps":["trace[274850566] 'process raft request' (duration: 1.195216858s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.61925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.069547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:29.619283Z","caller":"traceutil/trace.go:171","msg":"trace[2022986530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"977.134728ms","start":"2026-08-11T07:39:28.64214Z","end":"2026-08-11T07:39:29.619274Z","steps":["trace[2022986530] 'agreement among raft nodes before linearized reading' (duration: 977.056916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.619315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.642126Z","time spent":"977.183659ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T07:39:29.619322Z","caller":"traceutil/trace.go:171","msg":"trace[814089153] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.185318076s","start":"2026-08-11T07:39:28.433958Z","end":"2026-08-11T07:39:29.619276Z","steps":["trace[814089153] 'process raft request' (duration: 1.184783063s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:29.619354Z","caller":"traceutil/trace.go:171","msg":"trace[1655623662] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.167342798s","start":"2026-08-11T07:39:28.452Z","end":"2026-08-11T07:39:29.619342Z","steps":["trace[1655623662] 'process raft request' (duration: 1.166822466s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.619373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.423436Z","time spent":"1.195828301s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:29.619408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.45198Z","time spent":"1.167406069s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:29.619405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.43394Z","time spent":"1.185411528s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:39:29.619049Z","caller":"traceutil/trace.go:171","msg":"trace[1457453556] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.189591716s","start":"2026-08-11T07:39:28.429438Z","end":"2026-08-11T07:39:29.61903Z","steps":["trace[1457453556] 'process raft request' (duration: 1.189247728s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.619496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.42942Z","time spent":"1.190056807s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:39:29.619504Z","caller":"traceutil/trace.go:171","msg":"trace[556858050] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.201958854s","start":"2026-08-11T07:39:28.41753Z","end":"2026-08-11T07:39:29.619489Z","steps":["trace[556858050] 'process raft request' (duration: 1.201073213s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.619558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.417524Z","time spent":"1.202009935s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:39:29.618668Z","caller":"traceutil/trace.go:171","msg":"trace[2122585336] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.205076636s","start":"2026-08-11T07:39:28.413552Z","end":"2026-08-11T07:39:29.618629Z","steps":["trace[2122585336] 'process raft request' (duration: 1.204891942s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.619734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.413526Z","time spent":"1.206151511s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:29.623986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.4644ms","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-11T07:39:29.624025Z","caller":"traceutil/trace.go:171","msg":"trace[324057402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"240.538812ms","start":"2026-08-11T07:39:29.383478Z","end":"2026-08-11T07:39:29.624017Z","steps":["trace[324057402] 'agreement among raft nodes before linearized reading' (duration: 240.412459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.624135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.327994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T07:39:29.624159Z","caller":"traceutil/trace.go:171","msg":"trace[1406042494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"865.386615ms","start":"2026-08-11T07:39:28.758766Z","end":"2026-08-11T07:39:29.624153Z","steps":["trace[1406042494] 'agreement among raft nodes before linearized reading' (duration: 865.346554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.624178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.758753Z","time spent":"865.418856ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:39:29.624446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.459386ms","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-11T07:39:29.624488Z","caller":"traceutil/trace.go:171","msg":"trace[2015337360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"877.548238ms","start":"2026-08-11T07:39:28.746931Z","end":"2026-08-11T07:39:29.62448Z","steps":["trace[2015337360] 'agreement among raft nodes before linearized reading' (duration: 877.455436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.624494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.227575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T07:39:29.62452Z","caller":"traceutil/trace.go:171","msg":"trace[230107902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"866.298126ms","start":"2026-08-11T07:39:28.758213Z","end":"2026-08-11T07:39:29.624512Z","steps":["trace[230107902] 'agreement among raft nodes before linearized reading' (duration: 866.247385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:29.624524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.746908Z","time spent":"877.597959ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:39:29.624542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:28.758192Z","time spent":"866.346477ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:39:36.081391Z","caller":"traceutil/trace.go:171","msg":"trace[2000741637] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"437.441748ms","start":"2026-08-11T07:39:35.643913Z","end":"2026-08-11T07:39:36.081354Z","steps":["trace[2000741637] 'read index received' (duration: 436.655959ms)","trace[2000741637] 'applied index is now lower than readState.Index' (duration: 784.249µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:36.081547Z","caller":"traceutil/trace.go:171","msg":"trace[159092733] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"609.338062ms","start":"2026-08-11T07:39:35.47214Z","end":"2026-08-11T07:39:36.081478Z","steps":["trace[159092733] 'process raft request' (duration: 608.608765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:36.081682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.739995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:36.081757Z","caller":"traceutil/trace.go:171","msg":"trace[183964678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"437.869928ms","start":"2026-08-11T07:39:35.64387Z","end":"2026-08-11T07:39:36.08174Z","steps":["trace[183964678] 'agreement among raft nodes before linearized reading' (duration: 437.735715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:36.081813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:35.643853Z","time spent":"437.946319ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:39:36.081925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.761655ms","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-11T07:39:36.082001Z","caller":"traceutil/trace.go:171","msg":"trace[1852679566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"364.865337ms","start":"2026-08-11T07:39:35.717117Z","end":"2026-08-11T07:39:36.081982Z","steps":["trace[1852679566] 'agreement among raft nodes before linearized reading' (duration: 364.650482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:36.082065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.189149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T07:39:36.082119Z","caller":"traceutil/trace.go:171","msg":"trace[1351002242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"366.275801ms","start":"2026-08-11T07:39:35.715826Z","end":"2026-08-11T07:39:36.082102Z","steps":["trace[1351002242] 'agreement among raft nodes before linearized reading' (duration: 366.152368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:36.082175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:35.715813Z","time spent":"366.350673ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T07:39:36.082232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.035741ms","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-11T07:39:36.082335Z","caller":"traceutil/trace.go:171","msg":"trace[1063123101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"365.182585ms","start":"2026-08-11T07:39:35.717129Z","end":"2026-08-11T07:39:36.082311Z","steps":["trace[1063123101] 'agreement among raft nodes before linearized reading' (duration: 364.840797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:36.082384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:35.717117Z","time spent":"365.257786ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:39:36.081941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.022675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T07:39:36.082446Z","caller":"traceutil/trace.go:171","msg":"trace[2072310116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"366.602469ms","start":"2026-08-11T07:39:35.715824Z","end":"2026-08-11T07:39:36.082427Z","steps":["trace[2072310116] 'agreement among raft nodes before linearized reading' (duration: 365.881362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:36.082506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:35.715804Z","time spent":"366.68444ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:39:36.082532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:35.472116Z","time spent":"609.967577ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:39:36.082039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:35.717104Z","time spent":"364.926129ms","remote":"127.0.0.1:58292","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-11T07:39:36.637361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.033498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:39:36.637594Z","caller":"traceutil/trace.go:171","msg":"trace[1169419021] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"551.166876ms","start":"2026-08-11T07:39:36.086403Z","end":"2026-08-11T07:39:36.63757Z","steps":["trace[1169419021] 'process raft request' (duration: 180.836281ms)","trace[1169419021] 'compare' (duration: 369.895655ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:36.637707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:36.086385Z","time spent":"551.282948ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:37.068511Z","caller":"traceutil/trace.go:171","msg":"trace[116642653] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"981.379356ms","start":"2026-08-11T07:39:36.087104Z","end":"2026-08-11T07:39:37.068484Z","steps":["trace[116642653] 'process raft request' (duration: 981.178442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:37.068659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:36.087081Z","time spent":"981.505549ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:37.068801Z","caller":"traceutil/trace.go:171","msg":"trace[1282432052] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"981.529139ms","start":"2026-08-11T07:39:36.087257Z","end":"2026-08-11T07:39:37.068786Z","steps":["trace[1282432052] 'process raft request' (duration: 981.183141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:37.068902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:36.087229Z","time spent":"981.622992ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:37.069235Z","caller":"traceutil/trace.go:171","msg":"trace[1143570736] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"981.318625ms","start":"2026-08-11T07:39:36.087895Z","end":"2026-08-11T07:39:37.069214Z","steps":["trace[1143570736] 'process raft request' (duration: 980.833104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:37.069415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:36.087885Z","time spent":"981.475938ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:37.069425Z","caller":"traceutil/trace.go:171","msg":"trace[715734872] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:616; }","duration":"907.778512ms","start":"2026-08-11T07:39:36.16162Z","end":"2026-08-11T07:39:37.069398Z","steps":["trace[715734872] 'read index received' (duration: 105.62929ms)","trace[715734872] 'applied index is now lower than readState.Index' (duration: 802.147162ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:37.069751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.106741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jklf7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-11T07:39:37.069814Z","caller":"traceutil/trace.go:171","msg":"trace[1800653798] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jklf7; range_end:; response_count:1; response_revision:598; }","duration":"908.217904ms","start":"2026-08-11T07:39:36.161586Z","end":"2026-08-11T07:39:37.069803Z","steps":["trace[1800653798] 'agreement among raft nodes before linearized reading' (duration: 907.890385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:37.069851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:36.161573Z","time spent":"908.270704ms","remote":"127.0.0.1:58210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-jklf7\" "} {"level":"info","ts":"2026-08-11T07:39:37.069428Z","caller":"traceutil/trace.go:171","msg":"trace[1866740310] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"933.776377ms","start":"2026-08-11T07:39:36.135631Z","end":"2026-08-11T07:39:37.069408Z","steps":["trace[1866740310] 'process raft request' (duration: 933.47119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:37.070048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.04173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:37.070164Z","caller":"traceutil/trace.go:171","msg":"trace[1962128829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"428.196683ms","start":"2026-08-11T07:39:36.64195Z","end":"2026-08-11T07:39:37.070146Z","steps":["trace[1962128829] 'agreement among raft nodes before linearized reading' (duration: 428.05117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:37.070147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:36.135607Z","time spent":"934.342702ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:37.070269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:36.641928Z","time spent":"428.324036ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T07:39:37.775429Z","caller":"traceutil/trace.go:171","msg":"trace[908519097] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"690.412149ms","start":"2026-08-11T07:39:37.084986Z","end":"2026-08-11T07:39:37.775398Z","steps":["trace[908519097] 'process raft request' (duration: 690.251675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:37.775574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:37.084971Z","time spent":"690.529982ms","remote":"127.0.0.1:58210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:38.041199Z","caller":"traceutil/trace.go:171","msg":"trace[549846501] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"571.722646ms","start":"2026-08-11T07:39:37.469442Z","end":"2026-08-11T07:39:38.041165Z","steps":["trace[549846501] 'process raft request' (duration: 570.673552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:38.041341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:37.469414Z","time spent":"571.857379ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:39:39.14389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427578121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:39:39.644197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427578121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:39:39.80494Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.703155437s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T07:39:39.81255Z","caller":"traceutil/trace.go:171","msg":"trace[1914477110] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"1.168960467s","start":"2026-08-11T07:39:38.643547Z","end":"2026-08-11T07:39:39.812507Z","steps":["trace[1914477110] 'read index received' (duration: 1.161535523s)","trace[1914477110] 'applied index is now lower than readState.Index' (duration: 7.424074ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:39.812608Z","caller":"traceutil/trace.go:171","msg":"trace[1174514002] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.710903777s","start":"2026-08-11T07:39:38.101679Z","end":"2026-08-11T07:39:39.812583Z","steps":["trace[1174514002] 'process raft request' (duration: 1.703445173s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:39.812714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:38.101655Z","time spent":"1.71100583s","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:39:39.812745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168927775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:39.812812Z","caller":"traceutil/trace.go:171","msg":"trace[167376409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.169005847s","start":"2026-08-11T07:39:38.643788Z","end":"2026-08-11T07:39:39.812794Z","steps":["trace[167376409] 'agreement among raft nodes before linearized reading' (duration: 1.168868714s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:39.812818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169253623s","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-11T07:39:39.812842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:38.643732Z","time spent":"1.169104369s","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T07:39:39.812868Z","caller":"traceutil/trace.go:171","msg":"trace[1995492677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"1.169333815s","start":"2026-08-11T07:39:38.643505Z","end":"2026-08-11T07:39:39.812839Z","steps":["trace[1995492677] 'agreement among raft nodes before linearized reading' (duration: 1.169143761s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:39.812893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:38.643483Z","time spent":"1.169403376s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:39:39.813052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.310939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-11T07:39:39.813048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.830368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T07:39:39.813106Z","caller":"traceutil/trace.go:171","msg":"trace[980245058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"738.90837ms","start":"2026-08-11T07:39:39.074187Z","end":"2026-08-11T07:39:39.813095Z","steps":["trace[980245058] 'agreement among raft nodes before linearized reading' (duration: 738.763177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:39.813137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.074179Z","time spent":"738.950311ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:39:39.813075Z","caller":"traceutil/trace.go:171","msg":"trace[513452709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"739.37034ms","start":"2026-08-11T07:39:39.073697Z","end":"2026-08-11T07:39:39.813068Z","steps":["trace[513452709] 'agreement among raft nodes before linearized reading' (duration: 739.267408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:39.813257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.941771ms","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-11T07:39:39.81327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.073678Z","time spent":"739.529504ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T07:39:39.813284Z","caller":"traceutil/trace.go:171","msg":"trace[1742317946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"739.009452ms","start":"2026-08-11T07:39:39.074266Z","end":"2026-08-11T07:39:39.813276Z","steps":["trace[1742317946] 'agreement among raft nodes before linearized reading' (duration: 738.91794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:39.813304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.074244Z","time spent":"739.055573ms","remote":"127.0.0.1:58292","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-11T07:39:40.317719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427578128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:39:40.757475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.04232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:39:40.757715Z","caller":"traceutil/trace.go:171","msg":"trace[1007100311] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"940.27881ms","start":"2026-08-11T07:39:39.817415Z","end":"2026-08-11T07:39:40.757694Z","steps":["trace[1007100311] 'process raft request' (duration: 940.192108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:40.757779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.817392Z","time spent":"940.356092ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:40.757963Z","caller":"traceutil/trace.go:171","msg":"trace[1352089153] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"941.393265ms","start":"2026-08-11T07:39:39.816558Z","end":"2026-08-11T07:39:40.757951Z","steps":["trace[1352089153] 'process raft request' (duration: 752.795473ms)","trace[1352089153] 'compare' (duration: 187.874976ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:40.758027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.816542Z","time spent":"941.458207ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:40.758436Z","caller":"traceutil/trace.go:171","msg":"trace[902326943] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"940.624396ms","start":"2026-08-11T07:39:39.817799Z","end":"2026-08-11T07:39:40.758424Z","steps":["trace[902326943] 'process raft request' (duration: 939.859809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:40.758525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.817781Z","time spent":"940.710699ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:40.758546Z","caller":"traceutil/trace.go:171","msg":"trace[1508480053] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:625; }","duration":"941.580889ms","start":"2026-08-11T07:39:39.816939Z","end":"2026-08-11T07:39:40.758519Z","steps":["trace[1508480053] 'read index received' (duration: 752.526967ms)","trace[1508480053] 'applied index is now lower than readState.Index' (duration: 189.052622ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:40.758786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.842175ms","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-11T07:39:40.758832Z","caller":"traceutil/trace.go:171","msg":"trace[1903595455] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:605; }","duration":"941.911987ms","start":"2026-08-11T07:39:39.816909Z","end":"2026-08-11T07:39:40.758821Z","steps":["trace[1903595455] 'agreement among raft nodes before linearized reading' (duration: 941.833036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:40.758863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.816901Z","time spent":"941.955688ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-11T07:39:40.758883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.62319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:40.758945Z","caller":"traceutil/trace.go:171","msg":"trace[2121148581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"935.723143ms","start":"2026-08-11T07:39:39.823206Z","end":"2026-08-11T07:39:40.758929Z","steps":["trace[2121148581] 'agreement among raft nodes before linearized reading' (duration: 935.622631ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:40.758991Z","caller":"traceutil/trace.go:171","msg":"trace[1933267622] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"940.920604ms","start":"2026-08-11T07:39:39.818048Z","end":"2026-08-11T07:39:40.758968Z","steps":["trace[1933267622] 'process raft request' (duration: 939.740197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:40.759076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.823194Z","time spent":"935.833526ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:39:40.75917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:39.818036Z","time spent":"941.056928ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:42.647312Z","caller":"traceutil/trace.go:171","msg":"trace[2063726690] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"345.145419ms","start":"2026-08-11T07:39:42.302144Z","end":"2026-08-11T07:39:42.647289Z","steps":["trace[2063726690] 'process raft request' (duration: 274.308669ms)","trace[2063726690] 'compare' (duration: 70.473021ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:42.647714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:42.30212Z","time spent":"345.357115ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:39:42.649518Z","caller":"traceutil/trace.go:171","msg":"trace[1646924646] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"147.32086ms","start":"2026-08-11T07:39:42.502153Z","end":"2026-08-11T07:39:42.649474Z","steps":["trace[1646924646] 'process raft request' (duration: 147.153716ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:43.327813Z","caller":"traceutil/trace.go:171","msg":"trace[1939307231] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"134.085922ms","start":"2026-08-11T07:39:43.193705Z","end":"2026-08-11T07:39:43.327791Z","steps":["trace[1939307231] 'process raft request' (duration: 133.933699ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:43.408034Z","caller":"traceutil/trace.go:171","msg":"trace[1649224145] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"107.396151ms","start":"2026-08-11T07:39:43.300616Z","end":"2026-08-11T07:39:43.408012Z","steps":["trace[1649224145] 'read index received' (duration: 27.09795ms)","trace[1649224145] 'applied index is now lower than readState.Index' (duration: 80.297351ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:43.408152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.519094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:43.408185Z","caller":"traceutil/trace.go:171","msg":"trace[1594830902] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:658; }","duration":"107.609746ms","start":"2026-08-11T07:39:43.300564Z","end":"2026-08-11T07:39:43.408174Z","steps":["trace[1594830902] 'agreement among raft nodes before linearized reading' (duration: 107.527324ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:43.408304Z","caller":"traceutil/trace.go:171","msg":"trace[1816693320] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"203.53093ms","start":"2026-08-11T07:39:43.20474Z","end":"2026-08-11T07:39:43.408271Z","steps":["trace[1816693320] 'process raft request' (duration: 203.09678ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:45.502884Z","caller":"traceutil/trace.go:171","msg":"trace[1038037821] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"141.67548ms","start":"2026-08-11T07:39:45.361184Z","end":"2026-08-11T07:39:45.50286Z","steps":["trace[1038037821] 'process raft request' (duration: 140.489542ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:52.927658Z","caller":"traceutil/trace.go:171","msg":"trace[394541851] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"110.445562ms","start":"2026-08-11T07:39:52.817184Z","end":"2026-08-11T07:39:52.927629Z","steps":["trace[394541851] 'process raft request' (duration: 110.293648ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:52.93208Z","caller":"traceutil/trace.go:171","msg":"trace[548317859] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"113.35705ms","start":"2026-08-11T07:39:52.818705Z","end":"2026-08-11T07:39:52.932062Z","steps":["trace[548317859] 'process raft request' (duration: 113.239108ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:52.932332Z","caller":"traceutil/trace.go:171","msg":"trace[412300722] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"113.238987ms","start":"2026-08-11T07:39:52.819071Z","end":"2026-08-11T07:39:52.93231Z","steps":["trace[412300722] 'process raft request' (duration: 112.956211ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:52.932428Z","caller":"traceutil/trace.go:171","msg":"trace[264899397] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"112.366637ms","start":"2026-08-11T07:39:52.820046Z","end":"2026-08-11T07:39:52.932413Z","steps":["trace[264899397] 'process raft request' (duration: 112.181533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:56.143925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427578442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:39:56.705932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086466775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:39:56.706023Z","caller":"traceutil/trace.go:171","msg":"trace[1376322874] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:766; }","duration":"1.086608848s","start":"2026-08-11T07:39:55.619394Z","end":"2026-08-11T07:39:56.706003Z","steps":["trace[1376322874] 'count revisions from in-memory index tree' (duration: 1.086345532s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:56.706067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:55.619376Z","time spent":"1.086680389s","remote":"127.0.0.1:58352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:39:56.706052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438881952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nz25s\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-11T07:39:56.706595Z","caller":"traceutil/trace.go:171","msg":"trace[170815623] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nz25s; range_end:; response_count:1; response_revision:766; }","duration":"1.439005445s","start":"2026-08-11T07:39:55.267128Z","end":"2026-08-11T07:39:56.706133Z","steps":["trace[170815623] 'range keys from in-memory index tree' (duration: 1.438632076s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:56.706681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:55.267071Z","time spent":"1.439592309s","remote":"127.0.0.1:58210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nz25s\" "} {"level":"warn","ts":"2026-08-11T07:39:56.706966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.806146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:39:56.707181Z","caller":"traceutil/trace.go:171","msg":"trace[1373082423] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"1.063276413s","start":"2026-08-11T07:39:55.643785Z","end":"2026-08-11T07:39:56.707062Z","steps":["trace[1373082423] 'read index received' (duration: 785.77177ms)","trace[1373082423] 'applied index is now lower than readState.Index' (duration: 277.503073ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:56.707181Z","caller":"traceutil/trace.go:171","msg":"trace[1898318751] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"1.077051895s","start":"2026-08-11T07:39:55.630108Z","end":"2026-08-11T07:39:56.70716Z","steps":["trace[1898318751] 'process raft request' (duration: 799.594773ms)","trace[1898318751] 'compare' (duration: 275.983108ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:56.707287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063496059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:39:56.707306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:55.630082Z","time spent":"1.077151127s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:56.707332Z","caller":"traceutil/trace.go:171","msg":"trace[1380834508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"1.063569031s","start":"2026-08-11T07:39:55.643749Z","end":"2026-08-11T07:39:56.707319Z","steps":["trace[1380834508] 'agreement among raft nodes before linearized reading' (duration: 1.063491699s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:56.707371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:55.643736Z","time spent":"1.063627632s","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:39:56.708038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.894334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T07:39:56.708082Z","caller":"traceutil/trace.go:171","msg":"trace[1369601929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"545.974085ms","start":"2026-08-11T07:39:56.162098Z","end":"2026-08-11T07:39:56.708072Z","steps":["trace[1369601929] 'agreement among raft nodes before linearized reading' (duration: 545.576846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:56.708113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:56.162077Z","time spent":"546.028947ms","remote":"127.0.0.1:58292","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-11T07:40:11.343264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.530414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-11T07:40:11.343372Z","caller":"traceutil/trace.go:171","msg":"trace[752431673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"326.696648ms","start":"2026-08-11T07:40:11.016647Z","end":"2026-08-11T07:40:11.343343Z","steps":["trace[752431673] 'range keys from in-memory index tree' (duration: 326.34956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:11.34336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.847408ms","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-11T07:40:11.343461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:11.016626Z","time spent":"326.820751ms","remote":"127.0.0.1:58292","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-11T07:40:11.343461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.93691ms","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-11T07:40:11.343486Z","caller":"traceutil/trace.go:171","msg":"trace[1628113504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"326.027693ms","start":"2026-08-11T07:40:11.01744Z","end":"2026-08-11T07:40:11.343468Z","steps":["trace[1628113504] 'range keys from in-memory index tree' (duration: 325.633654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:11.343536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:11.017425Z","time spent":"326.100544ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T07:40:11.34353Z","caller":"traceutil/trace.go:171","msg":"trace[787813982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"332.046243ms","start":"2026-08-11T07:40:11.011466Z","end":"2026-08-11T07:40:11.343512Z","steps":["trace[787813982] 'range keys from in-memory index tree' (duration: 331.745646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:11.343513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.568722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-wljlx\" ","response":"range_response_count:1 size:5671"} {"level":"warn","ts":"2026-08-11T07:40:11.343572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:11.011446Z","time spent":"332.118184ms","remote":"127.0.0.1:58292","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-11T07:40:11.343591Z","caller":"traceutil/trace.go:171","msg":"trace[1590509393] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-wljlx; range_end:; response_count:1; response_revision:901; }","duration":"346.709655ms","start":"2026-08-11T07:40:10.996861Z","end":"2026-08-11T07:40:11.343571Z","steps":["trace[1590509393] 'range keys from in-memory index tree' (duration: 346.387118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:11.343633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:10.996836Z","time spent":"346.788387ms","remote":"127.0.0.1:58210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5694,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-wljlx\" "} {"level":"warn","ts":"2026-08-11T07:40:11.343667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.429771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-11T07:40:11.343688Z","caller":"traceutil/trace.go:171","msg":"trace[2080064816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"329.458063ms","start":"2026-08-11T07:40:11.014221Z","end":"2026-08-11T07:40:11.343679Z","steps":["trace[2080064816] 'range keys from in-memory index tree' (duration: 329.371461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:11.343704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:11.014217Z","time spent":"329.483883ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T07:40:12.341863Z","caller":"traceutil/trace.go:171","msg":"trace[26847032] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"100.632824ms","start":"2026-08-11T07:40:12.241195Z","end":"2026-08-11T07:40:12.341828Z","steps":["trace[26847032] 'process raft request' (duration: 38.295531ms)","trace[26847032] 'compare' (duration: 61.924553ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:40:48.980262Z","caller":"traceutil/trace.go:171","msg":"trace[2065464741] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"338.972352ms","start":"2026-08-11T07:40:48.641266Z","end":"2026-08-11T07:40:48.980238Z","steps":["trace[2065464741] 'read index received' (duration: 338.721206ms)","trace[2065464741] 'applied index is now lower than readState.Index' (duration: 250.266µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:40:48.980341Z","caller":"traceutil/trace.go:171","msg":"trace[1378906053] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"722.092193ms","start":"2026-08-11T07:40:48.258224Z","end":"2026-08-11T07:40:48.980317Z","steps":["trace[1378906053] 'process raft request' (duration: 721.865998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:48.980394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.108885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:40:48.980426Z","caller":"traceutil/trace.go:171","msg":"trace[1092795972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"339.184887ms","start":"2026-08-11T07:40:48.641231Z","end":"2026-08-11T07:40:48.980416Z","steps":["trace[1092795972] 'agreement among raft nodes before linearized reading' (duration: 339.112056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:48.980456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:48.641217Z","time spent":"339.232178ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:40:48.980637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:48.258203Z","time spent":"722.245916ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:40:50.367302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.256987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:40:50.367397Z","caller":"traceutil/trace.go:171","msg":"trace[961350622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"715.38334ms","start":"2026-08-11T07:40:49.65199Z","end":"2026-08-11T07:40:50.367373Z","steps":["trace[961350622] 'range keys from in-memory index tree' (duration: 715.168034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:50.36745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:49.651973Z","time spent":"715.462952ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:40:50.367575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.979074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T07:40:50.367634Z","caller":"traceutil/trace.go:171","msg":"trace[1445778734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"794.142719ms","start":"2026-08-11T07:40:49.573473Z","end":"2026-08-11T07:40:50.367616Z","steps":["trace[1445778734] 'range keys from in-memory index tree' (duration: 793.784029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:50.367661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:49.573448Z","time spent":"794.207401ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T07:40:50.367677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.899954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-11T07:40:50.367717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.272846ms","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-11T07:40:50.367754Z","caller":"traceutil/trace.go:171","msg":"trace[1580439112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"793.999736ms","start":"2026-08-11T07:40:49.573733Z","end":"2026-08-11T07:40:50.367732Z","steps":["trace[1580439112] 'range keys from in-memory index tree' (duration: 793.735269ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:40:50.367782Z","caller":"traceutil/trace.go:171","msg":"trace[1427806621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1041; }","duration":"795.407929ms","start":"2026-08-11T07:40:49.572357Z","end":"2026-08-11T07:40:50.367765Z","steps":["trace[1427806621] 'range keys from in-memory index tree' (duration: 795.099862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:50.367809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:49.573725Z","time spent":"794.070847ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:40:50.367815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.430327ms","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-11T07:40:50.36783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:49.572339Z","time spent":"795.476451ms","remote":"127.0.0.1:58292","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-11T07:40:50.367883Z","caller":"traceutil/trace.go:171","msg":"trace[1530031109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1041; }","duration":"793.362761ms","start":"2026-08-11T07:40:49.574498Z","end":"2026-08-11T07:40:50.36786Z","steps":["trace[1530031109] 'range keys from in-memory index tree' (duration: 792.272193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:50.368092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302056552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T07:40:50.368164Z","caller":"traceutil/trace.go:171","msg":"trace[696500016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"1.302818802s","start":"2026-08-11T07:40:49.065328Z","end":"2026-08-11T07:40:50.368147Z","steps":["trace[696500016] 'range keys from in-memory index tree' (duration: 1.301810597s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:50.368215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:49.065314Z","time spent":"1.302884733s","remote":"127.0.0.1:58292","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-11T07:40:50.36867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:49.574488Z","time spent":"794.15938ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T07:40:50.370154Z","caller":"traceutil/trace.go:171","msg":"trace[294091824] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"547.990114ms","start":"2026-08-11T07:40:49.822145Z","end":"2026-08-11T07:40:50.370135Z","steps":["trace[294091824] 'process raft request' (duration: 547.886922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:50.37028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:49.822121Z","time spent":"548.096537ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:57.092883Z","caller":"traceutil/trace.go:171","msg":"trace[78198351] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"120.07976ms","start":"2026-08-11T07:41:56.972771Z","end":"2026-08-11T07:41:57.092851Z","steps":["trace[78198351] 'process raft request' (duration: 107.892579ms)","trace[78198351] 'compare' (duration: 12.023537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:44:27.318282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.522083ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T07:44:27.659909Z","caller":"traceutil/trace.go:171","msg":"trace[117984396] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"347.150992ms","start":"2026-08-11T07:44:27.312734Z","end":"2026-08-11T07:44:27.659885Z","steps":["trace[117984396] 'process raft request' (duration: 346.986528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:27.660889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:27.312711Z","time spent":"347.315416ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:44:27.687734Z","caller":"traceutil/trace.go:171","msg":"trace[1739757402] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"227.366953ms","start":"2026-08-11T07:44:27.460338Z","end":"2026-08-11T07:44:27.687705Z","steps":["trace[1739757402] 'process raft request' (duration: 227.118758ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:33.930923Z","caller":"traceutil/trace.go:171","msg":"trace[300359997] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"242.245441ms","start":"2026-08-11T07:44:33.688628Z","end":"2026-08-11T07:44:33.930874Z","steps":["trace[300359997] 'process raft request' (duration: 241.941364ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:33.980958Z","caller":"traceutil/trace.go:171","msg":"trace[88999124] linearizableReadLoop","detail":"{readStateIndex:1862; appliedIndex:1860; }","duration":"148.835801ms","start":"2026-08-11T07:44:33.832098Z","end":"2026-08-11T07:44:33.980934Z","steps":["trace[88999124] 'read index received' (duration: 98.51217ms)","trace[88999124] 'applied index is now lower than readState.Index' (duration: 50.322591ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:44:33.981717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.604989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:44:33.981754Z","caller":"traceutil/trace.go:171","msg":"trace[426062196] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1776; }","duration":"149.715851ms","start":"2026-08-11T07:44:33.83203Z","end":"2026-08-11T07:44:33.981746Z","steps":["trace[426062196] 'agreement among raft nodes before linearized reading' (duration: 149.001755ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:33.982004Z","caller":"traceutil/trace.go:171","msg":"trace[301714661] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"184.399485ms","start":"2026-08-11T07:44:33.797596Z","end":"2026-08-11T07:44:33.981996Z","steps":["trace[301714661] 'process raft request' (duration: 183.219787ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:34.155944Z","caller":"traceutil/trace.go:171","msg":"trace[1441806162] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"166.461654ms","start":"2026-08-11T07:44:33.989424Z","end":"2026-08-11T07:44:34.155886Z","steps":["trace[1441806162] 'process raft request' (duration: 145.542793ms)","trace[1441806162] 'compare' (duration: 20.578533ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:44:34.181323Z","caller":"traceutil/trace.go:171","msg":"trace[1303301635] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1862; }","duration":"175.643051ms","start":"2026-08-11T07:44:34.005655Z","end":"2026-08-11T07:44:34.181298Z","steps":["trace[1303301635] 'read index received' (duration: 129.136879ms)","trace[1303301635] 'applied index is now lower than readState.Index' (duration: 46.504862ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:44:34.181454Z","caller":"traceutil/trace.go:171","msg":"trace[23443734] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"191.763179ms","start":"2026-08-11T07:44:33.989676Z","end":"2026-08-11T07:44:34.181439Z","steps":["trace[23443734] 'process raft request' (duration: 191.461102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:34.181701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.197198ms","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-11T07:44:34.181761Z","caller":"traceutil/trace.go:171","msg":"trace[1552667165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1778; }","duration":"163.338731ms","start":"2026-08-11T07:44:34.018409Z","end":"2026-08-11T07:44:34.181747Z","steps":["trace[1552667165] 'agreement among raft nodes before linearized reading' (duration: 163.041874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:34.181867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.217873ms","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-11T07:44:34.18192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.999563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T07:44:34.181923Z","caller":"traceutil/trace.go:171","msg":"trace[1731339169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1778; }","duration":"176.318856ms","start":"2026-08-11T07:44:34.005588Z","end":"2026-08-11T07:44:34.181907Z","steps":["trace[1731339169] 'agreement among raft nodes before linearized reading' (duration: 176.182513ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:34.181949Z","caller":"traceutil/trace.go:171","msg":"trace[692937617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1778; }","duration":"160.082854ms","start":"2026-08-11T07:44:34.021857Z","end":"2026-08-11T07:44:34.18194Z","steps":["trace[692937617] 'agreement among raft nodes before linearized reading' (duration: 159.999643ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:36.180929Z","caller":"traceutil/trace.go:171","msg":"trace[1740524547] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"190.140999ms","start":"2026-08-11T07:44:35.990755Z","end":"2026-08-11T07:44:36.180896Z","steps":["trace[1740524547] 'process raft request' (duration: 189.476404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:36.503683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.587779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:44:36.503811Z","caller":"traceutil/trace.go:171","msg":"trace[1418700187] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1868; }","duration":"316.198527ms","start":"2026-08-11T07:44:36.187592Z","end":"2026-08-11T07:44:36.50379Z","steps":["trace[1418700187] 'read index received' (duration: 166.30138ms)","trace[1418700187] 'applied index is now lower than readState.Index' (duration: 149.895727ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:44:36.504139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.555955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T07:44:36.504192Z","caller":"traceutil/trace.go:171","msg":"trace[2025139013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1783; }","duration":"316.645747ms","start":"2026-08-11T07:44:36.187532Z","end":"2026-08-11T07:44:36.504178Z","steps":["trace[2025139013] 'agreement among raft nodes before linearized reading' (duration: 316.436142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:36.504234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:36.187516Z","time spent":"316.709379ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:44:36.503901Z","caller":"traceutil/trace.go:171","msg":"trace[1989141722] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"316.576175ms","start":"2026-08-11T07:44:36.187278Z","end":"2026-08-11T07:44:36.503855Z","steps":["trace[1989141722] 'process raft request' (duration: 166.700799ms)","trace[1989141722] 'compare' (duration: 149.071446ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:44:36.504823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.10683ms","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-11T07:44:36.504864Z","caller":"traceutil/trace.go:171","msg":"trace[1618923215] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1783; }","duration":"118.223073ms","start":"2026-08-11T07:44:36.38663Z","end":"2026-08-11T07:44:36.504853Z","steps":["trace[1618923215] 'agreement among raft nodes before linearized reading' (duration: 118.130781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:36.505076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:36.187255Z","time spent":"317.577439ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:44:36.505232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.483686ms","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-11T07:44:36.505276Z","caller":"traceutil/trace.go:171","msg":"trace[1025637381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1783; }","duration":"282.530197ms","start":"2026-08-11T07:44:36.222737Z","end":"2026-08-11T07:44:36.505267Z","steps":["trace[1025637381] 'agreement among raft nodes before linearized reading' (duration: 282.421694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:36.505352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.564553ms","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-11T07:44:36.505533Z","caller":"traceutil/trace.go:171","msg":"trace[1586763772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1783; }","duration":"301.842639ms","start":"2026-08-11T07:44:36.203624Z","end":"2026-08-11T07:44:36.505467Z","steps":["trace[1586763772] 'agreement among raft nodes before linearized reading' (duration: 301.096282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:36.505638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:36.203608Z","time spent":"302.017223ms","remote":"127.0.0.1:58292","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-11T07:44:36.505567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.681363ms","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-11T07:44:36.50583Z","caller":"traceutil/trace.go:171","msg":"trace[1120032062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1783; }","duration":"283.95899ms","start":"2026-08-11T07:44:36.221859Z","end":"2026-08-11T07:44:36.505818Z","steps":["trace[1120032062] 'agreement among raft nodes before linearized reading' (duration: 283.601632ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:36.791477Z","caller":"traceutil/trace.go:171","msg":"trace[644783247] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"281.829949ms","start":"2026-08-11T07:44:36.509597Z","end":"2026-08-11T07:44:36.791427Z","steps":["trace[644783247] 'process raft request' (duration: 258.436051ms)","trace[644783247] 'compare' (duration: 23.083141ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:44:36.808515Z","caller":"traceutil/trace.go:171","msg":"trace[941277240] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"298.390418ms","start":"2026-08-11T07:44:36.5101Z","end":"2026-08-11T07:44:36.808491Z","steps":["trace[941277240] 'process raft request' (duration: 298.285336ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:36.808508Z","caller":"traceutil/trace.go:171","msg":"trace[1896090365] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"297.089798ms","start":"2026-08-11T07:44:36.511383Z","end":"2026-08-11T07:44:36.808473Z","steps":["trace[1896090365] 'process raft request' (duration: 297.042137ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:44:36.808964Z","caller":"traceutil/trace.go:171","msg":"trace[1576609779] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"298.948542ms","start":"2026-08-11T07:44:36.509994Z","end":"2026-08-11T07:44:36.808943Z","steps":["trace[1576609779] 'process raft request' (duration: 298.198204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:56.180696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.97442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:44:56.180809Z","caller":"traceutil/trace.go:171","msg":"trace[1881219955] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1898; }","duration":"474.163845ms","start":"2026-08-11T07:44:55.70662Z","end":"2026-08-11T07:44:56.180784Z","steps":["trace[1881219955] 'count revisions from in-memory index tree' (duration: 473.885218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:56.180861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:55.706597Z","time spent":"474.250747ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:44:56.181407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.573238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:44:56.181457Z","caller":"traceutil/trace.go:171","msg":"trace[1175609910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"467.656819ms","start":"2026-08-11T07:44:55.713786Z","end":"2026-08-11T07:44:56.181443Z","steps":["trace[1175609910] 'range keys from in-memory index tree' (duration: 467.502705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:56.181491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:55.713765Z","time spent":"467.715391ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:44:56.910942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427581346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:44:57.41149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335060427581346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:44:57.459413Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.205004808s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T07:44:57.459958Z","caller":"traceutil/trace.go:171","msg":"trace[498647440] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"1.205641753s","start":"2026-08-11T07:44:56.254282Z","end":"2026-08-11T07:44:57.459924Z","steps":["trace[498647440] 'process raft request' (duration: 1.205432228s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:57.46015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:56.25425Z","time spent":"1.205780575s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:44:57.464986Z","caller":"traceutil/trace.go:171","msg":"trace[2044897846] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"1.054797516s","start":"2026-08-11T07:44:56.41016Z","end":"2026-08-11T07:44:57.464958Z","steps":["trace[2044897846] 'read index received' (duration: 1.049870678s)","trace[2044897846] 'applied index is now lower than readState.Index' (duration: 4.925708ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:44:57.465233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.155597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T07:44:57.46526Z","caller":"traceutil/trace.go:171","msg":"trace[1442870046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"834.27278ms","start":"2026-08-11T07:44:56.63098Z","end":"2026-08-11T07:44:57.465253Z","steps":["trace[1442870046] 'agreement among raft nodes before linearized reading' (duration: 834.116636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:57.465284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:56.630948Z","time spent":"834.330752ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:44:57.465291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.586971ms","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-11T07:44:57.46544Z","caller":"traceutil/trace.go:171","msg":"trace[480074307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1899; }","duration":"481.727795ms","start":"2026-08-11T07:44:56.983606Z","end":"2026-08-11T07:44:57.465334Z","steps":["trace[480074307] 'agreement among raft nodes before linearized reading' (duration: 481.51186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:57.465518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:56.983581Z","time spent":"481.902688ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T07:44:57.465556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.260437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T07:44:57.465579Z","caller":"traceutil/trace.go:171","msg":"trace[483223529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1899; }","duration":"482.306468ms","start":"2026-08-11T07:44:56.983265Z","end":"2026-08-11T07:44:57.465572Z","steps":["trace[483223529] 'agreement among raft nodes before linearized reading' (duration: 482.222556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:57.465596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:56.983253Z","time spent":"482.339309ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:44:57.465697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.983609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T07:44:57.465794Z","caller":"traceutil/trace.go:171","msg":"trace[281459619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1899; }","duration":"487.137643ms","start":"2026-08-11T07:44:56.978633Z","end":"2026-08-11T07:44:57.465771Z","steps":["trace[281459619] 'agreement among raft nodes before linearized reading' (duration: 486.882877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:57.465752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.110069ms","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-11T07:44:57.465845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:56.978605Z","time spent":"487.223414ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:44:57.465847Z","caller":"traceutil/trace.go:171","msg":"trace[1866293008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1899; }","duration":"481.228372ms","start":"2026-08-11T07:44:56.984601Z","end":"2026-08-11T07:44:57.465829Z","steps":["trace[1866293008] 'agreement among raft nodes before linearized reading' (duration: 480.802152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:57.466075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055310348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:44:57.466419Z","caller":"traceutil/trace.go:171","msg":"trace[1752538468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1899; }","duration":"1.056296322s","start":"2026-08-11T07:44:56.410103Z","end":"2026-08-11T07:44:57.466399Z","steps":["trace[1752538468] 'agreement among raft nodes before linearized reading' (duration: 1.054952779s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:44:57.466584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:56.410072Z","time spent":"1.056417415s","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:44:57.466869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:44:56.984591Z","time spent":"481.296174ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T07:45:15.901921Z","caller":"traceutil/trace.go:171","msg":"trace[1609485657] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"301.818056ms","start":"2026-08-11T07:45:15.600079Z","end":"2026-08-11T07:45:15.901897Z","steps":["trace[1609485657] 'read index received' (duration: 284.896853ms)","trace[1609485657] 'applied index is now lower than readState.Index' (duration: 16.920213ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:45:15.901963Z","caller":"traceutil/trace.go:171","msg":"trace[275300690] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"302.78581ms","start":"2026-08-11T07:45:15.599135Z","end":"2026-08-11T07:45:15.901921Z","steps":["trace[275300690] 'process raft request' (duration: 289.468342ms)","trace[275300690] 'compare' (duration: 12.041527ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:15.902199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.092042ms","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-11T07:45:15.902249Z","caller":"traceutil/trace.go:171","msg":"trace[2108969078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1987; }","duration":"302.210345ms","start":"2026-08-11T07:45:15.600028Z","end":"2026-08-11T07:45:15.902238Z","steps":["trace[2108969078] 'agreement among raft nodes before linearized reading' (duration: 302.01054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:15.902284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:15.600003Z","time spent":"302.273707ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T07:45:15.90229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:15.599115Z","time spent":"303.041085ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:15.902471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.852547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:45:15.902686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.630054ms","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-11T07:45:15.902721Z","caller":"traceutil/trace.go:171","msg":"trace[1288233750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1987; }","duration":"247.721266ms","start":"2026-08-11T07:45:15.654992Z","end":"2026-08-11T07:45:15.902714Z","steps":["trace[1288233750] 'agreement among raft nodes before linearized reading' (duration: 247.619244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:15.902754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.964514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T07:45:15.902884Z","caller":"traceutil/trace.go:171","msg":"trace[938060412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1987; }","duration":"251.096337ms","start":"2026-08-11T07:45:15.651771Z","end":"2026-08-11T07:45:15.902868Z","steps":["trace[938060412] 'agreement among raft nodes before linearized reading' (duration: 250.872042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:15.902906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.373793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T07:45:15.903014Z","caller":"traceutil/trace.go:171","msg":"trace[1179792198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1987; }","duration":"251.500696ms","start":"2026-08-11T07:45:15.651498Z","end":"2026-08-11T07:45:15.902999Z","steps":["trace[1179792198] 'agreement among raft nodes before linearized reading' (duration: 251.227319ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:15.902579Z","caller":"traceutil/trace.go:171","msg":"trace[856235172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1987; }","duration":"257.992471ms","start":"2026-08-11T07:45:15.644578Z","end":"2026-08-11T07:45:15.90257Z","steps":["trace[856235172] 'agreement among raft nodes before linearized reading' (duration: 257.860218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:16.538806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.501115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:45:16.539101Z","caller":"traceutil/trace.go:171","msg":"trace[228489443] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2083; }","duration":"146.022661ms","start":"2026-08-11T07:45:16.39306Z","end":"2026-08-11T07:45:16.539083Z","steps":["trace[228489443] 'read index received' (duration: 33.260173ms)","trace[228489443] 'applied index is now lower than readState.Index' (duration: 112.761398ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:45:16.539135Z","caller":"traceutil/trace.go:171","msg":"trace[1878962124] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"630.522972ms","start":"2026-08-11T07:45:15.90857Z","end":"2026-08-11T07:45:16.539093Z","steps":["trace[1878962124] 'process raft request' (duration: 19.628528ms)","trace[1878962124] 'compare' (duration: 610.277369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:16.539207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.163554ms","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":"info","ts":"2026-08-11T07:45:16.539234Z","caller":"traceutil/trace.go:171","msg":"trace[1134650908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1991; }","duration":"146.261207ms","start":"2026-08-11T07:45:16.39296Z","end":"2026-08-11T07:45:16.539221Z","steps":["trace[1134650908] 'agreement among raft nodes before linearized reading' (duration: 146.176015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:16.539333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:15.908543Z","time spent":"630.687206ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:16.53945Z","caller":"traceutil/trace.go:171","msg":"trace[1506978862] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"628.180006ms","start":"2026-08-11T07:45:15.911252Z","end":"2026-08-11T07:45:16.539432Z","steps":["trace[1506978862] 'process raft request' (duration: 627.671224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:16.539502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:15.911231Z","time spent":"628.245388ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:16.539681Z","caller":"traceutil/trace.go:171","msg":"trace[846880765] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"627.802007ms","start":"2026-08-11T07:45:15.911821Z","end":"2026-08-11T07:45:16.539623Z","steps":["trace[846880765] 'process raft request' (duration: 627.222484ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:16.539724Z","caller":"traceutil/trace.go:171","msg":"trace[548799390] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"628.35552ms","start":"2026-08-11T07:45:15.911333Z","end":"2026-08-11T07:45:16.539689Z","steps":["trace[548799390] 'process raft request' (duration: 627.683784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:16.539921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:15.911811Z","time spent":"627.984961ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:16.540002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:15.911326Z","time spent":"628.539425ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:17.941147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.178402ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T07:45:17.941383Z","caller":"traceutil/trace.go:171","msg":"trace[1975278075] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"324.471486ms","start":"2026-08-11T07:45:17.61689Z","end":"2026-08-11T07:45:17.941361Z","steps":["trace[1975278075] 'read index received' (duration: 223.051787ms)","trace[1975278075] 'applied index is now lower than readState.Index' (duration: 101.418099ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:17.941581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.65143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:17.941683Z","caller":"traceutil/trace.go:171","msg":"trace[1217273198] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1991; }","duration":"324.828784ms","start":"2026-08-11T07:45:17.616824Z","end":"2026-08-11T07:45:17.941653Z","steps":["trace[1217273198] 'agreement among raft nodes before linearized reading' (duration: 324.63737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:17.941729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.10654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:45:17.941732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:17.616791Z","time spent":"324.927546ms","remote":"127.0.0.1:44852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:45:17.941711Z","caller":"traceutil/trace.go:171","msg":"trace[1854342850] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"315.889411ms","start":"2026-08-11T07:45:17.62576Z","end":"2026-08-11T07:45:17.941649Z","steps":["trace[1854342850] 'process raft request' (duration: 315.494942ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:17.941772Z","caller":"traceutil/trace.go:171","msg":"trace[170714726] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1992; }","duration":"106.195682ms","start":"2026-08-11T07:45:17.835565Z","end":"2026-08-11T07:45:17.941761Z","steps":["trace[170714726] 'agreement among raft nodes before linearized reading' (duration: 106.085909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:17.941921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:17.62573Z","time spent":"316.079686ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:17.941852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.446693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:17.9421Z","caller":"traceutil/trace.go:171","msg":"trace[1673648679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1992; }","duration":"214.73398ms","start":"2026-08-11T07:45:17.727358Z","end":"2026-08-11T07:45:17.942092Z","steps":["trace[1673648679] 'agreement among raft nodes before linearized reading' (duration: 214.234838ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:19.464261Z","caller":"traceutil/trace.go:171","msg":"trace[1370841489] linearizableReadLoop","detail":"{readStateIndex:2095; appliedIndex:2094; }","duration":"492.961232ms","start":"2026-08-11T07:45:18.971274Z","end":"2026-08-11T07:45:19.464236Z","steps":["trace[1370841489] 'read index received' (duration: 492.698346ms)","trace[1370841489] 'applied index is now lower than readState.Index' (duration: 262.046µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:19.46441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.107536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:19.464452Z","caller":"traceutil/trace.go:171","msg":"trace[1243706513] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1998; }","duration":"493.222208ms","start":"2026-08-11T07:45:18.971218Z","end":"2026-08-11T07:45:19.46444Z","steps":["trace[1243706513] 'agreement among raft nodes before linearized reading' (duration: 493.126396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:19.464497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:18.971192Z","time spent":"493.29604ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:45:19.464481Z","caller":"traceutil/trace.go:171","msg":"trace[1126804477] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"712.948936ms","start":"2026-08-11T07:45:18.751486Z","end":"2026-08-11T07:45:19.464435Z","steps":["trace[1126804477] 'process raft request' (duration: 712.560277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:19.465024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:18.751444Z","time spent":"713.300404ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:20.39457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.498764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:20.394653Z","caller":"traceutil/trace.go:171","msg":"trace[737429670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1998; }","duration":"753.620257ms","start":"2026-08-11T07:45:19.641015Z","end":"2026-08-11T07:45:20.394635Z","steps":["trace[737429670] 'range keys from in-memory index tree' (duration: 753.435752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:20.394721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:19.640998Z","time spent":"753.685819ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:45:20.395377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.035073ms","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-11T07:45:20.395421Z","caller":"traceutil/trace.go:171","msg":"trace[395481594] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1998; }","duration":"456.120565ms","start":"2026-08-11T07:45:19.93929Z","end":"2026-08-11T07:45:20.395411Z","steps":["trace[395481594] 'range keys from in-memory index tree' (duration: 455.894279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:20.395456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:19.939269Z","time spent":"456.180416ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-11T07:45:20.39605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.706226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:45:20.396214Z","caller":"traceutil/trace.go:171","msg":"trace[969180977] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"411.685325ms","start":"2026-08-11T07:45:19.984497Z","end":"2026-08-11T07:45:20.396183Z","steps":["trace[969180977] 'read index received' (duration: 236.554219ms)","trace[969180977] 'applied index is now lower than readState.Index' (duration: 175.129086ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:45:20.39634Z","caller":"traceutil/trace.go:171","msg":"trace[2012838614] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"447.866147ms","start":"2026-08-11T07:45:19.94846Z","end":"2026-08-11T07:45:20.396326Z","steps":["trace[2012838614] 'process raft request' (duration: 272.752072ms)","trace[2012838614] 'compare' (duration: 174.533712ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:20.396412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:19.948447Z","time spent":"447.93016ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:20.396603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.110265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T07:45:20.396658Z","caller":"traceutil/trace.go:171","msg":"trace[569837634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1999; }","duration":"412.217598ms","start":"2026-08-11T07:45:19.984425Z","end":"2026-08-11T07:45:20.396643Z","steps":["trace[569837634] 'agreement among raft nodes before linearized reading' (duration: 412.109775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:20.396693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:19.984395Z","time spent":"412.290979ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T07:45:20.58506Z","caller":"traceutil/trace.go:171","msg":"trace[351808003] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"182.501351ms","start":"2026-08-11T07:45:20.402527Z","end":"2026-08-11T07:45:20.585029Z","steps":["trace[351808003] 'process raft request' (duration: 143.539652ms)","trace[351808003] 'compare' (duration: 38.561469ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:45:21.110224Z","caller":"traceutil/trace.go:171","msg":"trace[83596711] linearizableReadLoop","detail":"{readStateIndex:2101; appliedIndex:2100; }","duration":"467.591597ms","start":"2026-08-11T07:45:20.642594Z","end":"2026-08-11T07:45:21.110186Z","steps":["trace[83596711] 'read index received' (duration: 467.359011ms)","trace[83596711] 'applied index is now lower than readState.Index' (duration: 230.976µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:45:21.110991Z","caller":"traceutil/trace.go:171","msg":"trace[238108126] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"513.327347ms","start":"2026-08-11T07:45:20.597633Z","end":"2026-08-11T07:45:21.11096Z","steps":["trace[238108126] 'process raft request' (duration: 512.325634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:21.111182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:20.597619Z","time spent":"513.449861ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:21.111502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.901778ms","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-11T07:45:21.11156Z","caller":"traceutil/trace.go:171","msg":"trace[2093161443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2004; }","duration":"469.002541ms","start":"2026-08-11T07:45:20.642543Z","end":"2026-08-11T07:45:21.111545Z","steps":["trace[2093161443] 'agreement among raft nodes before linearized reading' (duration: 468.832447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:21.111565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.830197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:21.111622Z","caller":"traceutil/trace.go:171","msg":"trace[356218197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2004; }","duration":"468.948679ms","start":"2026-08-11T07:45:20.64266Z","end":"2026-08-11T07:45:21.111609Z","steps":["trace[356218197] 'agreement among raft nodes before linearized reading' (duration: 468.841707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:21.111623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:20.642515Z","time spent":"469.095752ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T07:45:21.11165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:20.642637Z","time spent":"469.00863ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:45:21.111916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.061498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T07:45:21.111939Z","caller":"traceutil/trace.go:171","msg":"trace[1625767833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2004; }","duration":"468.099519ms","start":"2026-08-11T07:45:20.643834Z","end":"2026-08-11T07:45:21.111934Z","steps":["trace[1625767833] 'agreement among raft nodes before linearized reading' (duration: 468.010467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:21.111964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:20.643824Z","time spent":"468.136391ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T07:45:21.255275Z","caller":"traceutil/trace.go:171","msg":"trace[443305449] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"135.986131ms","start":"2026-08-11T07:45:21.119005Z","end":"2026-08-11T07:45:21.254991Z","steps":["trace[443305449] 'process raft request' (duration: 39.85865ms)","trace[443305449] 'compare' (duration: 95.995118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:21.939265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.055963ms","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-11T07:45:21.939397Z","caller":"traceutil/trace.go:171","msg":"trace[1186012568] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2006; }","duration":"469.220647ms","start":"2026-08-11T07:45:21.470128Z","end":"2026-08-11T07:45:21.939349Z","steps":["trace[1186012568] 'count revisions from in-memory index tree' (duration: 468.93921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:21.939445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:21.470107Z","time spent":"469.32826ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:45:21.939529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.559694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:21.93965Z","caller":"traceutil/trace.go:171","msg":"trace[1785371078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2006; }","duration":"295.153787ms","start":"2026-08-11T07:45:21.644468Z","end":"2026-08-11T07:45:21.939622Z","steps":["trace[1785371078] 'range keys from in-memory index tree' (duration: 294.421549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.045339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.547806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:45:23.045509Z","caller":"traceutil/trace.go:171","msg":"trace[1232411534] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"643.117113ms","start":"2026-08-11T07:45:22.402351Z","end":"2026-08-11T07:45:23.045468Z","steps":["trace[1232411534] 'process raft request' (duration: 58.181427ms)","trace[1232411534] 'compare' (duration: 584.275569ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:23.045715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:22.402316Z","time spent":"643.336948ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:23.055226Z","caller":"traceutil/trace.go:171","msg":"trace[633325414] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"616.308314ms","start":"2026-08-11T07:45:22.438898Z","end":"2026-08-11T07:45:23.055206Z","steps":["trace[633325414] 'process raft request' (duration: 616.186781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.055395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:22.438865Z","time spent":"616.451796ms","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:23.055413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.883833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T07:45:23.055211Z","caller":"traceutil/trace.go:171","msg":"trace[713613905] linearizableReadLoop","detail":"{readStateIndex:2106; appliedIndex:2105; }","duration":"464.667678ms","start":"2026-08-11T07:45:22.59052Z","end":"2026-08-11T07:45:23.055188Z","steps":["trace[713613905] 'read index received' (duration: 464.500754ms)","trace[713613905] 'applied index is now lower than readState.Index' (duration: 166.164µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:23.055801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.534427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T07:45:23.05583Z","caller":"traceutil/trace.go:171","msg":"trace[1348967536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2008; }","duration":"436.587879ms","start":"2026-08-11T07:45:22.619233Z","end":"2026-08-11T07:45:23.055821Z","steps":["trace[1348967536] 'agreement among raft nodes before linearized reading' (duration: 436.520017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.055864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:22.619214Z","time spent":"436.64253ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T07:45:23.056008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.668846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:23.056042Z","caller":"traceutil/trace.go:171","msg":"trace[2097955837] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2008; }","duration":"297.743588ms","start":"2026-08-11T07:45:22.758288Z","end":"2026-08-11T07:45:23.056031Z","steps":["trace[2097955837] 'agreement among raft nodes before linearized reading' (duration: 297.669656ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:23.055683Z","caller":"traceutil/trace.go:171","msg":"trace[1934690948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2008; }","duration":"465.17101ms","start":"2026-08-11T07:45:22.590448Z","end":"2026-08-11T07:45:23.055619Z","steps":["trace[1934690948] 'agreement among raft nodes before linearized reading' (duration: 464.816092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.056396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:22.590418Z","time spent":"465.945318ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T07:45:23.267496Z","caller":"traceutil/trace.go:171","msg":"trace[680942904] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2106; }","duration":"206.591745ms","start":"2026-08-11T07:45:23.060883Z","end":"2026-08-11T07:45:23.267475Z","steps":["trace[680942904] 'read index received' (duration: 75.859958ms)","trace[680942904] 'applied index is now lower than readState.Index' (duration: 130.730587ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:45:23.26792Z","caller":"traceutil/trace.go:171","msg":"trace[1918831293] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"207.438155ms","start":"2026-08-11T07:45:23.060465Z","end":"2026-08-11T07:45:23.267904Z","steps":["trace[1918831293] 'process raft request' (duration: 206.843721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.268379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.445945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-11T07:45:23.268507Z","caller":"traceutil/trace.go:171","msg":"trace[1292014392] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2010; }","duration":"207.62025ms","start":"2026-08-11T07:45:23.060861Z","end":"2026-08-11T07:45:23.268482Z","steps":["trace[1292014392] 'agreement among raft nodes before linearized reading' (duration: 207.265221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.310932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.07974ms","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-11T07:45:23.311001Z","caller":"traceutil/trace.go:171","msg":"trace[1768394701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2011; }","duration":"174.231033ms","start":"2026-08-11T07:45:23.136756Z","end":"2026-08-11T07:45:23.310987Z","steps":["trace[1768394701] 'agreement among raft nodes before linearized reading' (duration: 173.998967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.311162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.711668ms","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-11T07:45:23.311203Z","caller":"traceutil/trace.go:171","msg":"trace[1155185090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2011; }","duration":"193.80422ms","start":"2026-08-11T07:45:23.117393Z","end":"2026-08-11T07:45:23.311198Z","steps":["trace[1155185090] 'agreement among raft nodes before linearized reading' (duration: 193.732208ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:23.311279Z","caller":"traceutil/trace.go:171","msg":"trace[971236753] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"142.793374ms","start":"2026-08-11T07:45:23.168455Z","end":"2026-08-11T07:45:23.311248Z","steps":["trace[971236753] 'process raft request' (duration: 142.162929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.856613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.944863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:45:23.856996Z","caller":"traceutil/trace.go:171","msg":"trace[1238194337] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"540.24353ms","start":"2026-08-11T07:45:23.316706Z","end":"2026-08-11T07:45:23.85695Z","steps":["trace[1238194337] 'process raft request' (duration: 384.859376ms)","trace[1238194337] 'compare' (duration: 154.79379ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:23.857197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:23.316686Z","time spent":"540.398063ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:23.857672Z","caller":"traceutil/trace.go:171","msg":"trace[1583648966] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"540.793283ms","start":"2026-08-11T07:45:23.31674Z","end":"2026-08-11T07:45:23.857533Z","steps":["trace[1583648966] 'process raft request' (duration: 540.527436ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:23.857756Z","caller":"traceutil/trace.go:171","msg":"trace[1522211027] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2109; }","duration":"539.581135ms","start":"2026-08-11T07:45:23.31816Z","end":"2026-08-11T07:45:23.857741Z","steps":["trace[1522211027] 'read index received' (duration: 383.457122ms)","trace[1522211027] 'applied index is now lower than readState.Index' (duration: 156.109902ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:23.857773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:23.316711Z","time spent":"541.03148ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:23.857846Z","caller":"traceutil/trace.go:171","msg":"trace[591523022] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"540.878036ms","start":"2026-08-11T07:45:23.316926Z","end":"2026-08-11T07:45:23.857804Z","steps":["trace[591523022] 'process raft request' (duration: 540.532817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.857953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.232144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:45:23.858048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:23.316915Z","time spent":"541.025879ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:23.858091Z","caller":"traceutil/trace.go:171","msg":"trace[1120094514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2014; }","duration":"213.415088ms","start":"2026-08-11T07:45:23.644662Z","end":"2026-08-11T07:45:23.858077Z","steps":["trace[1120094514] 'agreement among raft nodes before linearized reading' (duration: 213.262495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.857889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.716948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:23.858474Z","caller":"traceutil/trace.go:171","msg":"trace[723916973] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:2014; }","duration":"540.318762ms","start":"2026-08-11T07:45:23.318144Z","end":"2026-08-11T07:45:23.858463Z","steps":["trace[723916973] 'agreement among raft nodes before linearized reading' (duration: 539.698337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:23.858505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:23.318137Z","time spent":"540.359762ms","remote":"127.0.0.1:58106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-08-11T07:45:23.96463Z","caller":"traceutil/trace.go:171","msg":"trace[1052055309] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"104.139274ms","start":"2026-08-11T07:45:23.860453Z","end":"2026-08-11T07:45:23.964592Z","steps":["trace[1052055309] 'process raft request' (duration: 103.928709ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:23.965625Z","caller":"traceutil/trace.go:171","msg":"trace[347471284] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"103.051527ms","start":"2026-08-11T07:45:23.862529Z","end":"2026-08-11T07:45:23.96558Z","steps":["trace[347471284] 'process raft request' (duration: 102.856082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:26.143732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.573396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:26.14387Z","caller":"traceutil/trace.go:171","msg":"trace[1624466232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2022; }","duration":"498.780461ms","start":"2026-08-11T07:45:25.645056Z","end":"2026-08-11T07:45:26.143837Z","steps":["trace[1624466232] 'range keys from in-memory index tree' (duration: 498.455853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:26.143901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.173806ms","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-11T07:45:26.144005Z","caller":"traceutil/trace.go:171","msg":"trace[1608225689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2022; }","duration":"279.3558ms","start":"2026-08-11T07:45:25.864623Z","end":"2026-08-11T07:45:26.143979Z","steps":["trace[1608225689] 'range keys from in-memory index tree' (duration: 278.950301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:26.144235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.848039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T07:45:26.144325Z","caller":"traceutil/trace.go:171","msg":"trace[444599529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2022; }","duration":"872.994233ms","start":"2026-08-11T07:45:25.271307Z","end":"2026-08-11T07:45:26.144302Z","steps":["trace[444599529] 'range keys from in-memory index tree' (duration: 872.534251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:26.14437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:25.271284Z","time spent":"873.076825ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T07:45:26.144411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.960929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-11T07:45:26.144418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.612458ms","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-11T07:45:26.144454Z","caller":"traceutil/trace.go:171","msg":"trace[352139854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2022; }","duration":"281.022401ms","start":"2026-08-11T07:45:25.863418Z","end":"2026-08-11T07:45:26.14444Z","steps":["trace[352139854] 'range keys from in-memory index tree' (duration: 280.866475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:26.143939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:25.645021Z","time spent":"498.904814ms","remote":"127.0.0.1:58012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:45:26.14452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.544992ms","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-11T07:45:26.144458Z","caller":"traceutil/trace.go:171","msg":"trace[2082847826] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2022; }","duration":"670.70305ms","start":"2026-08-11T07:45:25.473743Z","end":"2026-08-11T07:45:26.144447Z","steps":["trace[2082847826] 'range keys from in-memory index tree' (duration: 670.377832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:26.14473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:25.473713Z","time spent":"670.998507ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-11T07:45:26.145472Z","caller":"traceutil/trace.go:171","msg":"trace[20676536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2022; }","duration":"281.723877ms","start":"2026-08-11T07:45:25.862881Z","end":"2026-08-11T07:45:26.144605Z","steps":["trace[20676536] 'range keys from in-memory index tree' (duration: 281.318907ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:26.828529Z","caller":"traceutil/trace.go:171","msg":"trace[1298046145] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"677.279167ms","start":"2026-08-11T07:45:26.151217Z","end":"2026-08-11T07:45:26.828496Z","steps":["trace[1298046145] 'process raft request' (duration: 677.064432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:26.828842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:26.151204Z","time spent":"677.42495ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:27.253265Z","caller":"traceutil/trace.go:171","msg":"trace[1322510872] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"1.102007463s","start":"2026-08-11T07:45:26.151221Z","end":"2026-08-11T07:45:27.253229Z","steps":["trace[1322510872] 'process raft request' (duration: 1.099930233s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:27.253595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:26.151196Z","time spent":"1.102264579s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:27.253748Z","caller":"traceutil/trace.go:171","msg":"trace[2075764593] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"1.101282845s","start":"2026-08-11T07:45:26.152418Z","end":"2026-08-11T07:45:27.253701Z","steps":["trace[2075764593] 'process raft request' (duration: 1.100792863s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:27.253837Z","caller":"traceutil/trace.go:171","msg":"trace[454613922] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"1.102069204s","start":"2026-08-11T07:45:26.151723Z","end":"2026-08-11T07:45:27.253793Z","steps":["trace[454613922] 'process raft request' (duration: 1.101380198s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:27.253986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:26.152409Z","time spent":"1.101455349s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:45:27.254036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:26.151716Z","time spent":"1.102218187s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:27.254238Z","caller":"traceutil/trace.go:171","msg":"trace[1752551939] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"1.097998607s","start":"2026-08-11T07:45:26.156219Z","end":"2026-08-11T07:45:27.254218Z","steps":["trace[1752551939] 'process raft request' (duration: 1.097043375s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:27.254368Z","caller":"traceutil/trace.go:171","msg":"trace[784389714] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"194.009794ms","start":"2026-08-11T07:45:27.060345Z","end":"2026-08-11T07:45:27.254355Z","steps":["trace[784389714] 'process raft request' (duration: 192.96252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:27.256101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:26.15621Z","time spent":"1.099679207s","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:45:30.488431Z","caller":"traceutil/trace.go:171","msg":"trace[564333036] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2157; }","duration":"157.432263ms","start":"2026-08-11T07:45:30.330968Z","end":"2026-08-11T07:45:30.4884Z","steps":["trace[564333036] 'read index received' (duration: 157.134836ms)","trace[564333036] 'applied index is now lower than readState.Index' (duration: 296.067µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:45:30.488636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.642199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:45:30.488674Z","caller":"traceutil/trace.go:171","msg":"trace[574194586] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"564.493458ms","start":"2026-08-11T07:45:29.924139Z","end":"2026-08-11T07:45:30.488632Z","steps":["trace[574194586] 'process raft request' (duration: 564.049097ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:45:30.488676Z","caller":"traceutil/trace.go:171","msg":"trace[1182677532] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2059; }","duration":"157.776012ms","start":"2026-08-11T07:45:30.330888Z","end":"2026-08-11T07:45:30.488664Z","steps":["trace[1182677532] 'agreement among raft nodes before linearized reading' (duration: 157.636698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:45:30.488894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:45:29.924101Z","time spent":"564.690343ms","remote":"127.0.0.1:58210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:48:04.531671Z","caller":"traceutil/trace.go:171","msg":"trace[1198061589] linearizableReadLoop","detail":"{readStateIndex:2904; appliedIndex:2903; }","duration":"138.865871ms","start":"2026-08-11T07:48:04.392777Z","end":"2026-08-11T07:48:04.531643Z","steps":["trace[1198061589] 'read index received' (duration: 63.461608ms)","trace[1198061589] 'applied index is now lower than readState.Index' (duration: 75.403063ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:48:04.531756Z","caller":"traceutil/trace.go:171","msg":"trace[1270462151] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"145.29841ms","start":"2026-08-11T07:48:04.386411Z","end":"2026-08-11T07:48:04.53171Z","steps":["trace[1270462151] 'process raft request' (duration: 69.800235ms)","trace[1270462151] 'compare' (duration: 75.232069ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:48:04.53184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.015515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:48:04.531886Z","caller":"traceutil/trace.go:171","msg":"trace[754756795] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2774; }","duration":"139.099547ms","start":"2026-08-11T07:48:04.39277Z","end":"2026-08-11T07:48:04.531869Z","steps":["trace[754756795] 'agreement among raft nodes before linearized reading' (duration: 138.989184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:48:30.428713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.854928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:48:30.428812Z","caller":"traceutil/trace.go:171","msg":"trace[1861369282] linearizableReadLoop","detail":"{readStateIndex:3041; appliedIndex:3040; }","duration":"148.435127ms","start":"2026-08-11T07:48:30.280361Z","end":"2026-08-11T07:48:30.428796Z","steps":["trace[1861369282] 'read index received' (duration: 59.612µs)","trace[1861369282] 'applied index is now lower than readState.Index' (duration: 148.374635ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:48:30.429056Z","caller":"traceutil/trace.go:171","msg":"trace[1016078571] transaction","detail":"{read_only:false; response_revision:2905; number_of_response:1; }","duration":"194.981467ms","start":"2026-08-11T07:48:30.234027Z","end":"2026-08-11T07:48:30.429008Z","steps":["trace[1016078571] 'process raft request' (duration: 32.719508ms)","trace[1016078571] 'compare' (duration: 161.724815ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:48:30.429267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.888718ms","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-11T07:48:30.429304Z","caller":"traceutil/trace.go:171","msg":"trace[1362131359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2905; }","duration":"148.995611ms","start":"2026-08-11T07:48:30.2803Z","end":"2026-08-11T07:48:30.429295Z","steps":["trace[1362131359] 'agreement among raft nodes before linearized reading' (duration: 148.796256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:48:30.429618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.341418ms","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-11T07:48:30.429725Z","caller":"traceutil/trace.go:171","msg":"trace[1237238223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2905; }","duration":"102.468122ms","start":"2026-08-11T07:48:30.327233Z","end":"2026-08-11T07:48:30.429701Z","steps":["trace[1237238223] 'agreement among raft nodes before linearized reading' (duration: 102.157395ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:48:48.2226Z","caller":"traceutil/trace.go:171","msg":"trace[230395213] linearizableReadLoop","detail":"{readStateIndex:3133; appliedIndex:3132; }","duration":"331.610331ms","start":"2026-08-11T07:48:47.890958Z","end":"2026-08-11T07:48:48.222568Z","steps":["trace[230395213] 'read index received' (duration: 324.784143ms)","trace[230395213] 'applied index is now lower than readState.Index' (duration: 6.824608ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:48:48.222879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.886298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-08-11T07:48:48.222922Z","caller":"traceutil/trace.go:171","msg":"trace[1241093079] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2993; }","duration":"332.005192ms","start":"2026-08-11T07:48:47.890902Z","end":"2026-08-11T07:48:48.222908Z","steps":["trace[1241093079] 'agreement among raft nodes before linearized reading' (duration: 331.805757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:48:48.222958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:48:47.890876Z","time spent":"332.074473ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-11T07:48:48.223009Z","caller":"traceutil/trace.go:171","msg":"trace[488192891] transaction","detail":"{read_only:false; response_revision:2993; number_of_response:1; }","duration":"426.607549ms","start":"2026-08-11T07:48:47.796345Z","end":"2026-08-11T07:48:48.222953Z","steps":["trace[488192891] 'process raft request' (duration: 426.014394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:48:48.223228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:48:47.796326Z","time spent":"426.790943ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:48:48.607906Z","caller":"traceutil/trace.go:171","msg":"trace[625326367] transaction","detail":"{read_only:false; response_revision:2996; number_of_response:1; }","duration":"109.729432ms","start":"2026-08-11T07:48:48.498137Z","end":"2026-08-11T07:48:48.607866Z","steps":["trace[625326367] 'process raft request' (duration: 109.66836ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:48:48.608449Z","caller":"traceutil/trace.go:171","msg":"trace[1941628509] transaction","detail":"{read_only:false; response_revision:2994; number_of_response:1; }","duration":"380.316895ms","start":"2026-08-11T07:48:48.228119Z","end":"2026-08-11T07:48:48.608436Z","steps":["trace[1941628509] 'process raft request' (duration: 319.191395ms)","trace[1941628509] 'compare' (duration: 60.142695ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:48:48.608567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:48:48.228085Z","time spent":"380.394567ms","remote":"127.0.0.1:58292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:48:48.608701Z","caller":"traceutil/trace.go:171","msg":"trace[1513180438] linearizableReadLoop","detail":"{readStateIndex:3134; appliedIndex:3133; }","duration":"289.693937ms","start":"2026-08-11T07:48:48.318996Z","end":"2026-08-11T07:48:48.60869Z","steps":["trace[1513180438] 'read index received' (duration: 228.248628ms)","trace[1513180438] 'applied index is now lower than readState.Index' (duration: 61.443749ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:48:48.6088Z","caller":"traceutil/trace.go:171","msg":"trace[1897610043] transaction","detail":"{read_only:false; response_revision:2995; number_of_response:1; }","duration":"196.913094ms","start":"2026-08-11T07:48:48.411876Z","end":"2026-08-11T07:48:48.60879Z","steps":["trace[1897610043] 'process raft request' (duration: 195.856298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:48:48.610075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.07752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T07:48:48.61012Z","caller":"traceutil/trace.go:171","msg":"trace[1614528482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2996; }","duration":"291.187354ms","start":"2026-08-11T07:48:48.318922Z","end":"2026-08-11T07:48:48.61011Z","steps":["trace[1614528482] 'agreement among raft nodes before linearized reading' (duration: 290.066747ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:48:49.176522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1629} {"level":"info","ts":"2026-08-11T07:48:49.43285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1629,"took":"255.773939ms","hash":923520287,"current-db-size-bytes":24625152,"current-db-size":"25 MB","current-db-size-in-use-bytes":6336512,"current-db-size-in-use":"6.3 MB"} {"level":"info","ts":"2026-08-11T07:48:49.432939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":923520287,"revision":1629,"compact-revision":-1} {"level":"info","ts":"2026-08-11T07:48:54.673309Z","caller":"traceutil/trace.go:171","msg":"trace[1650981784] transaction","detail":"{read_only:false; response_revision:3029; number_of_response:1; }","duration":"114.97141ms","start":"2026-08-11T07:48:54.558308Z","end":"2026-08-11T07:48:54.67328Z","steps":["trace[1650981784] 'process raft request' (duration: 103.030326ms)","trace[1650981784] 'compare' (duration: 11.640287ms)"],"step_count":2}