{"level":"warn","ts":"2026-04-28T06:54:17.488256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:54:17.488354Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T06:54:17.48843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:54:17.488444Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-28T06:54:17.488466Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:54:17.489125Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-28T06:54:17.489198Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T06:54:17.493898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.501593ms"} {"level":"info","ts":"2026-04-28T06:54:17.504744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-28T06:54:17.504857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:54:17.504877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-28T06:54:17.504888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:54:17.504897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-28T06:54:17.504929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-28T06:54:17.514034Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:54:17.515378Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:54:17.517778Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T06:54:17.519838Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:54:17.526207Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:54:17.52685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-28T06:54:17.527022Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:54:17.527122Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:54:17.527156Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:54:17.527066Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-28T06:54:17.527285Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:54:17.527378Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-28T06:54:17.527445Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-28T06:54:17.527595Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:54:17.527661Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:54:18.407034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:54:18.407094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:54:18.407117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-28T06:54:18.40714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:54:18.407148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-28T06:54:18.407159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-28T06:54:18.407168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-28T06:54:18.411667Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:54:18.412266Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:54:18.412295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:54:18.412261Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:54:18.412497Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:54:18.412547Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:54:18.412772Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:54:18.412857Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:54:18.412877Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:54:18.414721Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-28T06:54:18.415344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:54:19.94384Z","caller":"traceutil/trace.go:171","msg":"trace[1789665917] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"194.212892ms","start":"2026-04-28T06:54:19.749605Z","end":"2026-04-28T06:54:19.943818Z","steps":["trace[1789665917] 'read index received' (duration: 194.064579ms)","trace[1789665917] 'applied index is now lower than readState.Index' (duration: 147.403µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:19.943887Z","caller":"traceutil/trace.go:171","msg":"trace[1437459769] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"200.44991ms","start":"2026-04-28T06:54:19.743392Z","end":"2026-04-28T06:54:19.943842Z","steps":["trace[1437459769] 'process raft request' (duration: 200.219455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:19.94401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.386797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:19.944057Z","caller":"traceutil/trace.go:171","msg":"trace[1011412973] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"194.477ms","start":"2026-04-28T06:54:19.749571Z","end":"2026-04-28T06:54:19.944048Z","steps":["trace[1011412973] 'agreement among raft nodes before linearized reading' (duration: 194.363917ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:20.214573Z","caller":"traceutil/trace.go:171","msg":"trace[632501943] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"377.786038ms","start":"2026-04-28T06:54:19.836764Z","end":"2026-04-28T06:54:20.21455Z","steps":["trace[632501943] 'process raft request' (duration: 357.305161ms)","trace[632501943] 'compare' (duration: 19.80577ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:20.214657Z","caller":"traceutil/trace.go:171","msg":"trace[1585711492] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"270.701533ms","start":"2026-04-28T06:54:19.943912Z","end":"2026-04-28T06:54:20.214614Z","steps":["trace[1585711492] 'read index received' (duration: 250.122804ms)","trace[1585711492] 'applied index is now lower than readState.Index' (duration: 20.577449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:20.214802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.711591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:20.214858Z","caller":"traceutil/trace.go:171","msg":"trace[1289237766] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"441.808595ms","start":"2026-04-28T06:54:19.773036Z","end":"2026-04-28T06:54:20.214844Z","steps":["trace[1289237766] 'agreement among raft nodes before linearized reading' (duration: 441.670761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.214898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.773018Z","time spent":"441.871336ms","remote":"127.0.0.1:59612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-28T06:54:20.214994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.836633Z","time spent":"377.996313ms","remote":"127.0.0.1:59976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:20.214996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.765128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:54:20.215158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.342557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:20.215219Z","caller":"traceutil/trace.go:171","msg":"trace[296017382] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"375.027159ms","start":"2026-04-28T06:54:19.840184Z","end":"2026-04-28T06:54:20.215211Z","steps":["trace[296017382] 'process raft request' (duration: 374.325041ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:20.214798Z","caller":"traceutil/trace.go:171","msg":"trace[1178004892] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"374.452802ms","start":"2026-04-28T06:54:19.840291Z","end":"2026-04-28T06:54:20.214744Z","steps":["trace[1178004892] 'process raft request' (duration: 374.282248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.840172Z","time spent":"375.067109ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:20.215273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.513081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:20.215309Z","caller":"traceutil/trace.go:171","msg":"trace[1721115265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"440.560162ms","start":"2026-04-28T06:54:19.774739Z","end":"2026-04-28T06:54:20.215299Z","steps":["trace[1721115265] 'agreement among raft nodes before linearized reading' (duration: 440.513761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.046123ms","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:4"} {"level":"warn","ts":"2026-04-28T06:54:20.215353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.774733Z","time spent":"440.612473ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:54:20.215374Z","caller":"traceutil/trace.go:171","msg":"trace[999852614] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"376.115855ms","start":"2026-04-28T06:54:19.83924Z","end":"2026-04-28T06:54:20.215356Z","steps":["trace[999852614] 'agreement among raft nodes before linearized reading' (duration: 375.993302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.840273Z","time spent":"375.006587ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:20.21542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.839229Z","time spent":"376.182526ms","remote":"127.0.0.1:59638","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":"warn","ts":"2026-04-28T06:54:20.215438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.29174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:20.215461Z","caller":"traceutil/trace.go:171","msg":"trace[146530756] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"336.31816ms","start":"2026-04-28T06:54:19.879137Z","end":"2026-04-28T06:54:20.215455Z","steps":["trace[146530756] 'agreement among raft nodes before linearized reading' (duration: 336.28282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.879127Z","time spent":"336.351741ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:54:20.215525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.300976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:20.215564Z","caller":"traceutil/trace.go:171","msg":"trace[1045244389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"442.339698ms","start":"2026-04-28T06:54:19.773216Z","end":"2026-04-28T06:54:20.215555Z","steps":["trace[1045244389] 'agreement among raft nodes before linearized reading' (duration: 442.279955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.773198Z","time spent":"442.388119ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:54:20.21563Z","caller":"traceutil/trace.go:171","msg":"trace[1671332768] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"375.954131ms","start":"2026-04-28T06:54:19.839669Z","end":"2026-04-28T06:54:20.215623Z","steps":["trace[1671332768] 'process raft request' (duration: 374.485754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.21568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.839651Z","time spent":"376.002372ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:20.215739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.557183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:54:20.215749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.661139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:20.215776Z","caller":"traceutil/trace.go:171","msg":"trace[1773536360] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"376.68833ms","start":"2026-04-28T06:54:19.839082Z","end":"2026-04-28T06:54:20.21577Z","steps":["trace[1773536360] 'agreement among raft nodes before linearized reading' (duration: 376.653089ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:20.215776Z","caller":"traceutil/trace.go:171","msg":"trace[1384880202] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"442.606094ms","start":"2026-04-28T06:54:19.773161Z","end":"2026-04-28T06:54:20.215767Z","steps":["trace[1384880202] 'agreement among raft nodes before linearized reading' (duration: 442.556723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.357197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:20.215835Z","caller":"traceutil/trace.go:171","msg":"trace[514800473] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"375.574522ms","start":"2026-04-28T06:54:19.840237Z","end":"2026-04-28T06:54:20.215812Z","steps":["trace[514800473] 'process raft request' (duration: 374.30487ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:20.215841Z","caller":"traceutil/trace.go:171","msg":"trace[613861188] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"379.436529ms","start":"2026-04-28T06:54:19.836393Z","end":"2026-04-28T06:54:20.215829Z","steps":["trace[613861188] 'agreement among raft nodes before linearized reading' (duration: 379.361238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.836381Z","time spent":"379.496031ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:54:20.215905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.840231Z","time spent":"375.643463ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:54:20.215232Z","caller":"traceutil/trace.go:171","msg":"trace[296967779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"440.433799ms","start":"2026-04-28T06:54:19.774788Z","end":"2026-04-28T06:54:20.215221Z","steps":["trace[296967779] 'agreement among raft nodes before linearized reading' (duration: 440.331506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.215793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.839049Z","time spent":"376.741021ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:54:20.215979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.774782Z","time spent":"441.191268ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:54:20.215806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.773092Z","time spent":"442.707617ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-28T06:54:20.216174Z","caller":"traceutil/trace.go:171","msg":"trace[1306606286] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"376.938955ms","start":"2026-04-28T06:54:19.839224Z","end":"2026-04-28T06:54:20.216163Z","steps":["trace[1306606286] 'process raft request' (duration: 374.848842ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:20.216255Z","caller":"traceutil/trace.go:171","msg":"trace[218039974] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"376.592267ms","start":"2026-04-28T06:54:19.839642Z","end":"2026-04-28T06:54:20.216234Z","steps":["trace[218039974] 'process raft request' (duration: 374.476913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.216271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.839172Z","time spent":"377.06283ms","remote":"127.0.0.1:59806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:20.216335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.839626Z","time spent":"376.67411ms","remote":"127.0.0.1:59726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:54:20.215168Z","caller":"traceutil/trace.go:171","msg":"trace[1763564760] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:0; response_revision:10; }","duration":"379.969753ms","start":"2026-04-28T06:54:19.83514Z","end":"2026-04-28T06:54:20.21511Z","steps":["trace[1763564760] 'agreement among raft nodes before linearized reading' (duration: 379.655785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.216697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.835122Z","time spent":"381.561273ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"info","ts":"2026-04-28T06:54:20.217053Z","caller":"traceutil/trace.go:171","msg":"trace[888153331] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"376.921707ms","start":"2026-04-28T06:54:19.840096Z","end":"2026-04-28T06:54:20.217018Z","steps":["trace[888153331] 'process raft request' (duration: 374.108956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:20.217148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:19.840086Z","time spent":"377.021739ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:54:21.25194Z","caller":"traceutil/trace.go:171","msg":"trace[1694169096] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:85; }","duration":"417.073899ms","start":"2026-04-28T06:54:20.834841Z","end":"2026-04-28T06:54:21.251915Z","steps":["trace[1694169096] 'read index received' (duration: 400.778018ms)","trace[1694169096] 'applied index is now lower than readState.Index' (duration: 16.294771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:21.252112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.250524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:54:21.252103Z","caller":"traceutil/trace.go:171","msg":"trace[916923396] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"430.648192ms","start":"2026-04-28T06:54:20.821415Z","end":"2026-04-28T06:54:21.252064Z","steps":["trace[916923396] 'process raft request' (duration: 414.29984ms)","trace[916923396] 'compare' (duration: 16.067265ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:21.252156Z","caller":"traceutil/trace.go:171","msg":"trace[1905090219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:82; }","duration":"417.357437ms","start":"2026-04-28T06:54:20.834786Z","end":"2026-04-28T06:54:21.252143Z","steps":["trace[1905090219] 'agreement among raft nodes before linearized reading' (duration: 417.225344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:21.25221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:20.834766Z","time spent":"417.436488ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:54:21.252251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:20.821388Z","time spent":"430.792786ms","remote":"127.0.0.1:59900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:54:23.557118Z","caller":"traceutil/trace.go:171","msg":"trace[1543427552] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"216.202367ms","start":"2026-04-28T06:54:23.340862Z","end":"2026-04-28T06:54:23.557065Z","steps":["trace[1543427552] 'process raft request' (duration: 215.807698ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:23.557221Z","caller":"traceutil/trace.go:171","msg":"trace[1952997513] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:247; }","duration":"169.251912ms","start":"2026-04-28T06:54:23.38773Z","end":"2026-04-28T06:54:23.556982Z","steps":["trace[1952997513] 'read index received' (duration: 168.943755ms)","trace[1952997513] 'applied index is now lower than readState.Index' (duration: 307.007µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:23.557308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.55661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:23.557408Z","caller":"traceutil/trace.go:171","msg":"trace[1980827547] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:0; response_revision:243; }","duration":"169.713104ms","start":"2026-04-28T06:54:23.387676Z","end":"2026-04-28T06:54:23.557389Z","steps":["trace[1980827547] 'agreement among raft nodes before linearized reading' (duration: 169.477568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:23.782723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.037622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:23.783045Z","caller":"traceutil/trace.go:171","msg":"trace[2142936933] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"174.808173ms","start":"2026-04-28T06:54:23.6082Z","end":"2026-04-28T06:54:23.783008Z","steps":["trace[2142936933] 'process raft request' (duration: 63.073653ms)","trace[2142936933] 'compare' (duration: 110.872319ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:23.783622Z","caller":"traceutil/trace.go:171","msg":"trace[86518056] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"174.69131ms","start":"2026-04-28T06:54:23.608918Z","end":"2026-04-28T06:54:23.783609Z","steps":["trace[86518056] 'process raft request' (duration: 174.044164ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:24.498097Z","caller":"traceutil/trace.go:171","msg":"trace[675743990] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"164.541844ms","start":"2026-04-28T06:54:24.33352Z","end":"2026-04-28T06:54:24.498062Z","steps":["trace[675743990] 'read index received' (duration: 164.131823ms)","trace[675743990] 'applied index is now lower than readState.Index' (duration: 408.771µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:24.498147Z","caller":"traceutil/trace.go:171","msg":"trace[1363886180] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"169.402136ms","start":"2026-04-28T06:54:24.328726Z","end":"2026-04-28T06:54:24.498128Z","steps":["trace[1363886180] 'process raft request' (duration: 169.130989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:24.498352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.79967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T06:54:24.498379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.77589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:54:24.498475Z","caller":"traceutil/trace.go:171","msg":"trace[1024256416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:252; }","duration":"164.991796ms","start":"2026-04-28T06:54:24.333458Z","end":"2026-04-28T06:54:24.498449Z","steps":["trace[1024256416] 'agreement among raft nodes before linearized reading' (duration: 164.694669ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:24.498514Z","caller":"traceutil/trace.go:171","msg":"trace[428308799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:252; }","duration":"165.001156ms","start":"2026-04-28T06:54:24.333458Z","end":"2026-04-28T06:54:24.498459Z","steps":["trace[428308799] 'agreement among raft nodes before linearized reading' (duration: 164.781041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:24.700127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.254807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:24.700327Z","caller":"traceutil/trace.go:171","msg":"trace[485679597] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"182.785675ms","start":"2026-04-28T06:54:24.517513Z","end":"2026-04-28T06:54:24.700298Z","steps":["trace[485679597] 'process raft request' (duration: 62.287403ms)","trace[485679597] 'compare' (duration: 119.982229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:25.593514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.027426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:25.593632Z","caller":"traceutil/trace.go:171","msg":"trace[650305232] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"191.201478ms","start":"2026-04-28T06:54:25.402409Z","end":"2026-04-28T06:54:25.593611Z","steps":["trace[650305232] 'process raft request' (duration: 26.000347ms)","trace[650305232] 'compare' (duration: 164.894343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:26.644782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216687937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:54:27.145479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216687937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:54:27.646197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216687937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:54:27.706489Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.108438338s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:54:29.052443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345670923s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:29.052535Z","caller":"traceutil/trace.go:171","msg":"trace[1477833224] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"3.454575452s","start":"2026-04-28T06:54:25.597945Z","end":"2026-04-28T06:54:29.05252Z","steps":["trace[1477833224] 'process raft request' (duration: 2.108773657s)","trace[1477833224] 'compare' (duration: 1.345507948s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:29.052576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.597926Z","time spent":"3.454631824s","remote":"127.0.0.1:59900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":290,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:29.750309Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.921039359s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:54:30.704882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.299502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:30.704992Z","caller":"traceutil/trace.go:171","msg":"trace[790983845] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"1.648900818s","start":"2026-04-28T06:54:29.056078Z","end":"2026-04-28T06:54:30.704979Z","steps":["trace[790983845] 'process raft request' (duration: 1.648870597s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:30.705022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:29.056053Z","time spent":"1.648956449s","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":454,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:54:30.70518Z","caller":"traceutil/trace.go:171","msg":"trace[1898247800] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"3.285862444s","start":"2026-04-28T06:54:27.419311Z","end":"2026-04-28T06:54:30.705173Z","steps":["trace[1898247800] 'process raft request' (duration: 2.331229653s)","trace[1898247800] 'compare' (duration: 954.092487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:30.705209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:27.419287Z","time spent":"3.285910166s","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:54:30.70528Z","caller":"traceutil/trace.go:171","msg":"trace[18799519] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:267; }","duration":"4.560538674s","start":"2026-04-28T06:54:26.144707Z","end":"2026-04-28T06:54:30.705246Z","steps":["trace[18799519] 'read index received' (duration: 1.562011454s)","trace[18799519] 'applied index is now lower than readState.Index' (duration: 2.99852313s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:30.705479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.560753779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:30.705525Z","caller":"traceutil/trace.go:171","msg":"trace[541931772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:264; }","duration":"4.560840491s","start":"2026-04-28T06:54:26.144669Z","end":"2026-04-28T06:54:30.705509Z","steps":["trace[541931772] 'agreement among raft nodes before linearized reading' (duration: 4.560704178s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:30.705556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:26.14465Z","time spent":"4.560898212s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T06:54:31.055554Z","caller":"traceutil/trace.go:171","msg":"trace[777811150] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"557.043283ms","start":"2026-04-28T06:54:30.498487Z","end":"2026-04-28T06:54:31.055531Z","steps":["trace[777811150] 'process raft request' (duration: 556.888089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.055885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:30.498463Z","time spent":"557.165476ms","remote":"127.0.0.1:59726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3983,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:54:31.057393Z","caller":"traceutil/trace.go:171","msg":"trace[460624998] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:270; }","duration":"352.025808ms","start":"2026-04-28T06:54:30.705345Z","end":"2026-04-28T06:54:31.057371Z","steps":["trace[460624998] 'read index received' (duration: 350.086509ms)","trace[460624998] 'applied index is now lower than readState.Index' (duration: 1.937899ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:31.057457Z","caller":"traceutil/trace.go:171","msg":"trace[253355780] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"415.321815ms","start":"2026-04-28T06:54:30.642102Z","end":"2026-04-28T06:54:31.057424Z","steps":["trace[253355780] 'process raft request' (duration: 415.069848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.057575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.540598071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:54:31.057631Z","caller":"traceutil/trace.go:171","msg":"trace[795345378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:268; }","duration":"4.540703534s","start":"2026-04-28T06:54:26.516915Z","end":"2026-04-28T06:54:31.057618Z","steps":["trace[795345378] 'agreement among raft nodes before linearized reading' (duration: 4.54055266s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.057685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:26.516889Z","time spent":"4.540782935s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:54:31.057806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.338351615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-04-28T06:54:31.057838Z","caller":"traceutil/trace.go:171","msg":"trace[732980833] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"348.152369ms","start":"2026-04-28T06:54:30.709678Z","end":"2026-04-28T06:54:31.05783Z","steps":["trace[732980833] 'process raft request' (duration: 347.647097ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:31.057877Z","caller":"traceutil/trace.go:171","msg":"trace[1927550294] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:268; }","duration":"4.33856118s","start":"2026-04-28T06:54:26.719296Z","end":"2026-04-28T06:54:31.057857Z","steps":["trace[1927550294] 'agreement among raft nodes before linearized reading' (duration: 4.338190632s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.057906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:30.709671Z","time spent":"348.1965ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:31.05761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.423907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:54:31.057934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:26.719277Z","time spent":"4.338641983s","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2942,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-28T06:54:31.05796Z","caller":"traceutil/trace.go:171","msg":"trace[616325485] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:268; }","duration":"815.820486ms","start":"2026-04-28T06:54:30.242122Z","end":"2026-04-28T06:54:31.057943Z","steps":["trace[616325485] 'agreement among raft nodes before linearized reading' (duration: 815.343774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.057964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.512434861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T06:54:31.058009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:30.242084Z","time spent":"815.911659ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-28T06:54:31.05801Z","caller":"traceutil/trace.go:171","msg":"trace[2002739527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:268; }","duration":"4.512514983s","start":"2026-04-28T06:54:26.54548Z","end":"2026-04-28T06:54:31.057995Z","steps":["trace[2002739527] 'agreement among raft nodes before linearized reading' (duration: 4.512425241s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.05805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:26.545462Z","time spent":"4.512578464s","remote":"127.0.0.1:59820","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-04-28T06:54:31.05769Z","caller":"traceutil/trace.go:171","msg":"trace[1043585791] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"348.109558ms","start":"2026-04-28T06:54:30.709558Z","end":"2026-04-28T06:54:31.057667Z","steps":["trace[1043585791] 'process raft request' (duration: 347.727369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.05817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:30.709539Z","time spent":"348.607861ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:54:31.058134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.270400919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:31.058259Z","caller":"traceutil/trace.go:171","msg":"trace[1187745414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:268; }","duration":"2.270591253s","start":"2026-04-28T06:54:28.787647Z","end":"2026-04-28T06:54:31.058238Z","steps":["trace[1187745414] 'agreement among raft nodes before linearized reading' (duration: 2.270256145s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:31.058639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:28.787628Z","time spent":"2.270998794s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:54:31.059157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:30.642074Z","time spent":"415.476798ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:20.678988Z","caller":"traceutil/trace.go:171","msg":"trace[596095517] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"140.901772ms","start":"2026-04-28T06:55:20.538028Z","end":"2026-04-28T06:55:20.678929Z","steps":["trace[596095517] 'process raft request' (duration: 140.360748ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.679173Z","caller":"traceutil/trace.go:171","msg":"trace[815718157] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"127.046215ms","start":"2026-04-28T06:55:20.552079Z","end":"2026-04-28T06:55:20.679126Z","steps":["trace[815718157] 'process raft request' (duration: 126.723657ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.679213Z","caller":"traceutil/trace.go:171","msg":"trace[1025495297] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"128.444059ms","start":"2026-04-28T06:55:20.550664Z","end":"2026-04-28T06:55:20.679108Z","steps":["trace[1025495297] 'process raft request' (duration: 128.07128ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.679384Z","caller":"traceutil/trace.go:171","msg":"trace[1595805499] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"131.198179ms","start":"2026-04-28T06:55:20.548154Z","end":"2026-04-28T06:55:20.679352Z","steps":["trace[1595805499] 'process raft request' (duration: 130.493691ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.679553Z","caller":"traceutil/trace.go:171","msg":"trace[1247803819] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"126.696156ms","start":"2026-04-28T06:55:20.55284Z","end":"2026-04-28T06:55:20.679536Z","steps":["trace[1247803819] 'process raft request' (duration: 126.02375ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.678928Z","caller":"traceutil/trace.go:171","msg":"trace[1414163138] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"141.534528ms","start":"2026-04-28T06:55:20.537344Z","end":"2026-04-28T06:55:20.678878Z","steps":["trace[1414163138] 'process raft request' (duration: 140.740868ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.679807Z","caller":"traceutil/trace.go:171","msg":"trace[148174961] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"108.248243ms","start":"2026-04-28T06:55:20.571549Z","end":"2026-04-28T06:55:20.679797Z","steps":["trace[148174961] 'process raft request' (duration: 107.364301ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.680006Z","caller":"traceutil/trace.go:171","msg":"trace[1315592614] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"133.207179ms","start":"2026-04-28T06:55:20.546714Z","end":"2026-04-28T06:55:20.679921Z","steps":["trace[1315592614] 'process raft request' (duration: 131.859356ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.744366Z","caller":"traceutil/trace.go:171","msg":"trace[1025396668] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"114.591603ms","start":"2026-04-28T06:55:20.629732Z","end":"2026-04-28T06:55:20.744323Z","steps":["trace[1025396668] 'process raft request' (duration: 114.344216ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:20.7448Z","caller":"traceutil/trace.go:171","msg":"trace[1254741063] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"100.108699ms","start":"2026-04-28T06:55:20.644648Z","end":"2026-04-28T06:55:20.744756Z","steps":["trace[1254741063] 'process raft request' (duration: 99.593106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:21.201761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.568302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:21.202116Z","caller":"traceutil/trace.go:171","msg":"trace[738796088] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"143.016085ms","start":"2026-04-28T06:55:21.059077Z","end":"2026-04-28T06:55:21.202093Z","steps":["trace[738796088] 'process raft request' (duration: 142.955703ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:21.202471Z","caller":"traceutil/trace.go:171","msg":"trace[1969935751] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"200.818615ms","start":"2026-04-28T06:55:21.001607Z","end":"2026-04-28T06:55:21.202426Z","steps":["trace[1969935751] 'process raft request' (duration: 97.466213ms)","trace[1969935751] 'compare' (duration: 102.354267ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:21.20253Z","caller":"traceutil/trace.go:171","msg":"trace[1244054607] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"198.861976ms","start":"2026-04-28T06:55:21.003613Z","end":"2026-04-28T06:55:21.202475Z","steps":["trace[1244054607] 'process raft request' (duration: 198.308692ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:21.974117Z","caller":"traceutil/trace.go:171","msg":"trace[68840201] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"254.447088ms","start":"2026-04-28T06:55:21.71964Z","end":"2026-04-28T06:55:21.974087Z","steps":["trace[68840201] 'process raft request' (duration: 250.380346ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:21.993497Z","caller":"traceutil/trace.go:171","msg":"trace[325034373] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"245.555555ms","start":"2026-04-28T06:55:21.747893Z","end":"2026-04-28T06:55:21.993449Z","steps":["trace[325034373] 'process raft request' (duration: 241.030142ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:21.993545Z","caller":"traceutil/trace.go:171","msg":"trace[1054305746] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"235.156005ms","start":"2026-04-28T06:55:21.758366Z","end":"2026-04-28T06:55:21.993522Z","steps":["trace[1054305746] 'process raft request' (duration: 234.988021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:22.389783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.300045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:22.39004Z","caller":"traceutil/trace.go:171","msg":"trace[545835245] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"386.464938ms","start":"2026-04-28T06:55:22.003544Z","end":"2026-04-28T06:55:22.390009Z","steps":["trace[545835245] 'process raft request' (duration: 74.847047ms)","trace[545835245] 'compare' (duration: 310.879213ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:22.390113Z","caller":"traceutil/trace.go:171","msg":"trace[972261994] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"378.57921ms","start":"2026-04-28T06:55:22.011426Z","end":"2026-04-28T06:55:22.390005Z","steps":["trace[972261994] 'process raft request' (duration: 378.461277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:22.390365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:22.003521Z","time spent":"386.658713ms","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:22.390699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:22.011406Z","time spent":"379.195666ms","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:22.401218Z","caller":"traceutil/trace.go:171","msg":"trace[1771096214] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:668; }","duration":"291.941159ms","start":"2026-04-28T06:55:22.109248Z","end":"2026-04-28T06:55:22.401189Z","steps":["trace[1771096214] 'read index received' (duration: 193.545702ms)","trace[1771096214] 'applied index is now lower than readState.Index' (duration: 98.394267ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:22.401572Z","caller":"traceutil/trace.go:171","msg":"trace[594255477] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"322.652048ms","start":"2026-04-28T06:55:22.078906Z","end":"2026-04-28T06:55:22.401559Z","steps":["trace[594255477] 'process raft request' (duration: 322.017812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:22.401635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.362209ms","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-04-28T06:55:22.401691Z","caller":"traceutil/trace.go:171","msg":"trace[1865471495] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:641; }","duration":"292.479422ms","start":"2026-04-28T06:55:22.109196Z","end":"2026-04-28T06:55:22.401675Z","steps":["trace[1865471495] 'agreement among raft nodes before linearized reading' (duration: 292.340919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:22.401776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:22.078888Z","time spent":"322.776802ms","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:55:22.401792Z","caller":"traceutil/trace.go:171","msg":"trace[1468779671] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"320.943206ms","start":"2026-04-28T06:55:22.080609Z","end":"2026-04-28T06:55:22.401552Z","steps":["trace[1468779671] 'process raft request' (duration: 320.440913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:22.402047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:22.080588Z","time spent":"321.370006ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:22.415384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.054637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:22.415591Z","caller":"traceutil/trace.go:171","msg":"trace[256474661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"305.238752ms","start":"2026-04-28T06:55:22.110258Z","end":"2026-04-28T06:55:22.415497Z","steps":["trace[256474661] 'agreement among raft nodes before linearized reading' (duration: 305.054727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:22.415763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:22.11024Z","time spent":"305.487868ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:22.761767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.984873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:22.76206Z","caller":"traceutil/trace.go:171","msg":"trace[1694341555] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"307.108549ms","start":"2026-04-28T06:55:22.454925Z","end":"2026-04-28T06:55:22.762033Z","steps":["trace[1694341555] 'process raft request' (duration: 181.786058ms)","trace[1694341555] 'compare' (duration: 124.836739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:22.762166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:22.454902Z","time spent":"307.214502ms","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:22.762212Z","caller":"traceutil/trace.go:171","msg":"trace[265054892] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"191.0808ms","start":"2026-04-28T06:55:22.571123Z","end":"2026-04-28T06:55:22.762204Z","steps":["trace[265054892] 'process raft request' (duration: 190.969647ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:22.762225Z","caller":"traceutil/trace.go:171","msg":"trace[2022103208] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"274.997994ms","start":"2026-04-28T06:55:22.487171Z","end":"2026-04-28T06:55:22.762169Z","steps":["trace[2022103208] 'process raft request' (duration: 274.703136ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:23.043904Z","caller":"traceutil/trace.go:171","msg":"trace[365617080] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"119.605399ms","start":"2026-04-28T06:55:22.924266Z","end":"2026-04-28T06:55:23.043871Z","steps":["trace[365617080] 'process raft request' (duration: 118.744447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:23.591762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216688745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:55:24.092063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216688745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:55:24.381737Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29128307s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:55:24.427722Z","caller":"traceutil/trace.go:171","msg":"trace[203712369] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.337343325s","start":"2026-04-28T06:55:23.090346Z","end":"2026-04-28T06:55:24.42769Z","steps":["trace[203712369] 'process raft request' (duration: 1.291726721s)","trace[203712369] 'compare' (duration: 45.435369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:24.427893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.090328Z","time spent":"1.337475618s","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:24.445621Z","caller":"traceutil/trace.go:171","msg":"trace[1719538674] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"1.354825413s","start":"2026-04-28T06:55:23.090774Z","end":"2026-04-28T06:55:24.445599Z","steps":["trace[1719538674] 'read index received' (duration: 1.291410764s)","trace[1719538674] 'applied index is now lower than readState.Index' (duration: 63.413539ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:24.446084Z","caller":"traceutil/trace.go:171","msg":"trace[1576369877] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.196820391s","start":"2026-04-28T06:55:23.249226Z","end":"2026-04-28T06:55:24.446046Z","steps":["trace[1576369877] 'process raft request' (duration: 1.194932954s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.446243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.249205Z","time spent":"1.196960805s","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:24.446321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335879379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:24.44639Z","caller":"traceutil/trace.go:171","msg":"trace[1448190653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"1.335956381s","start":"2026-04-28T06:55:23.110401Z","end":"2026-04-28T06:55:24.446357Z","steps":["trace[1448190653] 'agreement among raft nodes before linearized reading' (duration: 1.335866068s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.446429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.110386Z","time spent":"1.336034423s","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:24.446666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072759742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:55:24.44675Z","caller":"traceutil/trace.go:171","msg":"trace[488689921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"1.072854975s","start":"2026-04-28T06:55:23.373876Z","end":"2026-04-28T06:55:24.446731Z","steps":["trace[488689921] 'agreement among raft nodes before linearized reading' (duration: 1.072728552s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.446775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098665621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T06:55:24.446826Z","caller":"traceutil/trace.go:171","msg":"trace[1400776745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"1.098755003s","start":"2026-04-28T06:55:23.348057Z","end":"2026-04-28T06:55:24.446812Z","steps":["trace[1400776745] 'agreement among raft nodes before linearized reading' (duration: 1.098573009s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.446813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186549525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-28T06:55:24.44686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.348039Z","time spent":"1.098812615s","remote":"127.0.0.1:59820","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-04-28T06:55:24.446871Z","caller":"traceutil/trace.go:171","msg":"trace[1352876993] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:654; }","duration":"1.186626737s","start":"2026-04-28T06:55:23.26023Z","end":"2026-04-28T06:55:24.446857Z","steps":["trace[1352876993] 'agreement among raft nodes before linearized reading' (duration: 1.186499544s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.446897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.260215Z","time spent":"1.186675248s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-04-28T06:55:24.446933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046707349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:55:24.446974Z","caller":"traceutil/trace.go:171","msg":"trace[362507350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"1.046768091s","start":"2026-04-28T06:55:23.400189Z","end":"2026-04-28T06:55:24.446958Z","steps":["trace[362507350] 'agreement among raft nodes before linearized reading' (duration: 1.046613797s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.447001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.400157Z","time spent":"1.046838152s","remote":"127.0.0.1:59820","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-04-28T06:55:24.446784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.373864Z","time spent":"1.072911696s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:24.447465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07343865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:55:24.447617Z","caller":"traceutil/trace.go:171","msg":"trace[1142888915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"1.073605804s","start":"2026-04-28T06:55:23.373931Z","end":"2026-04-28T06:55:24.447536Z","steps":["trace[1142888915] 'agreement among raft nodes before linearized reading' (duration: 1.073120362s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.44767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.37391Z","time spent":"1.073746336s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:24.449176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358392923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:29476"} {"level":"info","ts":"2026-04-28T06:55:24.449234Z","caller":"traceutil/trace.go:171","msg":"trace[1513730994] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:654; }","duration":"1.358461665s","start":"2026-04-28T06:55:23.09075Z","end":"2026-04-28T06:55:24.449212Z","steps":["trace[1513730994] 'agreement among raft nodes before linearized reading' (duration: 1.354965668s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.449261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.090742Z","time spent":"1.358511616s","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":29500,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-04-28T06:55:26.15524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.949505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:26.155597Z","caller":"traceutil/trace.go:171","msg":"trace[610500522] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.698434597s","start":"2026-04-28T06:55:24.457106Z","end":"2026-04-28T06:55:26.15554Z","steps":["trace[610500522] 'process raft request' (duration: 1.698281434s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.155758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.457095Z","time spent":"1.69857581s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:26.155806Z","caller":"traceutil/trace.go:171","msg":"trace[695750347] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:686; }","duration":"1.697032902s","start":"2026-04-28T06:55:24.458752Z","end":"2026-04-28T06:55:26.155785Z","steps":["trace[695750347] 'read index received' (duration: 12.745399ms)","trace[695750347] 'applied index is now lower than readState.Index' (duration: 1.684284623s)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:26.155786Z","caller":"traceutil/trace.go:171","msg":"trace[963210107] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.69855794s","start":"2026-04-28T06:55:24.457183Z","end":"2026-04-28T06:55:26.155741Z","steps":["trace[963210107] 'process raft request' (duration: 1.698250613s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:26.155861Z","caller":"traceutil/trace.go:171","msg":"trace[1502438553] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.698708705s","start":"2026-04-28T06:55:24.456812Z","end":"2026-04-28T06:55:26.155521Z","steps":["trace[1502438553] 'process raft request' (duration: 1.69852052s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:26.155882Z","caller":"traceutil/trace.go:171","msg":"trace[1546963801] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.648398053s","start":"2026-04-28T06:55:24.507462Z","end":"2026-04-28T06:55:26.15586Z","steps":["trace[1546963801] 'process raft request' (duration: 1.648334751s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.155971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.507446Z","time spent":"1.648482934s","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:26.156004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697245578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"warn","ts":"2026-04-28T06:55:26.155998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.456786Z","time spent":"1.699131045s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:26.155998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.457176Z","time spent":"1.698702473s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:26.156028Z","caller":"traceutil/trace.go:171","msg":"trace[1504998462] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:661; }","duration":"1.697530045s","start":"2026-04-28T06:55:24.45849Z","end":"2026-04-28T06:55:26.15602Z","steps":["trace[1504998462] 'agreement among raft nodes before linearized reading' (duration: 1.69739469s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.156047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.458483Z","time spent":"1.697559146s","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9173,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-04-28T06:55:26.156064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69417067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:26.156101Z","caller":"traceutil/trace.go:171","msg":"trace[44942972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"1.694215111s","start":"2026-04-28T06:55:24.461875Z","end":"2026-04-28T06:55:26.15609Z","steps":["trace[44942972] 'agreement among raft nodes before linearized reading' (duration: 1.6941531s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.156129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.46187Z","time spent":"1.694253452s","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:26.156149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314122513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:26.156172Z","caller":"traceutil/trace.go:171","msg":"trace[2108209872] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:661; }","duration":"1.314180575s","start":"2026-04-28T06:55:24.841985Z","end":"2026-04-28T06:55:26.156166Z","steps":["trace[2108209872] 'agreement among raft nodes before linearized reading' (duration: 1.314138364s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.156192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.841968Z","time spent":"1.314218796s","remote":"127.0.0.1:59638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:55:26.156195Z","caller":"traceutil/trace.go:171","msg":"trace[1263573402] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.68337328s","start":"2026-04-28T06:55:24.47281Z","end":"2026-04-28T06:55:26.156183Z","steps":["trace[1263573402] 'process raft request' (duration: 1.682786746s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:26.156222Z","caller":"traceutil/trace.go:171","msg":"trace[1398383350] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.699505495s","start":"2026-04-28T06:55:24.456692Z","end":"2026-04-28T06:55:26.156198Z","steps":["trace[1398383350] 'process raft request' (duration: 805.465072ms)","trace[1398383350] 'compare' (duration: 892.487214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:26.156262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652790543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:26.156292Z","caller":"traceutil/trace.go:171","msg":"trace[306707970] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:661; }","duration":"1.652820894s","start":"2026-04-28T06:55:24.503462Z","end":"2026-04-28T06:55:26.156282Z","steps":["trace[306707970] 'agreement among raft nodes before linearized reading' (duration: 1.652772543s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.156304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.456672Z","time spent":"1.699588866s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:26.156319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.503448Z","time spent":"1.652864415s","remote":"127.0.0.1:59840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:55:26.156341Z","caller":"traceutil/trace.go:171","msg":"trace[1573384939] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.68415214s","start":"2026-04-28T06:55:24.472181Z","end":"2026-04-28T06:55:26.156333Z","steps":["trace[1573384939] 'process raft request' (duration: 1.683320119s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.156379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.472169Z","time spent":"1.684186531s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:26.156252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.472804Z","time spent":"1.683416821s","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:29.215941Z","caller":"traceutil/trace.go:171","msg":"trace[1113882705] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"105.215727ms","start":"2026-04-28T06:55:29.110703Z","end":"2026-04-28T06:55:29.215919Z","steps":["trace[1113882705] 'read index received' (duration: 104.440438ms)","trace[1113882705] 'applied index is now lower than readState.Index' (duration: 774.379µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:29.215997Z","caller":"traceutil/trace.go:171","msg":"trace[72258053] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"285.069656ms","start":"2026-04-28T06:55:28.930894Z","end":"2026-04-28T06:55:29.215963Z","steps":["trace[72258053] 'process raft request' (duration: 284.473721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:29.216083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.367731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:29.216125Z","caller":"traceutil/trace.go:171","msg":"trace[224996497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"105.460994ms","start":"2026-04-28T06:55:29.110649Z","end":"2026-04-28T06:55:29.21611Z","steps":["trace[224996497] 'agreement among raft nodes before linearized reading' (duration: 105.367601ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:31.325833Z","caller":"traceutil/trace.go:171","msg":"trace[1219226131] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"103.626108ms","start":"2026-04-28T06:55:31.222176Z","end":"2026-04-28T06:55:31.325802Z","steps":["trace[1219226131] 'process raft request' (duration: 102.91659ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:33.373726Z","caller":"traceutil/trace.go:171","msg":"trace[1852289932] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"113.530066ms","start":"2026-04-28T06:55:33.260178Z","end":"2026-04-28T06:55:33.373709Z","steps":["trace[1852289932] 'read index received' (duration: 89.145844ms)","trace[1852289932] 'applied index is now lower than readState.Index' (duration: 24.383602ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:33.373842Z","caller":"traceutil/trace.go:171","msg":"trace[1814106632] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"241.274258ms","start":"2026-04-28T06:55:33.132536Z","end":"2026-04-28T06:55:33.37381Z","steps":["trace[1814106632] 'process raft request' (duration: 216.882686ms)","trace[1814106632] 'compare' (duration: 24.208188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:33.373958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.762382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T06:55:33.374055Z","caller":"traceutil/trace.go:171","msg":"trace[1450896513] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:703; }","duration":"113.868105ms","start":"2026-04-28T06:55:33.260135Z","end":"2026-04-28T06:55:33.374003Z","steps":["trace[1450896513] 'agreement among raft nodes before linearized reading' (duration: 113.665609ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:34.89405Z","caller":"traceutil/trace.go:171","msg":"trace[1220548360] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"649.280207ms","start":"2026-04-28T06:55:34.244745Z","end":"2026-04-28T06:55:34.894025Z","steps":["trace[1220548360] 'process raft request' (duration: 649.146904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:34.894234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.244728Z","time spent":"649.428182ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:34.906822Z","caller":"traceutil/trace.go:171","msg":"trace[1779098501] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"661.389851ms","start":"2026-04-28T06:55:34.245339Z","end":"2026-04-28T06:55:34.906729Z","steps":["trace[1779098501] 'process raft request' (duration: 661.231757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:34.906993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.245326Z","time spent":"661.573966ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:34.90724Z","caller":"traceutil/trace.go:171","msg":"trace[1007409319] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"661.682959ms","start":"2026-04-28T06:55:34.245533Z","end":"2026-04-28T06:55:34.907216Z","steps":["trace[1007409319] 'process raft request' (duration: 661.146636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:34.907372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.245516Z","time spent":"661.782121ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:34.90765Z","caller":"traceutil/trace.go:171","msg":"trace[165935291] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"661.442492ms","start":"2026-04-28T06:55:34.24618Z","end":"2026-04-28T06:55:34.907622Z","steps":["trace[165935291] 'process raft request' (duration: 660.934909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:34.907823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.246165Z","time spent":"661.565555ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:35.443877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.484495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:35.443967Z","caller":"traceutil/trace.go:171","msg":"trace[826532770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"332.635959ms","start":"2026-04-28T06:55:35.111312Z","end":"2026-04-28T06:55:35.443948Z","steps":["trace[826532770] 'range keys from in-memory index tree' (duration: 332.368713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.444003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:35.111256Z","time spent":"332.738241ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:35.444249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.389165ms","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-04-28T06:55:35.444273Z","caller":"traceutil/trace.go:171","msg":"trace[1370715452] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:708; }","duration":"230.465027ms","start":"2026-04-28T06:55:35.213797Z","end":"2026-04-28T06:55:35.444262Z","steps":["trace[1370715452] 'count revisions from in-memory index tree' (duration: 230.292233ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:36.973705Z","caller":"traceutil/trace.go:171","msg":"trace[1505917193] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"215.80171ms","start":"2026-04-28T06:55:36.757842Z","end":"2026-04-28T06:55:36.973644Z","steps":["trace[1505917193] 'process raft request' (duration: 173.796007ms)","trace[1505917193] 'compare' (duration: 41.732947ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:37.342375Z","caller":"traceutil/trace.go:171","msg":"trace[1701437952] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"363.447451ms","start":"2026-04-28T06:55:36.97888Z","end":"2026-04-28T06:55:37.342327Z","steps":["trace[1701437952] 'process raft request' (duration: 313.763695ms)","trace[1701437952] 'compare' (duration: 49.44307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:37.342563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.978856Z","time spent":"363.612315ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:37.395429Z","caller":"traceutil/trace.go:171","msg":"trace[481541456] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"415.722222ms","start":"2026-04-28T06:55:36.97968Z","end":"2026-04-28T06:55:37.395402Z","steps":["trace[481541456] 'process raft request' (duration: 415.64584ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:37.395583Z","caller":"traceutil/trace.go:171","msg":"trace[1990222649] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:745; }","duration":"284.727148ms","start":"2026-04-28T06:55:37.110844Z","end":"2026-04-28T06:55:37.395571Z","steps":["trace[1990222649] 'read index received' (duration: 181.769357ms)","trace[1990222649] 'applied index is now lower than readState.Index' (duration: 102.956601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:37.395591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.979667Z","time spent":"415.831885ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:37.395634Z","caller":"traceutil/trace.go:171","msg":"trace[538057334] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"414.754467ms","start":"2026-04-28T06:55:36.980871Z","end":"2026-04-28T06:55:37.395625Z","steps":["trace[538057334] 'process raft request' (duration: 414.4865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:37.395677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.980864Z","time spent":"414.789567ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:37.395429Z","caller":"traceutil/trace.go:171","msg":"trace[1204431068] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"416.181214ms","start":"2026-04-28T06:55:36.979231Z","end":"2026-04-28T06:55:37.395412Z","steps":["trace[1204431068] 'process raft request' (duration: 415.985718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:37.395734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.871051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:55:37.395771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.979219Z","time spent":"416.530232ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:37.395803Z","caller":"traceutil/trace.go:171","msg":"trace[2097503268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"284.986754ms","start":"2026-04-28T06:55:37.110799Z","end":"2026-04-28T06:55:37.395786Z","steps":["trace[2097503268] 'agreement among raft nodes before linearized reading' (duration: 284.84288ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:37.99015Z","caller":"traceutil/trace.go:171","msg":"trace[1033746673] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"320.293109ms","start":"2026-04-28T06:55:37.669787Z","end":"2026-04-28T06:55:37.99008Z","steps":["trace[1033746673] 'process raft request' (duration: 319.856268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:37.990336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:37.66977Z","time spent":"320.486833ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:55:37.990501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.007095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:37.99061Z","caller":"traceutil/trace.go:171","msg":"trace[103703439] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:715; }","duration":"135.145108ms","start":"2026-04-28T06:55:37.855424Z","end":"2026-04-28T06:55:37.990569Z","steps":["trace[103703439] 'agreement among raft nodes before linearized reading' (duration: 134.781808ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:37.990195Z","caller":"traceutil/trace.go:171","msg":"trace[900631180] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"134.699567ms","start":"2026-04-28T06:55:37.855455Z","end":"2026-04-28T06:55:37.990154Z","steps":["trace[900631180] 'read index received' (duration: 134.224425ms)","trace[900631180] 'applied index is now lower than readState.Index' (duration: 354.949µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:39.1503Z","caller":"traceutil/trace.go:171","msg":"trace[1922045908] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"139.972289ms","start":"2026-04-28T06:55:39.010302Z","end":"2026-04-28T06:55:39.150274Z","steps":["trace[1922045908] 'process raft request' (duration: 139.844026ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:39.350244Z","caller":"traceutil/trace.go:171","msg":"trace[851820368] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:751; }","duration":"237.633127ms","start":"2026-04-28T06:55:39.112593Z","end":"2026-04-28T06:55:39.350226Z","steps":["trace[851820368] 'read index received' (duration: 37.621673ms)","trace[851820368] 'applied index is now lower than readState.Index' (duration: 200.010384ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:39.350311Z","caller":"traceutil/trace.go:171","msg":"trace[533472995] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"326.868124ms","start":"2026-04-28T06:55:39.023408Z","end":"2026-04-28T06:55:39.350276Z","steps":["trace[533472995] 'process raft request' (duration: 326.66484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:39.350384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.77985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:39.35042Z","caller":"traceutil/trace.go:171","msg":"trace[900129439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"237.850942ms","start":"2026-04-28T06:55:39.112557Z","end":"2026-04-28T06:55:39.350408Z","steps":["trace[900129439] 'agreement among raft nodes before linearized reading' (duration: 237.784091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:39.350494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:39.02337Z","time spent":"327.023498ms","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17505,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:39.709519Z","caller":"traceutil/trace.go:171","msg":"trace[1166632135] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"305.246192ms","start":"2026-04-28T06:55:39.404251Z","end":"2026-04-28T06:55:39.709497Z","steps":["trace[1166632135] 'process raft request' (duration: 305.087789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:39.709758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:39.40422Z","time spent":"305.446986ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:39.758861Z","caller":"traceutil/trace.go:171","msg":"trace[1385583364] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"354.483376ms","start":"2026-04-28T06:55:39.404348Z","end":"2026-04-28T06:55:39.758832Z","steps":["trace[1385583364] 'process raft request' (duration: 354.203509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:39.759407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:39.404323Z","time spent":"354.63454ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:39.759635Z","caller":"traceutil/trace.go:171","msg":"trace[1907134811] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"354.933648ms","start":"2026-04-28T06:55:39.404672Z","end":"2026-04-28T06:55:39.759606Z","steps":["trace[1907134811] 'process raft request' (duration: 354.107456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:39.759864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:39.404656Z","time spent":"355.080281ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:39.92119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.663482ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T06:55:44.306593Z","caller":"traceutil/trace.go:171","msg":"trace[1088687943] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"138.763088ms","start":"2026-04-28T06:55:44.167805Z","end":"2026-04-28T06:55:44.306568Z","steps":["trace[1088687943] 'process raft request' (duration: 136.983344ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:45.555649Z","caller":"traceutil/trace.go:171","msg":"trace[493595627] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"190.881645ms","start":"2026-04-28T06:55:45.364745Z","end":"2026-04-28T06:55:45.555626Z","steps":["trace[493595627] 'read index received' (duration: 190.614488ms)","trace[493595627] 'applied index is now lower than readState.Index' (duration: 266.067µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:45.555787Z","caller":"traceutil/trace.go:171","msg":"trace[753126222] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"389.537966ms","start":"2026-04-28T06:55:45.166224Z","end":"2026-04-28T06:55:45.555761Z","steps":["trace[753126222] 'process raft request' (duration: 389.259899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:45.556231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.444228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:45.556368Z","caller":"traceutil/trace.go:171","msg":"trace[382851571] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:780; }","duration":"191.630364ms","start":"2026-04-28T06:55:45.364716Z","end":"2026-04-28T06:55:45.556347Z","steps":["trace[382851571] 'agreement among raft nodes before linearized reading' (duration: 191.416788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:45.556247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:45.166204Z","time spent":"389.829303ms","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:55:45.71812Z","caller":"traceutil/trace.go:171","msg":"trace[1991151293] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:817; }","duration":"153.614541ms","start":"2026-04-28T06:55:45.564467Z","end":"2026-04-28T06:55:45.718082Z","steps":["trace[1991151293] 'read index received' (duration: 8.964625ms)","trace[1991151293] 'applied index is now lower than readState.Index' (duration: 144.648356ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:45.718213Z","caller":"traceutil/trace.go:171","msg":"trace[134162184] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"157.690073ms","start":"2026-04-28T06:55:45.560489Z","end":"2026-04-28T06:55:45.71818Z","steps":["trace[134162184] 'process raft request' (duration: 96.254792ms)","trace[134162184] 'compare' (duration: 61.055031ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:45.718363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.871277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:45.718439Z","caller":"traceutil/trace.go:171","msg":"trace[935886666] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:782; }","duration":"153.99001ms","start":"2026-04-28T06:55:45.564434Z","end":"2026-04-28T06:55:45.718424Z","steps":["trace[935886666] 'agreement among raft nodes before linearized reading' (duration: 153.837977ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:53.842906Z","caller":"traceutil/trace.go:171","msg":"trace[91204833] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"242.890039ms","start":"2026-04-28T06:55:53.599993Z","end":"2026-04-28T06:55:53.842883Z","steps":["trace[91204833] 'read index received' (duration: 242.688854ms)","trace[91204833] 'applied index is now lower than readState.Index' (duration: 200.205µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:53.843044Z","caller":"traceutil/trace.go:171","msg":"trace[739774162] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"284.279307ms","start":"2026-04-28T06:55:53.55872Z","end":"2026-04-28T06:55:53.842999Z","steps":["trace[739774162] 'process raft request' (duration: 284.030861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:53.843236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.182386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:55:53.843322Z","caller":"traceutil/trace.go:171","msg":"trace[1817177894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:851; }","duration":"243.388222ms","start":"2026-04-28T06:55:53.599915Z","end":"2026-04-28T06:55:53.843304Z","steps":["trace[1817177894] 'agreement among raft nodes before linearized reading' (duration: 243.083014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:56.603485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216689291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:55:57.104435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216689291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:55:57.50746Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.49629202s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:55:57.509689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497445429s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:57.509828Z","caller":"traceutil/trace.go:171","msg":"trace[959753466] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:907; }","duration":"1.407135405s","start":"2026-04-28T06:55:56.102678Z","end":"2026-04-28T06:55:57.509813Z","steps":["trace[959753466] 'read index received' (duration: 1.405067653s)","trace[959753466] 'applied index is now lower than readState.Index' (duration: 2.066852ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:57.509908Z","caller":"traceutil/trace.go:171","msg":"trace[283914447] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"1.574986413s","start":"2026-04-28T06:55:55.934914Z","end":"2026-04-28T06:55:57.5099Z","steps":["trace[283914447] 'process raft request' (duration: 76.337274ms)","trace[283914447] 'compare' (duration: 1.497189292s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:57.509943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:55.934904Z","time spent":"1.575021964s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:57.510143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395097394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:57.510163Z","caller":"traceutil/trace.go:171","msg":"trace[2927728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"1.395137724s","start":"2026-04-28T06:55:56.115019Z","end":"2026-04-28T06:55:57.510157Z","steps":["trace[2927728] 'agreement among raft nodes before linearized reading' (duration: 1.395047202s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:57.510178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:56.115008Z","time spent":"1.395166476s","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:57.510207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407532145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6hxbr\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-28T06:55:57.510267Z","caller":"traceutil/trace.go:171","msg":"trace[855552919] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6hxbr; range_end:; response_count:1; response_revision:869; }","duration":"1.407600426s","start":"2026-04-28T06:55:56.102652Z","end":"2026-04-28T06:55:57.510253Z","steps":["trace[855552919] 'agreement among raft nodes before linearized reading' (duration: 1.407393831s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:57.5103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:56.10258Z","time spent":"1.407713638s","remote":"127.0.0.1:59728","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-6hxbr\" "} {"level":"info","ts":"2026-04-28T06:55:57.510316Z","caller":"traceutil/trace.go:171","msg":"trace[1460928457] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"1.573145827s","start":"2026-04-28T06:55:55.937164Z","end":"2026-04-28T06:55:57.51031Z","steps":["trace[1460928457] 'process raft request' (duration: 1.572602724s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:57.510346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:55.937148Z","time spent":"1.573181817s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:04.598101Z","caller":"traceutil/trace.go:171","msg":"trace[654343257] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"113.219488ms","start":"2026-04-28T06:56:04.484863Z","end":"2026-04-28T06:56:04.598083Z","steps":["trace[654343257] 'process raft request' (duration: 113.100915ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:04.761837Z","caller":"traceutil/trace.go:171","msg":"trace[927169773] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"154.987764ms","start":"2026-04-28T06:56:04.606831Z","end":"2026-04-28T06:56:04.761819Z","steps":["trace[927169773] 'process raft request' (duration: 154.856511ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:04.761974Z","caller":"traceutil/trace.go:171","msg":"trace[378469883] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"153.865118ms","start":"2026-04-28T06:56:04.608088Z","end":"2026-04-28T06:56:04.761953Z","steps":["trace[378469883] 'process raft request' (duration: 153.696363ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:04.802182Z","caller":"traceutil/trace.go:171","msg":"trace[12027949] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"106.016987ms","start":"2026-04-28T06:56:04.69614Z","end":"2026-04-28T06:56:04.802157Z","steps":["trace[12027949] 'process raft request' (duration: 105.902784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:05.074399Z","caller":"traceutil/trace.go:171","msg":"trace[597734890] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"240.431057ms","start":"2026-04-28T06:56:04.833944Z","end":"2026-04-28T06:56:05.074375Z","steps":["trace[597734890] 'process raft request' (duration: 239.975345ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:17.824886Z","caller":"traceutil/trace.go:171","msg":"trace[1914341124] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"152.993462ms","start":"2026-04-28T06:56:17.671842Z","end":"2026-04-28T06:56:17.824835Z","steps":["trace[1914341124] 'process raft request' (duration: 114.858472ms)","trace[1914341124] 'compare' (duration: 37.996047ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:18.141431Z","caller":"traceutil/trace.go:171","msg":"trace[1416766761] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"111.358904ms","start":"2026-04-28T06:56:18.030034Z","end":"2026-04-28T06:56:18.141393Z","steps":["trace[1416766761] 'process raft request' (duration: 111.17324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:24.226326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.50792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:24.22664Z","caller":"traceutil/trace.go:171","msg":"trace[157432281] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"161.399364ms","start":"2026-04-28T06:56:24.065207Z","end":"2026-04-28T06:56:24.226607Z","steps":["trace[157432281] 'process raft request' (duration: 43.533716ms)","trace[157432281] 'compare' (duration: 117.408937ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:24.226714Z","caller":"traceutil/trace.go:171","msg":"trace[1023228876] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"160.887122ms","start":"2026-04-28T06:56:24.065796Z","end":"2026-04-28T06:56:24.226683Z","steps":["trace[1023228876] 'process raft request' (duration: 160.623175ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:27.960516Z","caller":"traceutil/trace.go:171","msg":"trace[998375203] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"196.054538ms","start":"2026-04-28T06:56:27.764443Z","end":"2026-04-28T06:56:27.960497Z","steps":["trace[998375203] 'process raft request' (duration: 195.886144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:28.241501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.175473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:28.241558Z","caller":"traceutil/trace.go:171","msg":"trace[693194288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"131.267946ms","start":"2026-04-28T06:56:28.110278Z","end":"2026-04-28T06:56:28.241546Z","steps":["trace[693194288] 'range keys from in-memory index tree' (duration: 131.123112ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:28.392105Z","caller":"traceutil/trace.go:171","msg":"trace[1675671497] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"120.589428ms","start":"2026-04-28T06:56:28.271496Z","end":"2026-04-28T06:56:28.392085Z","steps":["trace[1675671497] 'read index received' (duration: 40.037748ms)","trace[1675671497] 'applied index is now lower than readState.Index' (duration: 80.550899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:28.392254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.737872ms","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-04-28T06:56:28.392289Z","caller":"traceutil/trace.go:171","msg":"trace[97838640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"120.818314ms","start":"2026-04-28T06:56:28.271464Z","end":"2026-04-28T06:56:28.392282Z","steps":["trace[97838640] 'agreement among raft nodes before linearized reading' (duration: 120.69074ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:28.392223Z","caller":"traceutil/trace.go:171","msg":"trace[1552821188] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"145.958136ms","start":"2026-04-28T06:56:28.246221Z","end":"2026-04-28T06:56:28.392179Z","steps":["trace[1552821188] 'process raft request' (duration: 109.757705ms)","trace[1552821188] 'compare' (duration: 36.025357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:33.579525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:33.26613Z","time spent":"313.391344ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:56:34.916603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.368742ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:34.916755Z","caller":"traceutil/trace.go:171","msg":"trace[139039506] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1088; }","duration":"494.556946ms","start":"2026-04-28T06:56:34.422179Z","end":"2026-04-28T06:56:34.916736Z","steps":["trace[139039506] 'range keys from in-memory index tree' (duration: 494.347021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:34.917429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284258086s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:34.917739Z","caller":"traceutil/trace.go:171","msg":"trace[1138271804] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"764.483924ms","start":"2026-04-28T06:56:34.153238Z","end":"2026-04-28T06:56:34.917722Z","steps":["trace[1138271804] 'process raft request' (duration: 764.28699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:34.917836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.153214Z","time spent":"764.578497ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:34.917935Z","caller":"traceutil/trace.go:171","msg":"trace[455793450] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"926.885895ms","start":"2026-04-28T06:56:33.991011Z","end":"2026-04-28T06:56:34.917897Z","steps":["trace[455793450] 'read index received' (duration: 48.171µs)","trace[455793450] 'applied index is now lower than readState.Index' (duration: 926.835924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:34.918147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.12698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T06:56:34.918235Z","caller":"traceutil/trace.go:171","msg":"trace[463961305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"927.260273ms","start":"2026-04-28T06:56:33.990961Z","end":"2026-04-28T06:56:34.918221Z","steps":["trace[463961305] 'agreement among raft nodes before linearized reading' (duration: 927.055098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:34.918272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:33.990942Z","time spent":"927.320235ms","remote":"127.0.0.1:59820","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-04-28T06:56:34.918037Z","caller":"traceutil/trace.go:171","msg":"trace[1411657516] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.334272245s","start":"2026-04-28T06:56:33.583724Z","end":"2026-04-28T06:56:34.917996Z","steps":["trace[1411657516] 'process raft request' (duration: 49.357762ms)","trace[1411657516] 'compare' (duration: 1.283536388s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:34.918609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:33.583706Z","time spent":"1.334788579s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:35.418051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216689771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:56:35.496499Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.087316177s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:56:35.516953Z","caller":"traceutil/trace.go:171","msg":"trace[1272838025] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"1.107841104s","start":"2026-04-28T06:56:34.409092Z","end":"2026-04-28T06:56:35.516933Z","steps":["trace[1272838025] 'process raft request' (duration: 1.087606734s)","trace[1272838025] 'compare' (duration: 19.954014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:35.517066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.409068Z","time spent":"1.107944796s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:35.873187Z","caller":"traceutil/trace.go:171","msg":"trace[817219465] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1140; }","duration":"955.175719ms","start":"2026-04-28T06:56:34.917993Z","end":"2026-04-28T06:56:35.873169Z","steps":["trace[817219465] 'read index received' (duration: 578.714606ms)","trace[817219465] 'applied index is now lower than readState.Index' (duration: 376.460293ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:35.873298Z","caller":"traceutil/trace.go:171","msg":"trace[431751431] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"1.2990694s","start":"2026-04-28T06:56:34.574204Z","end":"2026-04-28T06:56:35.873273Z","steps":["trace[431751431] 'process raft request' (duration: 1.298817594s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458540367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:56:35.873368Z","caller":"traceutil/trace.go:171","msg":"trace[1058912930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"1.458599938s","start":"2026-04-28T06:56:34.414762Z","end":"2026-04-28T06:56:35.873362Z","steps":["trace[1058912930] 'agreement among raft nodes before linearized reading' (duration: 1.458495426s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.414754Z","time spent":"1.45863055s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:56:35.873383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394877173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:56:35.873441Z","caller":"traceutil/trace.go:171","msg":"trace[1007605458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"1.394965237s","start":"2026-04-28T06:56:34.478459Z","end":"2026-04-28T06:56:35.873424Z","steps":["trace[1007605458] 'agreement among raft nodes before linearized reading' (duration: 1.394755261s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.574183Z","time spent":"1.299168843s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:35.87351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537541478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:56:35.873531Z","caller":"traceutil/trace.go:171","msg":"trace[1846361014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"1.537598629s","start":"2026-04-28T06:56:34.335928Z","end":"2026-04-28T06:56:35.873526Z","steps":["trace[1846361014] 'agreement among raft nodes before linearized reading' (duration: 1.537530958s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.335878Z","time spent":"1.537665661s","remote":"127.0.0.1:59820","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-04-28T06:56:35.873545Z","caller":"traceutil/trace.go:171","msg":"trace[3324785] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"950.324797ms","start":"2026-04-28T06:56:34.923206Z","end":"2026-04-28T06:56:35.873531Z","steps":["trace[3324785] 'process raft request' (duration: 949.936297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.066511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-28T06:56:35.873635Z","caller":"traceutil/trace.go:171","msg":"trace[1956579370] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1093; }","duration":"952.100192ms","start":"2026-04-28T06:56:34.921531Z","end":"2026-04-28T06:56:35.873631Z","steps":["trace[1956579370] 'agreement among raft nodes before linearized reading' (duration: 952.064681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.92152Z","time spent":"952.127532ms","remote":"127.0.0.1:59722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-28T06:56:35.873648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.923173Z","time spent":"950.40842ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:35.873687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.797341949s","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-04-28T06:56:35.873499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.478448Z","time spent":"1.395039308s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:56:35.873745Z","caller":"traceutil/trace.go:171","msg":"trace[2053224693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"1.797456122s","start":"2026-04-28T06:56:34.076274Z","end":"2026-04-28T06:56:35.87373Z","steps":["trace[2053224693] 'agreement among raft nodes before linearized reading' (duration: 1.797286828s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.076259Z","time spent":"1.797508734s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:56:35.873832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763761404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:35.873919Z","caller":"traceutil/trace.go:171","msg":"trace[894438401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"1.763852806s","start":"2026-04-28T06:56:34.110031Z","end":"2026-04-28T06:56:35.873884Z","steps":["trace[894438401] 'agreement among raft nodes before linearized reading' (duration: 1.763761074s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.873973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:34.110009Z","time spent":"1.763952549s","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:56:38.280971Z","caller":"traceutil/trace.go:171","msg":"trace[249249834] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"398.393064ms","start":"2026-04-28T06:56:37.882548Z","end":"2026-04-28T06:56:38.280942Z","steps":["trace[249249834] 'read index received' (duration: 398.21007ms)","trace[249249834] 'applied index is now lower than readState.Index' (duration: 182.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:38.281035Z","caller":"traceutil/trace.go:171","msg":"trace[299296316] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"399.596783ms","start":"2026-04-28T06:56:37.881425Z","end":"2026-04-28T06:56:38.281022Z","steps":["trace[299296316] 'process raft request' (duration: 399.365998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:38.281127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:37.881413Z","time spent":"399.654306ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:38.28114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.58846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:56:38.281174Z","caller":"traceutil/trace.go:171","msg":"trace[1330308697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"398.660292ms","start":"2026-04-28T06:56:37.882507Z","end":"2026-04-28T06:56:38.281167Z","steps":["trace[1330308697] 'agreement among raft nodes before linearized reading' (duration: 398.541829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:38.281197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:37.882489Z","time spent":"398.702502ms","remote":"127.0.0.1:59820","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-04-28T06:56:38.281296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.481795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:56:38.281344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.469741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T06:56:38.281415Z","caller":"traceutil/trace.go:171","msg":"trace[449831295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1099; }","duration":"395.587083ms","start":"2026-04-28T06:56:37.885814Z","end":"2026-04-28T06:56:38.281401Z","steps":["trace[449831295] 'agreement among raft nodes before linearized reading' (duration: 395.393269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:38.281448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:37.885807Z","time spent":"395.636144ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:56:38.281365Z","caller":"traceutil/trace.go:171","msg":"trace[556638331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"172.600458ms","start":"2026-04-28T06:56:38.108749Z","end":"2026-04-28T06:56:38.281349Z","steps":["trace[556638331] 'agreement among raft nodes before linearized reading' (duration: 172.453645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:38.281456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.905637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:56:38.281583Z","caller":"traceutil/trace.go:171","msg":"trace[453475916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"399.00802ms","start":"2026-04-28T06:56:37.882514Z","end":"2026-04-28T06:56:38.281522Z","steps":["trace[453475916] 'agreement among raft nodes before linearized reading' (duration: 398.526769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:38.28162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:37.882508Z","time spent":"399.104792ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:56:38.281897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.494467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:56:38.281926Z","caller":"traceutil/trace.go:171","msg":"trace[50838669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"396.537328ms","start":"2026-04-28T06:56:37.885382Z","end":"2026-04-28T06:56:38.281919Z","steps":["trace[50838669] 'agreement among raft nodes before linearized reading' (duration: 395.786049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:38.281945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:37.885375Z","time spent":"396.565689ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:56:38.917334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.146049ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T06:56:40.449193Z","caller":"traceutil/trace.go:171","msg":"trace[1985950889] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"155.318061ms","start":"2026-04-28T06:56:40.293853Z","end":"2026-04-28T06:56:40.449171Z","steps":["trace[1985950889] 'read index received' (duration: 155.141097ms)","trace[1985950889] 'applied index is now lower than readState.Index' (duration: 176.144µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:40.449226Z","caller":"traceutil/trace.go:171","msg":"trace[359236269] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"161.22948ms","start":"2026-04-28T06:56:40.287981Z","end":"2026-04-28T06:56:40.449211Z","steps":["trace[359236269] 'process raft request' (duration: 161.057426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:40.449438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.84764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T06:56:40.44943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.975211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:40.449471Z","caller":"traceutil/trace.go:171","msg":"trace[2046249006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"154.905031ms","start":"2026-04-28T06:56:40.294554Z","end":"2026-04-28T06:56:40.449459Z","steps":["trace[2046249006] 'agreement among raft nodes before linearized reading' (duration: 154.776028ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:40.449507Z","caller":"traceutil/trace.go:171","msg":"trace[1418296485] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1105; }","duration":"145.066264ms","start":"2026-04-28T06:56:40.304419Z","end":"2026-04-28T06:56:40.449485Z","steps":["trace[1418296485] 'agreement among raft nodes before linearized reading' (duration: 144.967151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:40.449542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.907761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T06:56:40.449426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.548168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T06:56:40.449528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.43832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:56:40.449575Z","caller":"traceutil/trace.go:171","msg":"trace[1800900026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"154.958493ms","start":"2026-04-28T06:56:40.294606Z","end":"2026-04-28T06:56:40.449564Z","steps":["trace[1800900026] 'agreement among raft nodes before linearized reading' (duration: 154.879351ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:40.449581Z","caller":"traceutil/trace.go:171","msg":"trace[1742328398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"155.734562ms","start":"2026-04-28T06:56:40.293835Z","end":"2026-04-28T06:56:40.44957Z","steps":["trace[1742328398] 'agreement among raft nodes before linearized reading' (duration: 155.449615ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:40.4496Z","caller":"traceutil/trace.go:171","msg":"trace[2120307423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"154.523162ms","start":"2026-04-28T06:56:40.295057Z","end":"2026-04-28T06:56:40.44958Z","steps":["trace[2120307423] 'agreement among raft nodes before linearized reading' (duration: 154.302807ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:42.743556Z","caller":"traceutil/trace.go:171","msg":"trace[376665710] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"221.54592ms","start":"2026-04-28T06:56:42.521984Z","end":"2026-04-28T06:56:42.74353Z","steps":["trace[376665710] 'read index received' (duration: 221.335164ms)","trace[376665710] 'applied index is now lower than readState.Index' (duration: 209.486µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:42.743625Z","caller":"traceutil/trace.go:171","msg":"trace[1759017607] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"286.413843ms","start":"2026-04-28T06:56:42.457181Z","end":"2026-04-28T06:56:42.743595Z","steps":["trace[1759017607] 'process raft request' (duration: 286.190967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:42.743784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.777486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:56:42.743819Z","caller":"traceutil/trace.go:171","msg":"trace[67552168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"221.859307ms","start":"2026-04-28T06:56:42.52195Z","end":"2026-04-28T06:56:42.743809Z","steps":["trace[67552168] 'agreement among raft nodes before linearized reading' (duration: 221.714254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:42.743838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.662493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:56:42.743862Z","caller":"traceutil/trace.go:171","msg":"trace[506985518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1111; }","duration":"221.717484ms","start":"2026-04-28T06:56:42.522137Z","end":"2026-04-28T06:56:42.743854Z","steps":["trace[506985518] 'agreement among raft nodes before linearized reading' (duration: 221.615632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:42.743957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.239752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:56:42.743983Z","caller":"traceutil/trace.go:171","msg":"trace[711273752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"221.266982ms","start":"2026-04-28T06:56:42.522708Z","end":"2026-04-28T06:56:42.743975Z","steps":["trace[711273752] 'agreement among raft nodes before linearized reading' (duration: 221.199631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:42.743991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.366155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:56:42.744017Z","caller":"traceutil/trace.go:171","msg":"trace[547969702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"221.432666ms","start":"2026-04-28T06:56:42.522576Z","end":"2026-04-28T06:56:42.744009Z","steps":["trace[547969702] 'agreement among raft nodes before linearized reading' (duration: 221.344454ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:47.732331Z","caller":"traceutil/trace.go:171","msg":"trace[1700103493] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"157.084957ms","start":"2026-04-28T06:56:47.575227Z","end":"2026-04-28T06:56:47.732312Z","steps":["trace[1700103493] 'process raft request' (duration: 156.977193ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:50.967918Z","caller":"traceutil/trace.go:171","msg":"trace[156054562] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"144.704785ms","start":"2026-04-28T06:56:50.823187Z","end":"2026-04-28T06:56:50.967892Z","steps":["trace[156054562] 'process raft request' (duration: 144.597422ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:50.968981Z","caller":"traceutil/trace.go:171","msg":"trace[359077378] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"145.071494ms","start":"2026-04-28T06:56:50.823899Z","end":"2026-04-28T06:56:50.96897Z","steps":["trace[359077378] 'process raft request' (duration: 145.043604ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:50.969171Z","caller":"traceutil/trace.go:171","msg":"trace[1770698108] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"145.921525ms","start":"2026-04-28T06:56:50.823243Z","end":"2026-04-28T06:56:50.969164Z","steps":["trace[1770698108] 'process raft request' (duration: 145.656569ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:53.142983Z","caller":"traceutil/trace.go:171","msg":"trace[586205479] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"106.218805ms","start":"2026-04-28T06:56:53.036738Z","end":"2026-04-28T06:56:53.142957Z","steps":["trace[586205479] 'process raft request' (duration: 105.9931ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:53.145937Z","caller":"traceutil/trace.go:171","msg":"trace[45833185] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"109.15976ms","start":"2026-04-28T06:56:53.036757Z","end":"2026-04-28T06:56:53.145917Z","steps":["trace[45833185] 'process raft request' (duration: 109.031917ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:53.146091Z","caller":"traceutil/trace.go:171","msg":"trace[380674185] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"109.216181ms","start":"2026-04-28T06:56:53.036851Z","end":"2026-04-28T06:56:53.146067Z","steps":["trace[380674185] 'process raft request' (duration: 109.040657ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:54.980451Z","caller":"traceutil/trace.go:171","msg":"trace[1584870855] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"120.049013ms","start":"2026-04-28T06:56:54.860283Z","end":"2026-04-28T06:56:54.980332Z","steps":["trace[1584870855] 'process raft request' (duration: 119.862209ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:55.196778Z","caller":"traceutil/trace.go:171","msg":"trace[478371468] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"211.626341ms","start":"2026-04-28T06:56:54.98513Z","end":"2026-04-28T06:56:55.196756Z","steps":["trace[478371468] 'process raft request' (duration: 211.503827ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:55.201192Z","caller":"traceutil/trace.go:171","msg":"trace[820892947] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1205; }","duration":"123.820378ms","start":"2026-04-28T06:56:55.077358Z","end":"2026-04-28T06:56:55.201178Z","steps":["trace[820892947] 'read index received' (duration: 119.185641ms)","trace[820892947] 'applied index is now lower than readState.Index' (duration: 4.633937ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:55.201311Z","caller":"traceutil/trace.go:171","msg":"trace[1914367780] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"126.970987ms","start":"2026-04-28T06:56:55.074308Z","end":"2026-04-28T06:56:55.201279Z","steps":["trace[1914367780] 'process raft request' (duration: 126.791113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:55.202184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.038004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:55.202254Z","caller":"traceutil/trace.go:171","msg":"trace[1424010426] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1153; }","duration":"124.911515ms","start":"2026-04-28T06:56:55.077327Z","end":"2026-04-28T06:56:55.202238Z","steps":["trace[1424010426] 'agreement among raft nodes before linearized reading' (duration: 123.938201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:59.14904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.031209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T06:56:59.580243Z","caller":"traceutil/trace.go:171","msg":"trace[1043837054] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"299.298919ms","start":"2026-04-28T06:56:59.280923Z","end":"2026-04-28T06:56:59.580222Z","steps":["trace[1043837054] 'read index received' (duration: 297.234087ms)","trace[1043837054] 'applied index is now lower than readState.Index' (duration: 2.063882ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:59.58036Z","caller":"traceutil/trace.go:171","msg":"trace[720824307] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"331.176151ms","start":"2026-04-28T06:56:59.249174Z","end":"2026-04-28T06:56:59.58035Z","steps":["trace[720824307] 'process raft request' (duration: 329.076258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:59.580448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:59.249157Z","time spent":"331.226162ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:59.58055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.761689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:56:59.580636Z","caller":"traceutil/trace.go:171","msg":"trace[1926997895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1166; }","duration":"297.899062ms","start":"2026-04-28T06:56:59.282714Z","end":"2026-04-28T06:56:59.580613Z","steps":["trace[1926997895] 'agreement among raft nodes before linearized reading' (duration: 297.693568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:59.580929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.014977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:56:59.58841Z","caller":"traceutil/trace.go:171","msg":"trace[1059243459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1166; }","duration":"300.14575ms","start":"2026-04-28T06:56:59.28082Z","end":"2026-04-28T06:56:59.580966Z","steps":["trace[1059243459] 'agreement among raft nodes before linearized reading' (duration: 300.018567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:59.588477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:59.280775Z","time spent":"307.68548ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:56:59.588757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.638201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:56:59.588803Z","caller":"traceutil/trace.go:171","msg":"trace[729164317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1166; }","duration":"265.724962ms","start":"2026-04-28T06:56:59.323067Z","end":"2026-04-28T06:56:59.588792Z","steps":["trace[729164317] 'agreement among raft nodes before linearized reading' (duration: 257.870984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:00.287036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.211964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:57:00.287149Z","caller":"traceutil/trace.go:171","msg":"trace[2025977918] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"695.397026ms","start":"2026-04-28T06:56:59.591735Z","end":"2026-04-28T06:57:00.287132Z","steps":["trace[2025977918] 'process raft request' (duration: 249.986826ms)","trace[2025977918] 'compare' (duration: 444.752033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:00.28729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:59.591718Z","time spent":"695.53953ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:57:00.296505Z","caller":"traceutil/trace.go:171","msg":"trace[1841532] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"697.618642ms","start":"2026-04-28T06:56:59.598866Z","end":"2026-04-28T06:57:00.296485Z","steps":["trace[1841532] 'process raft request' (duration: 697.489729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:00.296625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:59.598847Z","time spent":"697.714824ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:57:00.29688Z","caller":"traceutil/trace.go:171","msg":"trace[1213715260] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"674.600431ms","start":"2026-04-28T06:56:59.622248Z","end":"2026-04-28T06:57:00.296848Z","steps":["trace[1213715260] 'process raft request' (duration: 674.210961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:00.297067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:59.622231Z","time spent":"674.743186ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:57:00.297052Z","caller":"traceutil/trace.go:171","msg":"trace[2051283633] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1221; }","duration":"538.528575ms","start":"2026-04-28T06:56:59.758439Z","end":"2026-04-28T06:57:00.296967Z","steps":["trace[2051283633] 'read index received' (duration: 83.253047ms)","trace[2051283633] 'applied index is now lower than readState.Index' (duration: 455.274398ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:00.297252Z","caller":"traceutil/trace.go:171","msg":"trace[1173170658] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"480.891263ms","start":"2026-04-28T06:56:59.816275Z","end":"2026-04-28T06:57:00.297166Z","steps":["trace[1173170658] 'process raft request' (duration: 480.437481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:00.297514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.061815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:00.297565Z","caller":"traceutil/trace.go:171","msg":"trace[1410475685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"186.143247ms","start":"2026-04-28T06:57:00.111407Z","end":"2026-04-28T06:57:00.29755Z","steps":["trace[1410475685] 'agreement among raft nodes before linearized reading' (duration: 186.073486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:00.2973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.839553ms","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-04-28T06:57:00.297979Z","caller":"traceutil/trace.go:171","msg":"trace[17081695] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1170; }","duration":"539.54317ms","start":"2026-04-28T06:56:59.758391Z","end":"2026-04-28T06:57:00.297934Z","steps":["trace[17081695] 'agreement among raft nodes before linearized reading' (duration: 538.846873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:00.298188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:59.758373Z","time spent":"539.793386ms","remote":"127.0.0.1:45992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:00.29733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:59.816253Z","time spent":"481.047556ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:57:00.876901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:00.530476Z","time spent":"346.418285ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:57:02.043261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216689965,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:57:02.159072Z","caller":"traceutil/trace.go:171","msg":"trace[607858479] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"616.19025ms","start":"2026-04-28T06:57:01.54286Z","end":"2026-04-28T06:57:02.15905Z","steps":["trace[607858479] 'read index received' (duration: 616.017596ms)","trace[607858479] 'applied index is now lower than readState.Index' (duration: 172.014µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:02.159212Z","caller":"traceutil/trace.go:171","msg":"trace[529183579] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"994.452029ms","start":"2026-04-28T06:57:01.16472Z","end":"2026-04-28T06:57:02.159172Z","steps":["trace[529183579] 'process raft request' (duration: 994.220212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:02.159306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:01.1647Z","time spent":"994.53654ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:57:02.159518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.621712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:57:02.159628Z","caller":"traceutil/trace.go:171","msg":"trace[1508519950] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1171; }","duration":"616.791075ms","start":"2026-04-28T06:57:01.542812Z","end":"2026-04-28T06:57:02.159603Z","steps":["trace[1508519950] 'agreement among raft nodes before linearized reading' (duration: 616.58745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:02.159698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:01.542791Z","time spent":"616.882418ms","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:02.160326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.924985ms","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-04-28T06:57:02.160424Z","caller":"traceutil/trace.go:171","msg":"trace[458790885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"570.077649ms","start":"2026-04-28T06:57:01.590328Z","end":"2026-04-28T06:57:02.160406Z","steps":["trace[458790885] 'agreement among raft nodes before linearized reading' (duration: 569.780032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:02.160469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:01.59031Z","time spent":"570.15086ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:57:02.41812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.675678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:57:02.418317Z","caller":"traceutil/trace.go:171","msg":"trace[1365718446] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"249.482345ms","start":"2026-04-28T06:57:02.168813Z","end":"2026-04-28T06:57:02.418295Z","steps":["trace[1365718446] 'process raft request' (duration: 107.5732ms)","trace[1365718446] 'compare' (duration: 141.368561ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:02.418408Z","caller":"traceutil/trace.go:171","msg":"trace[1928350295] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"127.709887ms","start":"2026-04-28T06:57:02.290669Z","end":"2026-04-28T06:57:02.418379Z","steps":["trace[1928350295] 'read index received' (duration: 29.101µs)","trace[1928350295] 'applied index is now lower than readState.Index' (duration: 127.679196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:02.418564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.879701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:57:02.418623Z","caller":"traceutil/trace.go:171","msg":"trace[875499765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1172; }","duration":"127.973723ms","start":"2026-04-28T06:57:02.290637Z","end":"2026-04-28T06:57:02.41861Z","steps":["trace[875499765] 'agreement among raft nodes before linearized reading' (duration: 127.82667ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:02.418703Z","caller":"traceutil/trace.go:171","msg":"trace[1280332907] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"115.486589ms","start":"2026-04-28T06:57:02.303209Z","end":"2026-04-28T06:57:02.418695Z","steps":["trace[1280332907] 'process raft request' (duration: 114.992348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:02.418974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.559007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:57:02.419019Z","caller":"traceutil/trace.go:171","msg":"trace[2137929957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"118.658079ms","start":"2026-04-28T06:57:02.300351Z","end":"2026-04-28T06:57:02.41901Z","steps":["trace[2137929957] 'agreement among raft nodes before linearized reading' (duration: 118.375741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:02.419054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.652339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:57:02.419172Z","caller":"traceutil/trace.go:171","msg":"trace[92807427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"118.817303ms","start":"2026-04-28T06:57:02.300345Z","end":"2026-04-28T06:57:02.419162Z","steps":["trace[92807427] 'agreement among raft nodes before linearized reading' (duration: 118.336981ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:03.503702Z","caller":"traceutil/trace.go:171","msg":"trace[2097104637] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"121.810788ms","start":"2026-04-28T06:57:03.381866Z","end":"2026-04-28T06:57:03.503677Z","steps":["trace[2097104637] 'read index received' (duration: 121.581562ms)","trace[2097104637] 'applied index is now lower than readState.Index' (duration: 227.606µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:03.503904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.020593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:03.50396Z","caller":"traceutil/trace.go:171","msg":"trace[314377] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1176; }","duration":"122.204487ms","start":"2026-04-28T06:57:03.381742Z","end":"2026-04-28T06:57:03.503947Z","steps":["trace[314377] 'agreement among raft nodes before linearized reading' (duration: 122.032693ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:03.689566Z","caller":"traceutil/trace.go:171","msg":"trace[609632902] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"184.62115ms","start":"2026-04-28T06:57:03.504915Z","end":"2026-04-28T06:57:03.689536Z","steps":["trace[609632902] 'process raft request' (duration: 103.994289ms)","trace[609632902] 'compare' (duration: 80.484417ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:04.708193Z","caller":"traceutil/trace.go:171","msg":"trace[942417803] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"284.149816ms","start":"2026-04-28T06:57:04.424022Z","end":"2026-04-28T06:57:04.708172Z","steps":["trace[942417803] 'process raft request' (duration: 284.012182ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:04.717127Z","caller":"traceutil/trace.go:171","msg":"trace[158598344] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1234; }","duration":"236.116407ms","start":"2026-04-28T06:57:04.480988Z","end":"2026-04-28T06:57:04.717104Z","steps":["trace[158598344] 'read index received' (duration: 226.987057ms)","trace[158598344] 'applied index is now lower than readState.Index' (duration: 9.128479ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:04.717255Z","caller":"traceutil/trace.go:171","msg":"trace[2131005475] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"289.506801ms","start":"2026-04-28T06:57:04.427738Z","end":"2026-04-28T06:57:04.717244Z","steps":["trace[2131005475] 'process raft request' (duration: 289.239865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:04.717524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.532776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T06:57:04.717617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.831918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:57:04.71762Z","caller":"traceutil/trace.go:171","msg":"trace[1438750785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1180; }","duration":"230.67966ms","start":"2026-04-28T06:57:04.486919Z","end":"2026-04-28T06:57:04.717598Z","steps":["trace[1438750785] 'agreement among raft nodes before linearized reading' (duration: 230.395684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:04.717623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.609059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:57:04.717659Z","caller":"traceutil/trace.go:171","msg":"trace[1672713297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1180; }","duration":"229.93935ms","start":"2026-04-28T06:57:04.487709Z","end":"2026-04-28T06:57:04.717649Z","steps":["trace[1672713297] 'agreement among raft nodes before linearized reading' (duration: 229.806077ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:04.717701Z","caller":"traceutil/trace.go:171","msg":"trace[863977162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1180; }","duration":"236.741412ms","start":"2026-04-28T06:57:04.480942Z","end":"2026-04-28T06:57:04.717684Z","steps":["trace[863977162] 'agreement among raft nodes before linearized reading' (duration: 236.464116ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:04.837097Z","caller":"traceutil/trace.go:171","msg":"trace[496086851] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"115.090159ms","start":"2026-04-28T06:57:04.721983Z","end":"2026-04-28T06:57:04.837073Z","steps":["trace[496086851] 'process raft request' (duration: 114.960725ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:04.843689Z","caller":"traceutil/trace.go:171","msg":"trace[1475256728] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"119.315095ms","start":"2026-04-28T06:57:04.724344Z","end":"2026-04-28T06:57:04.843659Z","steps":["trace[1475256728] 'process raft request' (duration: 119.266173ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:04.844231Z","caller":"traceutil/trace.go:171","msg":"trace[841525573] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"121.653325ms","start":"2026-04-28T06:57:04.722554Z","end":"2026-04-28T06:57:04.844207Z","steps":["trace[841525573] 'process raft request' (duration: 120.884125ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:05.706673Z","caller":"traceutil/trace.go:171","msg":"trace[1034851031] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"125.782648ms","start":"2026-04-28T06:57:05.580864Z","end":"2026-04-28T06:57:05.706647Z","steps":["trace[1034851031] 'process raft request' (duration: 125.631144ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:06.528379Z","caller":"traceutil/trace.go:171","msg":"trace[1570034271] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"101.414453ms","start":"2026-04-28T06:57:06.426929Z","end":"2026-04-28T06:57:06.528343Z","steps":["trace[1570034271] 'process raft request' (duration: 101.230749ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:13.464686Z","caller":"traceutil/trace.go:171","msg":"trace[1291736856] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"116.191827ms","start":"2026-04-28T06:57:13.348473Z","end":"2026-04-28T06:57:13.464665Z","steps":["trace[1291736856] 'process raft request' (duration: 97.482495ms)","trace[1291736856] 'compare' (duration: 18.610079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:21.292725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216690221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:57:21.446458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.724441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:57:21.446551Z","caller":"traceutil/trace.go:171","msg":"trace[1308067409] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1282; }","duration":"861.396138ms","start":"2026-04-28T06:57:20.585133Z","end":"2026-04-28T06:57:21.446529Z","steps":["trace[1308067409] 'count revisions from in-memory index tree' (duration: 860.65406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:21.446597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:20.585118Z","time spent":"861.46182ms","remote":"127.0.0.1:59742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:21.446564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.932123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:57:21.446747Z","caller":"traceutil/trace.go:171","msg":"trace[1591441344] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1282; }","duration":"829.13852ms","start":"2026-04-28T06:57:20.617569Z","end":"2026-04-28T06:57:21.446708Z","steps":["trace[1591441344] 'count revisions from in-memory index tree' (duration: 828.871732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:21.446808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:20.617551Z","time spent":"829.240732ms","remote":"127.0.0.1:58518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:57:21.465685Z","caller":"traceutil/trace.go:171","msg":"trace[172274123] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"673.48904ms","start":"2026-04-28T06:57:20.792171Z","end":"2026-04-28T06:57:21.46566Z","steps":["trace[172274123] 'read index received' (duration: 673.345586ms)","trace[172274123] 'applied index is now lower than readState.Index' (duration: 142.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:21.465778Z","caller":"traceutil/trace.go:171","msg":"trace[1900063425] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"696.911139ms","start":"2026-04-28T06:57:20.768856Z","end":"2026-04-28T06:57:21.465767Z","steps":["trace[1900063425] 'process raft request' (duration: 696.645741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:21.465957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:20.768832Z","time spent":"697.058353ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:57:21.465971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.970804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T06:57:21.466045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.852989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:57:21.46611Z","caller":"traceutil/trace.go:171","msg":"trace[797437132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"673.952102ms","start":"2026-04-28T06:57:20.792144Z","end":"2026-04-28T06:57:21.466096Z","steps":["trace[797437132] 'agreement among raft nodes before linearized reading' (duration: 673.753377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:21.466155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:20.79213Z","time spent":"674.018673ms","remote":"127.0.0.1:59820","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-04-28T06:57:21.466118Z","caller":"traceutil/trace.go:171","msg":"trace[1996428877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"549.107057ms","start":"2026-04-28T06:57:20.916951Z","end":"2026-04-28T06:57:21.466058Z","steps":["trace[1996428877] 'agreement among raft nodes before linearized reading' (duration: 548.887611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:21.4663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.116876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:57:21.466361Z","caller":"traceutil/trace.go:171","msg":"trace[596422237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"492.195258ms","start":"2026-04-28T06:57:20.97415Z","end":"2026-04-28T06:57:21.466345Z","steps":["trace[596422237] 'agreement among raft nodes before linearized reading' (duration: 492.041764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:21.46641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:20.974144Z","time spent":"492.25515ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:57:21.466274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.231249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T06:57:21.466388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:20.916938Z","time spent":"549.436006ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:57:21.466463Z","caller":"traceutil/trace.go:171","msg":"trace[289393946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"496.439415ms","start":"2026-04-28T06:57:20.970013Z","end":"2026-04-28T06:57:21.466452Z","steps":["trace[289393946] 'agreement among raft nodes before linearized reading' (duration: 496.224479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:21.466541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:20.97Z","time spent":"496.535387ms","remote":"127.0.0.1:59820","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-04-28T06:57:43.411071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278451216690462,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:57:43.475348Z","caller":"traceutil/trace.go:171","msg":"trace[928562502] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"564.661318ms","start":"2026-04-28T06:57:42.910667Z","end":"2026-04-28T06:57:43.475328Z","steps":["trace[928562502] 'read index received' (duration: 564.439622ms)","trace[928562502] 'applied index is now lower than readState.Index' (duration: 220.566µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:43.475395Z","caller":"traceutil/trace.go:171","msg":"trace[732111446] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"803.094435ms","start":"2026-04-28T06:57:42.67228Z","end":"2026-04-28T06:57:43.475375Z","steps":["trace[732111446] 'process raft request' (duration: 802.8878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:43.475485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:42.672258Z","time spent":"803.170267ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:57:43.475541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.876743ms","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-04-28T06:57:43.475574Z","caller":"traceutil/trace.go:171","msg":"trace[1821828855] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1395; }","duration":"564.962465ms","start":"2026-04-28T06:57:42.910601Z","end":"2026-04-28T06:57:43.475564Z","steps":["trace[1821828855] 'agreement among raft nodes before linearized reading' (duration: 564.833102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:43.475559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.739643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:57:43.475605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:42.910578Z","time spent":"565.019267ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:57:43.475613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.415003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T06:57:43.475642Z","caller":"traceutil/trace.go:171","msg":"trace[221842106] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1395; }","duration":"206.535546ms","start":"2026-04-28T06:57:43.2691Z","end":"2026-04-28T06:57:43.475636Z","steps":["trace[221842106] 'agreement among raft nodes before linearized reading' (duration: 206.463824ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:43.475648Z","caller":"traceutil/trace.go:171","msg":"trace[108187014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"365.862005ms","start":"2026-04-28T06:57:43.109747Z","end":"2026-04-28T06:57:43.475609Z","steps":["trace[108187014] 'agreement among raft nodes before linearized reading' (duration: 365.731812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:43.475685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:43.109729Z","time spent":"365.950088ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:57:43.914621Z","caller":"traceutil/trace.go:171","msg":"trace[804088122] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"115.753856ms","start":"2026-04-28T06:57:43.798848Z","end":"2026-04-28T06:57:43.914602Z","steps":["trace[804088122] 'process raft request' (duration: 115.688545ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:43.91462Z","caller":"traceutil/trace.go:171","msg":"trace[873332738] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"115.753536ms","start":"2026-04-28T06:57:43.798837Z","end":"2026-04-28T06:57:43.914591Z","steps":["trace[873332738] 'process raft request' (duration: 75.502016ms)","trace[873332738] 'compare' (duration: 40.077606ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:43.914789Z","caller":"traceutil/trace.go:171","msg":"trace[908525447] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"115.828078ms","start":"2026-04-28T06:57:43.798948Z","end":"2026-04-28T06:57:43.914776Z","steps":["trace[908525447] 'process raft request' (duration: 115.624183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.924203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.802714ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:44.924317Z","caller":"traceutil/trace.go:171","msg":"trace[39388154] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1402; }","duration":"500.926237ms","start":"2026-04-28T06:57:44.423372Z","end":"2026-04-28T06:57:44.924298Z","steps":["trace[39388154] 'range keys from in-memory index tree' (duration: 500.788033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.92433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.68618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:57:44.924477Z","caller":"traceutil/trace.go:171","msg":"trace[1786586397] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"811.912246ms","start":"2026-04-28T06:57:44.112543Z","end":"2026-04-28T06:57:44.924455Z","steps":["trace[1786586397] 'read index received' (duration: 20.061µs)","trace[1786586397] 'applied index is now lower than readState.Index' (duration: 811.890065ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:44.924556Z","caller":"traceutil/trace.go:171","msg":"trace[737338141] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"1.013900827s","start":"2026-04-28T06:57:43.910629Z","end":"2026-04-28T06:57:44.924529Z","steps":["trace[737338141] 'process raft request' (duration: 199.926759ms)","trace[737338141] 'compare' (duration: 813.326131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:44.924654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.09577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:57:44.924673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:43.910612Z","time spent":"1.013997521s","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":886,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:57:44.924698Z","caller":"traceutil/trace.go:171","msg":"trace[1087122944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1403; }","duration":"812.174562ms","start":"2026-04-28T06:57:44.112509Z","end":"2026-04-28T06:57:44.924684Z","steps":["trace[1087122944] 'agreement among raft nodes before linearized reading' (duration: 812.0652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.924739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.112495Z","time spent":"812.233713ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:57:44.926801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.05056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:44.926843Z","caller":"traceutil/trace.go:171","msg":"trace[1491703580] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1403; }","duration":"364.122272ms","start":"2026-04-28T06:57:44.56271Z","end":"2026-04-28T06:57:44.926833Z","steps":["trace[1491703580] 'agreement among raft nodes before linearized reading' (duration: 364.057141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.92687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.562696Z","time spent":"364.165683ms","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:44.926907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.128775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T06:57:44.926961Z","caller":"traceutil/trace.go:171","msg":"trace[667757844] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1403; }","duration":"621.231987ms","start":"2026-04-28T06:57:44.305717Z","end":"2026-04-28T06:57:44.926949Z","steps":["trace[667757844] 'agreement among raft nodes before linearized reading' (duration: 621.072483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.926971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.358646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:44.927332Z","caller":"traceutil/trace.go:171","msg":"trace[845402332] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1403; }","duration":"708.798368ms","start":"2026-04-28T06:57:44.218514Z","end":"2026-04-28T06:57:44.927312Z","steps":["trace[845402332] 'agreement among raft nodes before linearized reading' (duration: 708.351156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.305696Z","time spent":"621.293929ms","remote":"127.0.0.1:60038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":150,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:44.926933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.900158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:57:44.927533Z","caller":"traceutil/trace.go:171","msg":"trace[782361808] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1403; }","duration":"337.598426ms","start":"2026-04-28T06:57:44.589924Z","end":"2026-04-28T06:57:44.927522Z","steps":["trace[782361808] 'agreement among raft nodes before linearized reading' (duration: 336.874548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.927575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.58988Z","time spent":"337.682948ms","remote":"127.0.0.1:59924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:44.927516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.218492Z","time spent":"708.92374ms","remote":"127.0.0.1:59694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:44.92716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.887626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:44.927707Z","caller":"traceutil/trace.go:171","msg":"trace[1731330133] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1403; }","duration":"309.45801ms","start":"2026-04-28T06:57:44.618238Z","end":"2026-04-28T06:57:44.927696Z","steps":["trace[1731330133] 'agreement among raft nodes before linearized reading' (duration: 308.892965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.927758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.618226Z","time spent":"309.518721ms","remote":"127.0.0.1:46006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:44.927201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.995399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:57:44.927849Z","caller":"traceutil/trace.go:171","msg":"trace[663209529] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1403; }","duration":"683.693736ms","start":"2026-04-28T06:57:44.244138Z","end":"2026-04-28T06:57:44.927832Z","steps":["trace[663209529] 'agreement among raft nodes before linearized reading' (duration: 682.951617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:44.927892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.244119Z","time spent":"683.760608ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:57:46.082042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.818692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:57:46.082199Z","caller":"traceutil/trace.go:171","msg":"trace[1239641093] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1470; }","duration":"626.90231ms","start":"2026-04-28T06:57:45.455281Z","end":"2026-04-28T06:57:46.082183Z","steps":["trace[1239641093] 'read index received' (duration: 71.521µs)","trace[1239641093] 'applied index is now lower than readState.Index' (duration: 626.829659ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:46.082297Z","caller":"traceutil/trace.go:171","msg":"trace[1264947099] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"1.149768118s","start":"2026-04-28T06:57:44.932454Z","end":"2026-04-28T06:57:46.082222Z","steps":["trace[1264947099] 'process raft request' (duration: 400.70465ms)","trace[1264947099] 'compare' (duration: 748.601567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:46.082475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:44.932432Z","time spent":"1.149966914s","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:57:46.083822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.267856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:57:46.083859Z","caller":"traceutil/trace.go:171","msg":"trace[1106334776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1405; }","duration":"164.330497ms","start":"2026-04-28T06:57:45.91952Z","end":"2026-04-28T06:57:46.08385Z","steps":["trace[1106334776] 'agreement among raft nodes before linearized reading' (duration: 164.196033ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.083955Z","caller":"traceutil/trace.go:171","msg":"trace[552170436] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"325.165286ms","start":"2026-04-28T06:57:45.758783Z","end":"2026-04-28T06:57:46.083948Z","steps":["trace[552170436] 'process raft request' (duration: 323.35579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.083951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.917225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:57:46.083996Z","caller":"traceutil/trace.go:171","msg":"trace[315152774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1405; }","duration":"285.018677ms","start":"2026-04-28T06:57:45.798964Z","end":"2026-04-28T06:57:46.083983Z","steps":["trace[315152774] 'agreement among raft nodes before linearized reading' (duration: 284.885554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.084062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.349837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:57:46.084084Z","caller":"traceutil/trace.go:171","msg":"trace[611591006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1405; }","duration":"164.388018ms","start":"2026-04-28T06:57:45.91969Z","end":"2026-04-28T06:57:46.084078Z","steps":["trace[611591006] 'agreement among raft nodes before linearized reading' (duration: 164.331147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.084064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.762318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:57:46.084067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.01175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:57:46.084004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:45.758764Z","time spent":"325.212457ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:57:46.084195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.672825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:57:46.084214Z","caller":"traceutil/trace.go:171","msg":"trace[762503087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1405; }","duration":"164.761157ms","start":"2026-04-28T06:57:45.919447Z","end":"2026-04-28T06:57:46.084209Z","steps":["trace[762503087] 'agreement among raft nodes before linearized reading' (duration: 164.713206ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.084194Z","caller":"traceutil/trace.go:171","msg":"trace[2081860336] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1405; }","duration":"454.209925ms","start":"2026-04-28T06:57:45.629969Z","end":"2026-04-28T06:57:46.084179Z","steps":["trace[2081860336] 'agreement among raft nodes before linearized reading' (duration: 453.98602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.084308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:45.629951Z","time spent":"454.342688ms","remote":"127.0.0.1:46080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:46.084149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.238385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:57:46.084521Z","caller":"traceutil/trace.go:171","msg":"trace[1583304575] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1405; }","duration":"172.639725ms","start":"2026-04-28T06:57:45.911867Z","end":"2026-04-28T06:57:46.084507Z","steps":["trace[1583304575] 'agreement among raft nodes before linearized reading' (duration: 172.260365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.084708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.931899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-28T06:57:46.084111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.845747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:46.084775Z","caller":"traceutil/trace.go:171","msg":"trace[254532481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1405; }","duration":"595.025881ms","start":"2026-04-28T06:57:45.489736Z","end":"2026-04-28T06:57:46.084762Z","steps":["trace[254532481] 'agreement among raft nodes before linearized reading' (duration: 593.586285ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.084806Z","caller":"traceutil/trace.go:171","msg":"trace[1651714904] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1405; }","duration":"421.616026ms","start":"2026-04-28T06:57:45.663173Z","end":"2026-04-28T06:57:46.084789Z","steps":["trace[1651714904] 'agreement among raft nodes before linearized reading' (duration: 420.860527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.08489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:45.489721Z","time spent":"595.132373ms","remote":"127.0.0.1:59820","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-04-28T06:57:46.084892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:45.663155Z","time spent":"421.712279ms","remote":"127.0.0.1:58514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:57:46.08414Z","caller":"traceutil/trace.go:171","msg":"trace[89896474] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1405; }","duration":"628.89023ms","start":"2026-04-28T06:57:45.455233Z","end":"2026-04-28T06:57:46.084124Z","steps":["trace[89896474] 'agreement among raft nodes before linearized reading' (duration: 627.035343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.085233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:45.455216Z","time spent":"630.000619ms","remote":"127.0.0.1:59638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:57:46.084313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.855121ms","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-04-28T06:57:46.085437Z","caller":"traceutil/trace.go:171","msg":"trace[2023591237] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1405; }","duration":"594.953169ms","start":"2026-04-28T06:57:45.49045Z","end":"2026-04-28T06:57:46.085403Z","steps":["trace[2023591237] 'agreement among raft nodes before linearized reading' (duration: 593.751579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:46.085507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:45.490425Z","time spent":"595.065221ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T06:57:46.354851Z","caller":"traceutil/trace.go:171","msg":"trace[832081868] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1472; }","duration":"226.429826ms","start":"2026-04-28T06:57:46.128398Z","end":"2026-04-28T06:57:46.354828Z","steps":["trace[832081868] 'read index received' (duration: 196.582916ms)","trace[832081868] 'applied index is now lower than readState.Index' (duration: 29.84595ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:46.354907Z","caller":"traceutil/trace.go:171","msg":"trace[1519505961] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"265.496996ms","start":"2026-04-28T06:57:46.089378Z","end":"2026-04-28T06:57:46.354875Z","steps":["trace[1519505961] 'process raft request' (duration: 265.328962ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.354946Z","caller":"traceutil/trace.go:171","msg":"trace[990310778] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"267.24038ms","start":"2026-04-28T06:57:46.087665Z","end":"2026-04-28T06:57:46.354906Z","steps":["trace[990310778] 'process raft request' (duration: 237.303228ms)","trace[990310778] 'compare' (duration: 29.566722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:46.354997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.562559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:46.355029Z","caller":"traceutil/trace.go:171","msg":"trace[1241086578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1411; }","duration":"226.658922ms","start":"2026-04-28T06:57:46.12836Z","end":"2026-04-28T06:57:46.355019Z","steps":["trace[1241086578] 'agreement among raft nodes before linearized reading' (duration: 226.557249ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.355208Z","caller":"traceutil/trace.go:171","msg":"trace[1290891132] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"266.010029ms","start":"2026-04-28T06:57:46.089133Z","end":"2026-04-28T06:57:46.355143Z","steps":["trace[1290891132] 'process raft request' (duration: 265.533887ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.355275Z","caller":"traceutil/trace.go:171","msg":"trace[260734947] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"265.838545ms","start":"2026-04-28T06:57:46.089422Z","end":"2026-04-28T06:57:46.35526Z","steps":["trace[260734947] 'process raft request' (duration: 265.316332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.355331Z","caller":"traceutil/trace.go:171","msg":"trace[780730450] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"265.812494ms","start":"2026-04-28T06:57:46.08946Z","end":"2026-04-28T06:57:46.355272Z","steps":["trace[780730450] 'process raft request' (duration: 265.307122ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.355426Z","caller":"traceutil/trace.go:171","msg":"trace[758909667] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"264.552193ms","start":"2026-04-28T06:57:46.09086Z","end":"2026-04-28T06:57:46.355412Z","steps":["trace[758909667] 'process raft request' (duration: 263.935627ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:46.813922Z","caller":"traceutil/trace.go:171","msg":"trace[381649104] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"103.115128ms","start":"2026-04-28T06:57:46.710768Z","end":"2026-04-28T06:57:46.813883Z","steps":["trace[381649104] 'process raft request' (duration: 91.713992ms)","trace[381649104] 'compare' (duration: 11.14141ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:46.965779Z","caller":"traceutil/trace.go:171","msg":"trace[1704253818] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"128.017474ms","start":"2026-04-28T06:57:46.837738Z","end":"2026-04-28T06:57:46.965756Z","steps":["trace[1704253818] 'process raft request' (duration: 127.400089ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:50.325268Z","caller":"traceutil/trace.go:171","msg":"trace[58745299] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"210.740381ms","start":"2026-04-28T06:57:50.114502Z","end":"2026-04-28T06:57:50.325242Z","steps":["trace[58745299] 'process raft request' (duration: 210.570887ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:50.702223Z","caller":"traceutil/trace.go:171","msg":"trace[736492617] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"326.041806ms","start":"2026-04-28T06:57:50.37615Z","end":"2026-04-28T06:57:50.702192Z","steps":["trace[736492617] 'process raft request' (duration: 301.056729ms)","trace[736492617] 'compare' (duration: 24.526296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:50.702433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:50.376138Z","time spent":"326.18241ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:57:51.217054Z","caller":"traceutil/trace.go:171","msg":"trace[1019496735] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"838.593037ms","start":"2026-04-28T06:57:50.378441Z","end":"2026-04-28T06:57:51.217034Z","steps":["trace[1019496735] 'process raft request' (duration: 838.452363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:51.217174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:50.378432Z","time spent":"838.681508ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:57:51.217284Z","caller":"traceutil/trace.go:171","msg":"trace[453452467] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"838.788361ms","start":"2026-04-28T06:57:50.37849Z","end":"2026-04-28T06:57:51.217279Z","steps":["trace[453452467] 'process raft request' (duration: 838.520255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:51.217308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:50.378486Z","time spent":"838.808932ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:57:51.2177Z","caller":"traceutil/trace.go:171","msg":"trace[1278268798] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1509; }","duration":"379.674094ms","start":"2026-04-28T06:57:50.838005Z","end":"2026-04-28T06:57:51.217679Z","steps":["trace[1278268798] 'read index received' (duration: 378.836823ms)","trace[1278268798] 'applied index is now lower than readState.Index' (duration: 836.301µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:51.217785Z","caller":"traceutil/trace.go:171","msg":"trace[946297049] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"838.75184ms","start":"2026-04-28T06:57:50.379026Z","end":"2026-04-28T06:57:51.217777Z","steps":["trace[946297049] 'process raft request' (duration: 838.234917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:51.217828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:50.379017Z","time spent":"838.794131ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:57:51.217864Z","caller":"traceutil/trace.go:171","msg":"trace[1490489051] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"838.323919ms","start":"2026-04-28T06:57:50.379528Z","end":"2026-04-28T06:57:51.217851Z","steps":["trace[1490489051] 'process raft request' (duration: 838.041902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:51.217992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.263066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:57:51.218018Z","caller":"traceutil/trace.go:171","msg":"trace[873753843] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1445; }","duration":"127.339538ms","start":"2026-04-28T06:57:51.09067Z","end":"2026-04-28T06:57:51.21801Z","steps":["trace[873753843] 'agreement among raft nodes before linearized reading' (duration: 127.263336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:51.21802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:50.379523Z","time spent":"838.402331ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:57:51.218109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.118585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T06:57:51.218127Z","caller":"traceutil/trace.go:171","msg":"trace[192832633] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1445; }","duration":"380.204286ms","start":"2026-04-28T06:57:50.837919Z","end":"2026-04-28T06:57:51.218123Z","steps":["trace[192832633] 'agreement among raft nodes before linearized reading' (duration: 380.154715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:51.21814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:57:50.837881Z","time spent":"380.255208ms","remote":"127.0.0.1:59820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-28T06:57:51.2182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.867159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:51.218215Z","caller":"traceutil/trace.go:171","msg":"trace[1567850077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1445; }","duration":"107.926511ms","start":"2026-04-28T06:57:51.110285Z","end":"2026-04-28T06:57:51.218211Z","steps":["trace[1567850077] 'agreement among raft nodes before linearized reading' (duration: 107.90318ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:56.386547Z","caller":"traceutil/trace.go:171","msg":"trace[1503677615] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"107.294564ms","start":"2026-04-28T06:57:56.279234Z","end":"2026-04-28T06:57:56.386529Z","steps":["trace[1503677615] 'process raft request' (duration: 107.028236ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:24.6269Z","caller":"traceutil/trace.go:171","msg":"trace[1204239929] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"127.437062ms","start":"2026-04-28T06:58:24.499439Z","end":"2026-04-28T06:58:24.626876Z","steps":["trace[1204239929] 'process raft request' (duration: 127.025011ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:35.71779Z","caller":"traceutil/trace.go:171","msg":"trace[1639438151] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"161.630077ms","start":"2026-04-28T06:58:35.556122Z","end":"2026-04-28T06:58:35.717752Z","steps":["trace[1639438151] 'process raft request' (duration: 136.272576ms)","trace[1639438151] 'compare' (duration: 25.110144ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:36.94991Z","caller":"traceutil/trace.go:171","msg":"trace[601891556] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"156.887867ms","start":"2026-04-28T06:58:36.793003Z","end":"2026-04-28T06:58:36.949891Z","steps":["trace[601891556] 'process raft request' (duration: 120.787544ms)","trace[601891556] 'compare' (duration: 35.880767ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:41.708697Z","caller":"traceutil/trace.go:171","msg":"trace[108262485] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"113.08924ms","start":"2026-04-28T06:58:41.595579Z","end":"2026-04-28T06:58:41.708668Z","steps":["trace[108262485] 'process raft request' (duration: 49.483941ms)","trace[108262485] 'compare' (duration: 63.442755ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:41.708964Z","caller":"traceutil/trace.go:171","msg":"trace[1552151916] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"113.334135ms","start":"2026-04-28T06:58:41.595605Z","end":"2026-04-28T06:58:41.708939Z","steps":["trace[1552151916] 'process raft request' (duration: 113.024178ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:41.709052Z","caller":"traceutil/trace.go:171","msg":"trace[1902536009] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"112.72073ms","start":"2026-04-28T06:58:41.596318Z","end":"2026-04-28T06:58:41.709038Z","steps":["trace[1902536009] 'process raft request' (duration: 112.545705ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:41.908388Z","caller":"traceutil/trace.go:171","msg":"trace[884310152] linearizableReadLoop","detail":"{readStateIndex:1756; appliedIndex:1755; }","duration":"106.653006ms","start":"2026-04-28T06:58:41.80171Z","end":"2026-04-28T06:58:41.908363Z","steps":["trace[884310152] 'read index received' (duration: 106.475422ms)","trace[884310152] 'applied index is now lower than readState.Index' (duration: 176.484µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:41.908531Z","caller":"traceutil/trace.go:171","msg":"trace[976333981] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"160.578801ms","start":"2026-04-28T06:58:41.747922Z","end":"2026-04-28T06:58:41.908501Z","steps":["trace[976333981] 'process raft request' (duration: 160.281763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:58:41.908629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.910833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T06:58:41.908683Z","caller":"traceutil/trace.go:171","msg":"trace[1918340125] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1678; }","duration":"106.989285ms","start":"2026-04-28T06:58:41.801661Z","end":"2026-04-28T06:58:41.908651Z","steps":["trace[1918340125] 'agreement among raft nodes before linearized reading' (duration: 106.79656ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:42.10504Z","caller":"traceutil/trace.go:171","msg":"trace[581331534] linearizableReadLoop","detail":"{readStateIndex:1757; appliedIndex:1756; }","duration":"146.989267ms","start":"2026-04-28T06:58:41.958029Z","end":"2026-04-28T06:58:42.105018Z","steps":["trace[581331534] 'read index received' (duration: 132.86865ms)","trace[581331534] 'applied index is now lower than readState.Index' (duration: 14.119597ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:42.105194Z","caller":"traceutil/trace.go:171","msg":"trace[488272773] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"192.398014ms","start":"2026-04-28T06:58:41.912734Z","end":"2026-04-28T06:58:42.105132Z","steps":["trace[488272773] 'process raft request' (duration: 178.219315ms)","trace[488272773] 'compare' (duration: 13.871471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:58:42.105233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.193241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-28T06:58:42.105408Z","caller":"traceutil/trace.go:171","msg":"trace[394478085] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1679; }","duration":"147.266114ms","start":"2026-04-28T06:58:41.95799Z","end":"2026-04-28T06:58:42.105257Z","steps":["trace[394478085] 'agreement among raft nodes before linearized reading' (duration: 147.13657ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:42.872618Z","caller":"traceutil/trace.go:171","msg":"trace[1603601107] transaction","detail":"{read_only:false; response_revision:1681; number_of_response:1; }","duration":"128.461418ms","start":"2026-04-28T06:58:42.74413Z","end":"2026-04-28T06:58:42.872591Z","steps":["trace[1603601107] 'process raft request' (duration: 128.113318ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:43.661621Z","caller":"traceutil/trace.go:171","msg":"trace[1159922184] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"117.810689ms","start":"2026-04-28T06:58:43.543786Z","end":"2026-04-28T06:58:43.661596Z","steps":["trace[1159922184] 'process raft request' (duration: 117.675875ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:43.808239Z","caller":"traceutil/trace.go:171","msg":"trace[501495983] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"128.583041ms","start":"2026-04-28T06:58:43.679637Z","end":"2026-04-28T06:58:43.80822Z","steps":["trace[501495983] 'process raft request' (duration: 87.217665ms)","trace[501495983] 'compare' (duration: 40.979336ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:43.940572Z","caller":"traceutil/trace.go:171","msg":"trace[826269198] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"126.610542ms","start":"2026-04-28T06:58:43.813417Z","end":"2026-04-28T06:58:43.940028Z","steps":["trace[826269198] 'process raft request' (duration: 120.828896ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:54.393796Z","caller":"traceutil/trace.go:171","msg":"trace[1192091047] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"181.442048ms","start":"2026-04-28T06:58:54.21233Z","end":"2026-04-28T06:58:54.393772Z","steps":["trace[1192091047] 'process raft request' (duration: 181.323355ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:56.194192Z","caller":"traceutil/trace.go:171","msg":"trace[1267950626] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"139.010035ms","start":"2026-04-28T06:58:56.055137Z","end":"2026-04-28T06:58:56.194147Z","steps":["trace[1267950626] 'process raft request' (duration: 138.869942ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:56.315043Z","caller":"traceutil/trace.go:171","msg":"trace[2018173687] linearizableReadLoop","detail":"{readStateIndex:1822; appliedIndex:1820; }","duration":"180.391712ms","start":"2026-04-28T06:58:56.134626Z","end":"2026-04-28T06:58:56.315017Z","steps":["trace[2018173687] 'read index received' (duration: 59.473324ms)","trace[2018173687] 'applied index is now lower than readState.Index' (duration: 120.917327ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:56.315301Z","caller":"traceutil/trace.go:171","msg":"trace[580292271] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"251.742366ms","start":"2026-04-28T06:58:56.063539Z","end":"2026-04-28T06:58:56.315281Z","steps":["trace[580292271] 'process raft request' (duration: 245.524369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:58:56.31552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.601857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:58:56.315581Z","caller":"traceutil/trace.go:171","msg":"trace[373105903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1741; }","duration":"180.983276ms","start":"2026-04-28T06:58:56.134584Z","end":"2026-04-28T06:58:56.315567Z","steps":["trace[373105903] 'agreement among raft nodes before linearized reading' (duration: 180.527445ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:02.053618Z","caller":"traceutil/trace.go:171","msg":"trace[1537661004] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"100.209243ms","start":"2026-04-28T06:59:01.953387Z","end":"2026-04-28T06:59:02.053596Z","steps":["trace[1537661004] 'process raft request' (duration: 99.779613ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:06.391602Z","caller":"traceutil/trace.go:171","msg":"trace[1469059957] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"124.286163ms","start":"2026-04-28T06:59:06.267291Z","end":"2026-04-28T06:59:06.391577Z","steps":["trace[1469059957] 'process raft request' (duration: 123.854162ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:27.116509Z","caller":"traceutil/trace.go:171","msg":"trace[1591016426] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"200.830427ms","start":"2026-04-28T06:59:26.915648Z","end":"2026-04-28T06:59:27.116478Z","steps":["trace[1591016426] 'process raft request' (duration: 199.81661ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:27.354543Z","caller":"traceutil/trace.go:171","msg":"trace[1530988369] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"123.892115ms","start":"2026-04-28T06:59:27.230632Z","end":"2026-04-28T06:59:27.354524Z","steps":["trace[1530988369] 'process raft request' (duration: 84.429892ms)","trace[1530988369] 'compare' (duration: 39.065792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:59:37.259717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.956721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:59:37.259806Z","caller":"traceutil/trace.go:171","msg":"trace[607731804] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1928; }","duration":"125.097375ms","start":"2026-04-28T06:59:37.134687Z","end":"2026-04-28T06:59:37.259784Z","steps":["trace[607731804] 'range keys from in-memory index tree' (duration: 124.783417ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:00:45.770281Z","caller":"traceutil/trace.go:171","msg":"trace[1271979109] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"133.824287ms","start":"2026-04-28T07:00:45.636419Z","end":"2026-04-28T07:00:45.770243Z","steps":["trace[1271979109] 'process raft request' (duration: 133.676263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:00:45.990234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.944684ms","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-04-28T07:00:45.990318Z","caller":"traceutil/trace.go:171","msg":"trace[1704591230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2243; }","duration":"135.156971ms","start":"2026-04-28T07:00:45.855143Z","end":"2026-04-28T07:00:45.9903Z","steps":["trace[1704591230] 'range keys from in-memory index tree' (duration: 134.74098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:00:46.391164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.293338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:00:46.391281Z","caller":"traceutil/trace.go:171","msg":"trace[410623775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2244; }","duration":"281.453942ms","start":"2026-04-28T07:00:46.109806Z","end":"2026-04-28T07:00:46.39126Z","steps":["trace[410623775] 'range keys from in-memory index tree' (duration: 281.199107ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:00:46.810514Z","caller":"traceutil/trace.go:171","msg":"trace[1556573836] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2347; }","duration":"140.636579ms","start":"2026-04-28T07:00:46.66986Z","end":"2026-04-28T07:00:46.810497Z","steps":["trace[1556573836] 'read index received' (duration: 140.539557ms)","trace[1556573836] 'applied index is now lower than readState.Index' (duration: 96.292µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:00:46.81058Z","caller":"traceutil/trace.go:171","msg":"trace[2117269381] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"216.157225ms","start":"2026-04-28T07:00:46.594394Z","end":"2026-04-28T07:00:46.810552Z","steps":["trace[2117269381] 'process raft request' (duration: 216.005942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:00:46.810904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.022639ms","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-04-28T07:00:46.81107Z","caller":"traceutil/trace.go:171","msg":"trace[946553499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2245; }","duration":"141.147212ms","start":"2026-04-28T07:00:46.669817Z","end":"2026-04-28T07:00:46.810964Z","steps":["trace[946553499] 'agreement among raft nodes before linearized reading' (duration: 140.753262ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:00:56.25066Z","caller":"traceutil/trace.go:171","msg":"trace[676928474] linearizableReadLoop","detail":"{readStateIndex:2402; appliedIndex:2400; }","duration":"235.054062ms","start":"2026-04-28T07:00:56.015577Z","end":"2026-04-28T07:00:56.250631Z","steps":["trace[676928474] 'read index received' (duration: 3.673943ms)","trace[676928474] 'applied index is now lower than readState.Index' (duration: 231.378809ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:00:56.250769Z","caller":"traceutil/trace.go:171","msg":"trace[947592053] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"235.418621ms","start":"2026-04-28T07:00:56.015329Z","end":"2026-04-28T07:00:56.250748Z","steps":["trace[947592053] 'process raft request' (duration: 218.643177ms)","trace[947592053] 'compare' (duration: 16.521887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:00:56.25085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.525566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:00:56.250844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.253476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-04-28T07:00:56.250893Z","caller":"traceutil/trace.go:171","msg":"trace[1394694200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2296; }","duration":"142.635589ms","start":"2026-04-28T07:00:56.108244Z","end":"2026-04-28T07:00:56.250879Z","steps":["trace[1394694200] 'agreement among raft nodes before linearized reading' (duration: 142.544436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:00:56.25093Z","caller":"traceutil/trace.go:171","msg":"trace[610095481] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2296; }","duration":"235.37187ms","start":"2026-04-28T07:00:56.01554Z","end":"2026-04-28T07:00:56.250912Z","steps":["trace[610095481] 'agreement among raft nodes before linearized reading' (duration: 235.221016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:00:56.251058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.365758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:00:56.25112Z","caller":"traceutil/trace.go:171","msg":"trace[1123254892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2296; }","duration":"122.47561ms","start":"2026-04-28T07:00:56.128638Z","end":"2026-04-28T07:00:56.251113Z","steps":["trace[1123254892] 'agreement among raft nodes before linearized reading' (duration: 122.349747ms)"],"step_count":1}