{"level":"warn","ts":"2026-07-21T20:58:09.142139Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T20:58:09.142239Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-07-21T20:58:09.142338Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T20:58:09.142359Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-21T20:58:09.142392Z","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-07-21T20:58:09.143286Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-21T20:58:09.143383Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-07-21T20:58:09.149384Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.746601ms"} {"level":"info","ts":"2026-07-21T20:58:09.157309Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-21T20:58:09.157421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T20:58:09.157464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-21T20:58:09.157488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T20:58:09.1575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-21T20:58:09.157558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-21T20:58:09.168853Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T20:58:09.171575Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T20:58:09.173404Z","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-07-21T20:58:09.177552Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T20:58:09.179505Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T20:58:09.179366Z","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-07-21T20:58:09.179596Z","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-07-21T20:58:09.179649Z","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-07-21T20:58:09.183323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-21T20:58:09.183554Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-21T20:58:09.187605Z","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-07-21T20:58:09.187769Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-21T20:58:09.187828Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-21T20:58:09.187956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T20:58:09.188005Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T20:58:09.558777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T20:58:09.558862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T20:58:09.558922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-21T20:58:09.558945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-21T20:58:09.558963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-21T20:58:09.558981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-21T20:58:09.559003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-21T20:58:09.560302Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:58:09.561206Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T20:58:09.561422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:58:09.562354Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:58:09.563026Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T20:58:09.563064Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T20:58:09.565425Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:58:09.565587Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:58:09.565661Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:58:09.565963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T20:58:09.571965Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"warn","ts":"2026-07-21T20:58:12.885864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.606694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-21T20:58:12.885958Z","caller":"traceutil/trace.go:171","msg":"trace[1600484081] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"513.525246ms","start":"2026-07-21T20:58:12.37242Z","end":"2026-07-21T20:58:12.885945Z","steps":["trace[1600484081] 'read index received' (duration: 229.38356ms)","trace[1600484081] 'applied index is now lower than readState.Index' (duration: 284.140826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:12.886023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.606958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:58:12.886043Z","caller":"traceutil/trace.go:171","msg":"trace[1075738567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"513.67036ms","start":"2026-07-21T20:58:12.372367Z","end":"2026-07-21T20:58:12.886038Z","steps":["trace[1075738567] 'agreement among raft nodes before linearized reading' (duration: 513.612598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:12.886062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:12.372346Z","time spent":"513.711661ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T20:58:12.886275Z","caller":"traceutil/trace.go:171","msg":"trace[915474640] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"517.242338ms","start":"2026-07-21T20:58:12.369007Z","end":"2026-07-21T20:58:12.88625Z","steps":["trace[915474640] 'process raft request' (duration: 232.857755ms)","trace[915474640] 'compare' (duration: 283.484771ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:12.886384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:12.368988Z","time spent":"517.34535ms","remote":"127.0.0.1:56184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:13.322695Z","caller":"traceutil/trace.go:171","msg":"trace[2089609064] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"433.097379ms","start":"2026-07-21T20:58:12.889576Z","end":"2026-07-21T20:58:13.322674Z","steps":["trace[2089609064] 'process raft request' (duration: 432.984336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:13.32289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:12.889563Z","time spent":"433.263443ms","remote":"127.0.0.1:56184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:13.478869Z","caller":"traceutil/trace.go:171","msg":"trace[1498911666] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"100.735396ms","start":"2026-07-21T20:58:13.378091Z","end":"2026-07-21T20:58:13.478827Z","steps":["trace[1498911666] 'process raft request' (duration: 86.897496ms)","trace[1498911666] 'compare' (duration: 13.607305ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:58:51.854444Z","caller":"traceutil/trace.go:171","msg":"trace[1076682572] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"492.463547ms","start":"2026-07-21T20:58:51.361965Z","end":"2026-07-21T20:58:51.854428Z","steps":["trace[1076682572] 'process raft request' (duration: 492.348704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:51.854553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.361944Z","time spent":"492.55736ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:51.877116Z","caller":"traceutil/trace.go:171","msg":"trace[1821421250] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"495.679356ms","start":"2026-07-21T20:58:51.38142Z","end":"2026-07-21T20:58:51.8771Z","steps":["trace[1821421250] 'process raft request' (duration: 495.554483ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:51.87707Z","caller":"traceutil/trace.go:171","msg":"trace[393171358] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"511.384673ms","start":"2026-07-21T20:58:51.365648Z","end":"2026-07-21T20:58:51.877033Z","steps":["trace[393171358] 'process raft request' (duration: 511.217629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:51.877251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.365625Z","time spent":"511.591707ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:51.877255Z","caller":"traceutil/trace.go:171","msg":"trace[972026174] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"472.781454ms","start":"2026-07-21T20:58:51.404451Z","end":"2026-07-21T20:58:51.877233Z","steps":["trace[972026174] 'process raft request' (duration: 472.598259ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:51.877302Z","caller":"traceutil/trace.go:171","msg":"trace[827536553] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"467.611016ms","start":"2026-07-21T20:58:51.40968Z","end":"2026-07-21T20:58:51.877291Z","steps":["trace[827536553] 'process raft request' (duration: 467.405111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:51.87737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.409662Z","time spent":"467.688889ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:51.877436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.404445Z","time spent":"472.920427ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:51.877255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.381399Z","time spent":"495.781259ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:51.877598Z","caller":"traceutil/trace.go:171","msg":"trace[955656983] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"456.996355ms","start":"2026-07-21T20:58:51.420592Z","end":"2026-07-21T20:58:51.877589Z","steps":["trace[955656983] 'process raft request' (duration: 456.563485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:51.877643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.420573Z","time spent":"457.048437ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:51.877653Z","caller":"traceutil/trace.go:171","msg":"trace[497447710] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"473.206734ms","start":"2026-07-21T20:58:51.404378Z","end":"2026-07-21T20:58:51.877584Z","steps":["trace[497447710] 'process raft request' (duration: 472.636ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:51.877714Z","caller":"traceutil/trace.go:171","msg":"trace[867619084] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"445.41278ms","start":"2026-07-21T20:58:51.432293Z","end":"2026-07-21T20:58:51.877706Z","steps":["trace[867619084] 'process raft request' (duration: 444.902398ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:51.877695Z","caller":"traceutil/trace.go:171","msg":"trace[481601040] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"464.719936ms","start":"2026-07-21T20:58:51.412915Z","end":"2026-07-21T20:58:51.877635Z","steps":["trace[481601040] 'process raft request' (duration: 464.203473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:51.877753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.432264Z","time spent":"445.473392ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:51.877845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.404357Z","time spent":"473.417389ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:51.877844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.412898Z","time spent":"464.882209ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:51.877761Z","caller":"traceutil/trace.go:171","msg":"trace[1147263336] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"444.538999ms","start":"2026-07-21T20:58:51.433203Z","end":"2026-07-21T20:58:51.877742Z","steps":["trace[1147263336] 'process raft request' (duration: 444.105058ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:51.877993Z","caller":"traceutil/trace.go:171","msg":"trace[293313019] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"435.780744ms","start":"2026-07-21T20:58:51.442203Z","end":"2026-07-21T20:58:51.877984Z","steps":["trace[293313019] 'process raft request' (duration: 435.168949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:51.877958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.433142Z","time spent":"444.752694ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:51.87805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.442184Z","time spent":"435.836565ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:51.878258Z","caller":"traceutil/trace.go:171","msg":"trace[608461825] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"308.595057ms","start":"2026-07-21T20:58:51.569644Z","end":"2026-07-21T20:58:51.878239Z","steps":["trace[608461825] 'process raft request' (duration: 307.821828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:51.880738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.569611Z","time spent":"311.090849ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:52.351327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.445346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:58:52.351825Z","caller":"traceutil/trace.go:171","msg":"trace[705085760] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:583; }","duration":"326.672372ms","start":"2026-07-21T20:58:52.02514Z","end":"2026-07-21T20:58:52.351812Z","steps":["trace[705085760] 'read index received' (duration: 180.58677ms)","trace[705085760] 'applied index is now lower than readState.Index' (duration: 146.084842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:52.351897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.752504ms","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-07-21T20:58:52.351918Z","caller":"traceutil/trace.go:171","msg":"trace[631018173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"326.804566ms","start":"2026-07-21T20:58:52.025107Z","end":"2026-07-21T20:58:52.351912Z","steps":["trace[631018173] 'agreement among raft nodes before linearized reading' (duration: 326.740934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:52.35194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:52.025093Z","time spent":"326.840566ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T20:58:52.352077Z","caller":"traceutil/trace.go:171","msg":"trace[1768679631] transaction","detail":"{read_only:false; number_of_response:1; response_revision:563; }","duration":"392.621853ms","start":"2026-07-21T20:58:51.959442Z","end":"2026-07-21T20:58:52.352064Z","steps":["trace[1768679631] 'process raft request' (duration: 392.107301ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:52.352094Z","caller":"traceutil/trace.go:171","msg":"trace[865390273] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"387.564949ms","start":"2026-07-21T20:58:51.964522Z","end":"2026-07-21T20:58:52.352087Z","steps":["trace[865390273] 'process raft request' (duration: 387.059466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:52.352132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.964498Z","time spent":"387.615601ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:52.352134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.959434Z","time spent":"392.667684ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7374,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:58:52.352242Z","caller":"traceutil/trace.go:171","msg":"trace[1198802898] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"395.34192ms","start":"2026-07-21T20:58:51.956892Z","end":"2026-07-21T20:58:52.352234Z","steps":["trace[1198802898] 'process raft request' (duration: 248.823968ms)","trace[1198802898] 'compare' (duration: 145.062817ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:58:52.352257Z","caller":"traceutil/trace.go:171","msg":"trace[31878531] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"378.612729ms","start":"2026-07-21T20:58:51.973637Z","end":"2026-07-21T20:58:52.35225Z","steps":["trace[31878531] 'process raft request' (duration: 377.980353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:52.352283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.956869Z","time spent":"395.390831ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:58:52.352297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.973616Z","time spent":"378.659401ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:58:52.352352Z","caller":"traceutil/trace.go:171","msg":"trace[813726610] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"334.084764ms","start":"2026-07-21T20:58:52.01826Z","end":"2026-07-21T20:58:52.352344Z","steps":["trace[813726610] 'process raft request' (duration: 333.458979ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:52.352366Z","caller":"traceutil/trace.go:171","msg":"trace[1208840699] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"393.901175ms","start":"2026-07-21T20:58:51.958458Z","end":"2026-07-21T20:58:52.352359Z","steps":["trace[1208840699] 'process raft request' (duration: 392.964651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:52.352387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:52.018237Z","time spent":"334.129975ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:58:52.352402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.958447Z","time spent":"393.934466ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:58:52.352455Z","caller":"traceutil/trace.go:171","msg":"trace[407147] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"393.845824ms","start":"2026-07-21T20:58:51.958602Z","end":"2026-07-21T20:58:52.352448Z","steps":["trace[407147] 'process raft request' (duration: 392.89492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:52.352485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:51.958595Z","time spent":"393.872094ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:58:58.544842Z","caller":"traceutil/trace.go:171","msg":"trace[255025110] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"110.925268ms","start":"2026-07-21T20:58:58.433894Z","end":"2026-07-21T20:58:58.544819Z","steps":["trace[255025110] 'process raft request' (duration: 107.581975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:59.791199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644375636,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:58:59.796084Z","caller":"traceutil/trace.go:171","msg":"trace[1236193870] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"670.821443ms","start":"2026-07-21T20:58:59.125221Z","end":"2026-07-21T20:58:59.796042Z","steps":["trace[1236193870] 'process raft request' (duration: 669.762457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:59.79626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:59.125192Z","time spent":"670.989308ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:58:59.971083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.241003ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-21T20:58:59.971393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.27479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qgmjn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-21T20:58:59.971437Z","caller":"traceutil/trace.go:171","msg":"trace[882701378] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qgmjn; range_end:; response_count:1; response_revision:646; }","duration":"681.350552ms","start":"2026-07-21T20:58:59.290075Z","end":"2026-07-21T20:58:59.971425Z","steps":["trace[882701378] 'agreement among raft nodes before linearized reading' (duration: 681.200018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:59.971474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:59.290057Z","time spent":"681.408874ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-qgmjn\" "} {"level":"info","ts":"2026-07-21T20:58:59.971243Z","caller":"traceutil/trace.go:171","msg":"trace[1236780112] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"681.058924ms","start":"2026-07-21T20:58:59.290115Z","end":"2026-07-21T20:58:59.971174Z","steps":["trace[1236780112] 'read index received' (duration: 501.774317ms)","trace[1236780112] 'applied index is now lower than readState.Index' (duration: 179.283357ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:59:00.001897Z","caller":"traceutil/trace.go:171","msg":"trace[1528102228] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"151.73032ms","start":"2026-07-21T20:58:59.850136Z","end":"2026-07-21T20:59:00.001867Z","steps":["trace[1528102228] 'process raft request' (duration: 151.535306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:00.604876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.067548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:59:00.604952Z","caller":"traceutil/trace.go:171","msg":"trace[1433176019] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"603.14815ms","start":"2026-07-21T20:59:00.001791Z","end":"2026-07-21T20:59:00.604939Z","steps":["trace[1433176019] 'read index received' (duration: 315.9806ms)","trace[1433176019] 'applied index is now lower than readState.Index' (duration: 287.16667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:00.605114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.068661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T20:59:00.605117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.43594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T20:59:00.605146Z","caller":"traceutil/trace.go:171","msg":"trace[606692281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"619.124423ms","start":"2026-07-21T20:58:59.986013Z","end":"2026-07-21T20:59:00.605138Z","steps":["trace[606692281] 'agreement among raft nodes before linearized reading' (duration: 619.00786ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:00.605154Z","caller":"traceutil/trace.go:171","msg":"trace[415933931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"630.503432ms","start":"2026-07-21T20:58:59.97464Z","end":"2026-07-21T20:59:00.605144Z","steps":["trace[415933931] 'agreement among raft nodes before linearized reading' (duration: 630.397609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:00.605169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:59.985997Z","time spent":"619.165814ms","remote":"127.0.0.1:56086","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-07-21T20:59:00.605176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:59.974626Z","time spent":"630.544913ms","remote":"127.0.0.1:56086","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-07-21T20:59:00.60538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.743753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:59:00.60537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.95612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:59:00.605409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.618647ms","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-07-21T20:59:00.605429Z","caller":"traceutil/trace.go:171","msg":"trace[485907506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"602.056173ms","start":"2026-07-21T20:59:00.003361Z","end":"2026-07-21T20:59:00.605417Z","steps":["trace[485907506] 'agreement among raft nodes before linearized reading' (duration: 601.94472ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:00.605459Z","caller":"traceutil/trace.go:171","msg":"trace[1861018044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"188.70853ms","start":"2026-07-21T20:59:00.416742Z","end":"2026-07-21T20:59:00.60545Z","steps":["trace[1861018044] 'agreement among raft nodes before linearized reading' (duration: 188.592687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:00.605469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:00.003349Z","time spent":"602.114494ms","remote":"127.0.0.1:55862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T20:59:00.60541Z","caller":"traceutil/trace.go:171","msg":"trace[1204506987] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:648; }","duration":"594.800544ms","start":"2026-07-21T20:59:00.010601Z","end":"2026-07-21T20:59:00.605401Z","steps":["trace[1204506987] 'agreement among raft nodes before linearized reading' (duration: 594.760703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:00.605558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:00.010585Z","time spent":"594.963838ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-21T20:59:00.605751Z","caller":"traceutil/trace.go:171","msg":"trace[1260151438] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"617.598555ms","start":"2026-07-21T20:58:59.987504Z","end":"2026-07-21T20:59:00.605103Z","steps":["trace[1260151438] 'process raft request' (duration: 330.23532ms)","trace[1260151438] 'compare' (duration: 286.907524ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:00.60587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:59.987479Z","time spent":"618.297113ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:59:01.438064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.856954ms","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-07-21T20:59:01.438139Z","caller":"traceutil/trace.go:171","msg":"trace[991509389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"441.949756ms","start":"2026-07-21T20:59:00.996171Z","end":"2026-07-21T20:59:01.438121Z","steps":["trace[991509389] 'range keys from in-memory index tree' (duration: 441.763611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:01.438179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:00.996122Z","time spent":"442.046429ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T20:59:01.925669Z","caller":"traceutil/trace.go:171","msg":"trace[2081447295] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"926.435339ms","start":"2026-07-21T20:59:00.999207Z","end":"2026-07-21T20:59:01.925642Z","steps":["trace[2081447295] 'process raft request' (duration: 925.915406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:01.925822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:00.99918Z","time spent":"926.553782ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:59:01.93415Z","caller":"traceutil/trace.go:171","msg":"trace[235768250] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"492.907869ms","start":"2026-07-21T20:59:01.441222Z","end":"2026-07-21T20:59:01.93413Z","steps":["trace[235768250] 'process raft request' (duration: 492.649613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:01.934259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.441202Z","time spent":"492.996072ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:59:15.025886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.218638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:15.025975Z","caller":"traceutil/trace.go:171","msg":"trace[478512416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"116.331151ms","start":"2026-07-21T20:59:14.909626Z","end":"2026-07-21T20:59:15.025957Z","steps":["trace[478512416] 'range keys from in-memory index tree' (duration: 116.083914ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:18.53222Z","caller":"traceutil/trace.go:171","msg":"trace[1899216444] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"447.652916ms","start":"2026-07-21T20:59:18.084544Z","end":"2026-07-21T20:59:18.532197Z","steps":["trace[1899216444] 'read index received' (duration: 447.578384ms)","trace[1899216444] 'applied index is now lower than readState.Index' (duration: 73.522µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:59:18.532352Z","caller":"traceutil/trace.go:171","msg":"trace[2026720770] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"566.55839ms","start":"2026-07-21T20:59:17.965764Z","end":"2026-07-21T20:59:18.532322Z","steps":["trace[2026720770] 'process raft request' (duration: 566.302274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:18.532421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.869342ms","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-07-21T20:59:18.532539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:17.965733Z","time spent":"566.704694ms","remote":"127.0.0.1:55996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:59:18.532524Z","caller":"traceutil/trace.go:171","msg":"trace[1383317443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"448.024095ms","start":"2026-07-21T20:59:18.084488Z","end":"2026-07-21T20:59:18.532512Z","steps":["trace[1383317443] 'agreement among raft nodes before linearized reading' (duration: 447.82099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:18.532603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:18.084459Z","time spent":"448.132398ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T20:59:18.560729Z","caller":"traceutil/trace.go:171","msg":"trace[649356624] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"412.641803ms","start":"2026-07-21T20:59:18.148063Z","end":"2026-07-21T20:59:18.560705Z","steps":["trace[649356624] 'process raft request' (duration: 412.429427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:18.560802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.398286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7dq78\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-21T20:59:18.56088Z","caller":"traceutil/trace.go:171","msg":"trace[2133628744] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7dq78; range_end:; response_count:1; response_revision:816; }","duration":"166.514508ms","start":"2026-07-21T20:59:18.394347Z","end":"2026-07-21T20:59:18.560861Z","steps":["trace[2133628744] 'agreement among raft nodes before linearized reading' (duration: 166.293563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:18.560888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:18.148043Z","time spent":"412.775125ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:59:18.561528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.376377ms","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-07-21T20:59:18.561586Z","caller":"traceutil/trace.go:171","msg":"trace[2117394746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"113.46315ms","start":"2026-07-21T20:59:18.448108Z","end":"2026-07-21T20:59:18.561571Z","steps":["trace[2117394746] 'agreement among raft nodes before linearized reading' (duration: 113.0746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:38.892544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.354107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:59:38.892648Z","caller":"traceutil/trace.go:171","msg":"trace[1506424957] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:978; }","duration":"141.544652ms","start":"2026-07-21T20:59:38.75108Z","end":"2026-07-21T20:59:38.892625Z","steps":["trace[1506424957] 'count revisions from in-memory index tree' (duration: 141.279676ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:24.251413Z","caller":"traceutil/trace.go:171","msg":"trace[1004119581] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"137.333257ms","start":"2026-07-21T21:00:24.114054Z","end":"2026-07-21T21:00:24.251387Z","steps":["trace[1004119581] 'process raft request' (duration: 137.189533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:25.410091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644376756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:25.910585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644376756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:26.036474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716995802s","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-07-21T21:00:26.036577Z","caller":"traceutil/trace.go:171","msg":"trace[1679131617] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1131; }","duration":"1.717149736s","start":"2026-07-21T21:00:24.319404Z","end":"2026-07-21T21:00:26.036554Z","steps":["trace[1679131617] 'count revisions from in-memory index tree' (duration: 1.716868609s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:26.036636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:24.319382Z","time spent":"1.717236418s","remote":"127.0.0.1:56792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:26.036788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657321963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T21:00:26.03686Z","caller":"traceutil/trace.go:171","msg":"trace[1366397500] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:1131; }","duration":"1.657433096s","start":"2026-07-21T21:00:24.379407Z","end":"2026-07-21T21:00:26.03684Z","steps":["trace[1366397500] 'range keys from in-memory index tree' (duration: 1.657155429s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:26.036914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:24.37939Z","time spent":"1.657499017s","remote":"127.0.0.1:55872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-07-21T21:00:26.411492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644376756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:26.910221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000321743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-21T21:00:26.91034Z","caller":"traceutil/trace.go:171","msg":"trace[960606959] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000483647s","start":"2026-07-21T21:00:24.909835Z","end":"2026-07-21T21:00:26.910318Z","steps":["trace[960606959] 'agreement among raft nodes before linearized reading' (duration: 2.000344263s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:26.91042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:24.909821Z","time spent":"2.00058117s","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/21 21:00:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-21T21:00:26.912361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644376756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:27.097326Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.396009267s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T21:00:27.097657Z","caller":"traceutil/trace.go:171","msg":"trace[1971041956] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"2.396452588s","start":"2026-07-21T21:00:24.701184Z","end":"2026-07-21T21:00:27.097637Z","steps":["trace[1971041956] 'process raft request' (duration: 2.396335975s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.097783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:24.701163Z","time spent":"2.39655642s","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:27.124576Z","caller":"traceutil/trace.go:171","msg":"trace[1582086164] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1176; }","duration":"2.214684871s","start":"2026-07-21T21:00:24.909871Z","end":"2026-07-21T21:00:27.124556Z","steps":["trace[1582086164] 'read index received' (duration: 2.187657947s)","trace[1582086164] 'applied index is now lower than readState.Index' (duration: 27.026164ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:27.124642Z","caller":"traceutil/trace.go:171","msg":"trace[2040635736] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"867.876406ms","start":"2026-07-21T21:00:26.256736Z","end":"2026-07-21T21:00:27.124612Z","steps":["trace[2040635736] 'process raft request' (duration: 867.751273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.124645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:26.038522Z","time spent":"1.086115222s","remote":"127.0.0.1:55872","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T21:00:27.124776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:26.256713Z","time spent":"867.989079ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:27.124789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.17974259s","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-07-21T21:00:27.124817Z","caller":"traceutil/trace.go:171","msg":"trace[2130790716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1133; }","duration":"2.179817052s","start":"2026-07-21T21:00:24.944991Z","end":"2026-07-21T21:00:27.124808Z","steps":["trace[2130790716] 'agreement among raft nodes before linearized reading' (duration: 2.179671928s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.124805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011792047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T21:00:27.124842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:24.94498Z","time spent":"2.179856842s","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T21:00:27.124854Z","caller":"traceutil/trace.go:171","msg":"trace[1628498674] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1133; }","duration":"1.01189031s","start":"2026-07-21T21:00:26.112948Z","end":"2026-07-21T21:00:27.124839Z","steps":["trace[1628498674] 'agreement among raft nodes before linearized reading' (duration: 1.011786777s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.124901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:26.11293Z","time spent":"1.011950711s","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:27.124999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.43362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:27.125029Z","caller":"traceutil/trace.go:171","msg":"trace[1396241674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1133; }","duration":"214.501032ms","start":"2026-07-21T21:00:26.91052Z","end":"2026-07-21T21:00:27.125021Z","steps":["trace[1396241674] 'agreement among raft nodes before linearized reading' (duration: 214.444531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.12508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.528439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:27.125112Z","caller":"traceutil/trace.go:171","msg":"trace[1947830547] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1133; }","duration":"617.597211ms","start":"2026-07-21T21:00:26.507505Z","end":"2026-07-21T21:00:27.125102Z","steps":["trace[1947830547] 'agreement among raft nodes before linearized reading' (duration: 617.54311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.125142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:26.507484Z","time spent":"617.647312ms","remote":"127.0.0.1:56116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:27.125245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968082298s","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-07-21T21:00:27.125282Z","caller":"traceutil/trace.go:171","msg":"trace[354787858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1133; }","duration":"1.96814522s","start":"2026-07-21T21:00:25.157125Z","end":"2026-07-21T21:00:27.12527Z","steps":["trace[354787858] 'agreement among raft nodes before linearized reading' (duration: 1.968019467s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.12531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:25.157109Z","time spent":"1.968193571s","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T21:00:27.125427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656212987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T21:00:27.125455Z","caller":"traceutil/trace.go:171","msg":"trace[2028692924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1133; }","duration":"1.656271358s","start":"2026-07-21T21:00:25.469175Z","end":"2026-07-21T21:00:27.125447Z","steps":["trace[2028692924] 'agreement among raft nodes before linearized reading' (duration: 1.656195996s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.125485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:25.469161Z","time spent":"1.656317239s","remote":"127.0.0.1:56086","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-07-21T21:00:27.125613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778276793s","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-07-21T21:00:27.125639Z","caller":"traceutil/trace.go:171","msg":"trace[2116903904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1133; }","duration":"1.778303953s","start":"2026-07-21T21:00:25.347327Z","end":"2026-07-21T21:00:27.125631Z","steps":["trace[2116903904] 'agreement among raft nodes before linearized reading' (duration: 1.778201281s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.125659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:25.347282Z","time spent":"1.778370825s","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T21:00:27.126315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.176398096s","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-07-21T21:00:27.126368Z","caller":"traceutil/trace.go:171","msg":"trace[488035936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1133; }","duration":"2.176470568s","start":"2026-07-21T21:00:24.949887Z","end":"2026-07-21T21:00:27.126358Z","steps":["trace[488035936] 'agreement among raft nodes before linearized reading' (duration: 2.175357531s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.126403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:24.949877Z","time spent":"2.176520629s","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T21:00:27.657545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.188424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:00:27.657634Z","caller":"traceutil/trace.go:171","msg":"trace[977262770] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"530.98524ms","start":"2026-07-21T21:00:27.126634Z","end":"2026-07-21T21:00:27.65762Z","steps":["trace[977262770] 'process raft request' (duration: 401.649532ms)","trace[977262770] 'compare' (duration: 128.723802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:27.657676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.126619Z","time spent":"531.039281ms","remote":"127.0.0.1:55872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:27.671207Z","caller":"traceutil/trace.go:171","msg":"trace[1405403760] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"540.975609ms","start":"2026-07-21T21:00:27.1302Z","end":"2026-07-21T21:00:27.671175Z","steps":["trace[1405403760] 'process raft request' (duration: 540.821475ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:27.671253Z","caller":"traceutil/trace.go:171","msg":"trace[1578610620] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"540.923508ms","start":"2026-07-21T21:00:27.130287Z","end":"2026-07-21T21:00:27.67121Z","steps":["trace[1578610620] 'process raft request' (duration: 540.838426ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:27.67155Z","caller":"traceutil/trace.go:171","msg":"trace[1243784322] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"541.148873ms","start":"2026-07-21T21:00:27.13039Z","end":"2026-07-21T21:00:27.671539Z","steps":["trace[1243784322] 'process raft request' (duration: 540.950908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.67166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.13038Z","time spent":"541.208284ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:27.671379Z","caller":"traceutil/trace.go:171","msg":"trace[87886333] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"541.02817ms","start":"2026-07-21T21:00:27.13034Z","end":"2026-07-21T21:00:27.671368Z","steps":["trace[87886333] 'process raft request' (duration: 540.817125ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:27.671689Z","caller":"traceutil/trace.go:171","msg":"trace[323966984] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1179; }","duration":"539.390489ms","start":"2026-07-21T21:00:27.132276Z","end":"2026-07-21T21:00:27.671666Z","steps":["trace[323966984] 'read index received' (duration: 395.998441ms)","trace[323966984] 'applied index is now lower than readState.Index' (duration: 143.390858ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:27.671761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.131237Z","time spent":"540.521628ms","remote":"127.0.0.1:55914","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-21T21:00:27.671793Z","caller":"traceutil/trace.go:171","msg":"trace[576196066] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"540.848426ms","start":"2026-07-21T21:00:27.130921Z","end":"2026-07-21T21:00:27.67177Z","steps":["trace[576196066] 'process raft request' (duration: 540.578439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.671823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.538403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T21:00:27.671749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.130334Z","time spent":"541.377869ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:27.671886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.130912Z","time spent":"540.924707ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:27.671363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.130183Z","time spent":"541.098442ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:27.671857Z","caller":"traceutil/trace.go:171","msg":"trace[280344450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"539.604415ms","start":"2026-07-21T21:00:27.132242Z","end":"2026-07-21T21:00:27.671847Z","steps":["trace[280344450] 'agreement among raft nodes before linearized reading' (duration: 539.540143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:27.672014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.132229Z","time spent":"539.772949ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T21:00:27.672188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:27.13028Z","time spent":"541.286587ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:49.450878Z","caller":"traceutil/trace.go:171","msg":"trace[44274758] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"239.270261ms","start":"2026-07-21T21:00:49.211584Z","end":"2026-07-21T21:00:49.450855Z","steps":["trace[44274758] 'process raft request' (duration: 239.105637ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:49.899315Z","caller":"traceutil/trace.go:171","msg":"trace[1049075758] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"113.355788ms","start":"2026-07-21T21:00:49.785933Z","end":"2026-07-21T21:00:49.899289Z","steps":["trace[1049075758] 'read index received' (duration: 113.160883ms)","trace[1049075758] 'applied index is now lower than readState.Index' (duration: 193.255µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:49.899549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.601765ms","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-07-21T21:00:49.899601Z","caller":"traceutil/trace.go:171","msg":"trace[1347271748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"113.717927ms","start":"2026-07-21T21:00:49.78586Z","end":"2026-07-21T21:00:49.899578Z","steps":["trace[1347271748] 'agreement among raft nodes before linearized reading' (duration: 113.557834ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:49.899411Z","caller":"traceutil/trace.go:171","msg":"trace[1851698820] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"118.447096ms","start":"2026-07-21T21:00:49.780911Z","end":"2026-07-21T21:00:49.899358Z","steps":["trace[1851698820] 'process raft request' (duration: 118.146859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.400158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644376948,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T21:00:50.690619Z","caller":"traceutil/trace.go:171","msg":"trace[1083218115] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1259; }","duration":"791.213222ms","start":"2026-07-21T21:00:49.899388Z","end":"2026-07-21T21:00:50.690601Z","steps":["trace[1083218115] 'read index received' (duration: 704.476768ms)","trace[1083218115] 'applied index is now lower than readState.Index' (duration: 86.735314ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:50.690787Z","caller":"traceutil/trace.go:171","msg":"trace[989388666] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"344.034855ms","start":"2026-07-21T21:00:50.346665Z","end":"2026-07-21T21:00:50.690699Z","steps":["trace[989388666] 'process raft request' (duration: 343.894681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.691241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.774402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:50.691314Z","caller":"traceutil/trace.go:171","msg":"trace[2144599510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"780.868925ms","start":"2026-07-21T21:00:49.910427Z","end":"2026-07-21T21:00:50.691296Z","steps":["trace[2144599510] 'agreement among raft nodes before linearized reading' (duration: 780.509676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.691365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:49.910414Z","time spent":"780.940996ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T21:00:50.690905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.375827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T21:00:50.691425Z","caller":"traceutil/trace.go:171","msg":"trace[342380281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"898.946581ms","start":"2026-07-21T21:00:49.792463Z","end":"2026-07-21T21:00:50.691409Z","steps":["trace[342380281] 'agreement among raft nodes before linearized reading' (duration: 898.284575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.691509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:49.792442Z","time spent":"899.054924ms","remote":"127.0.0.1:56086","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-07-21T21:00:50.691011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.321605ms","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-07-21T21:00:50.691787Z","caller":"traceutil/trace.go:171","msg":"trace[1557345062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"905.050133ms","start":"2026-07-21T21:00:49.786647Z","end":"2026-07-21T21:00:50.691697Z","steps":["trace[1557345062] 'agreement among raft nodes before linearized reading' (duration: 904.269793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.691956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:49.786633Z","time spent":"905.206957ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T21:00:50.691125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.072511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:50.692075Z","caller":"traceutil/trace.go:171","msg":"trace[1401727497] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1210; }","duration":"542.053856ms","start":"2026-07-21T21:00:50.150004Z","end":"2026-07-21T21:00:50.692057Z","steps":["trace[1401727497] 'agreement among raft nodes before linearized reading' (duration: 541.03246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.692143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:50.149989Z","time spent":"542.133107ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:00:50.690906Z","caller":"traceutil/trace.go:171","msg":"trace[1157436371] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"785.848239ms","start":"2026-07-21T21:00:49.905032Z","end":"2026-07-21T21:00:50.69088Z","steps":["trace[1157436371] 'process raft request' (duration: 785.414538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.692249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:50.346643Z","time spent":"344.703241ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:50.692403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:49.905009Z","time spent":"787.323446ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:50.691209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.683834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T21:00:50.692538Z","caller":"traceutil/trace.go:171","msg":"trace[1567889630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"900.056748ms","start":"2026-07-21T21:00:49.792465Z","end":"2026-07-21T21:00:50.692521Z","steps":["trace[1567889630] 'agreement among raft nodes before linearized reading' (duration: 898.51749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:50.692596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:49.792451Z","time spent":"900.129831ms","remote":"127.0.0.1:56086","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-07-21T21:00:53.041593Z","caller":"traceutil/trace.go:171","msg":"trace[1982430434] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"258.368096ms","start":"2026-07-21T21:00:52.783195Z","end":"2026-07-21T21:00:53.041563Z","steps":["trace[1982430434] 'process raft request' (duration: 258.193252ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:53.050506Z","caller":"traceutil/trace.go:171","msg":"trace[2061661841] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1269; }","duration":"140.853644ms","start":"2026-07-21T21:00:52.909638Z","end":"2026-07-21T21:00:53.050491Z","steps":["trace[2061661841] 'read index received' (duration: 131.720266ms)","trace[2061661841] 'applied index is now lower than readState.Index' (duration: 9.132178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:53.050596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.954646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:53.050567Z","caller":"traceutil/trace.go:171","msg":"trace[555373246] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"267.202747ms","start":"2026-07-21T21:00:52.783336Z","end":"2026-07-21T21:00:53.050538Z","steps":["trace[555373246] 'process raft request' (duration: 267.090824ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:53.050629Z","caller":"traceutil/trace.go:171","msg":"trace[1417947190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"141.04985ms","start":"2026-07-21T21:00:52.90957Z","end":"2026-07-21T21:00:53.050619Z","steps":["trace[1417947190] 'agreement among raft nodes before linearized reading' (duration: 140.985798ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:53.717542Z","caller":"traceutil/trace.go:171","msg":"trace[1533669735] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"161.610443ms","start":"2026-07-21T21:00:53.555907Z","end":"2026-07-21T21:00:53.717517Z","steps":["trace[1533669735] 'process raft request' (duration: 161.458539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:10.408248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644377205,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T21:01:10.44414Z","caller":"traceutil/trace.go:171","msg":"trace[899158075] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"622.044422ms","start":"2026-07-21T21:01:09.822061Z","end":"2026-07-21T21:01:10.444105Z","steps":["trace[899158075] 'process raft request' (duration: 621.840876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:10.444328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:09.822038Z","time spent":"622.189086ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:10.587982Z","caller":"traceutil/trace.go:171","msg":"trace[77500752] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1380; }","duration":"679.973577ms","start":"2026-07-21T21:01:09.907983Z","end":"2026-07-21T21:01:10.587957Z","steps":["trace[77500752] 'read index received' (duration: 535.949253ms)","trace[77500752] 'applied index is now lower than readState.Index' (duration: 144.022874ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:10.588173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.161042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:10.588238Z","caller":"traceutil/trace.go:171","msg":"trace[1348860908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"680.275505ms","start":"2026-07-21T21:01:09.907946Z","end":"2026-07-21T21:01:10.588221Z","steps":["trace[1348860908] 'agreement among raft nodes before linearized reading' (duration: 680.143961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:10.588253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.710923ms","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-07-21T21:01:10.588276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:09.90793Z","time spent":"680.339986ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T21:01:10.588291Z","caller":"traceutil/trace.go:171","msg":"trace[1984198712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"526.789915ms","start":"2026-07-21T21:01:10.061488Z","end":"2026-07-21T21:01:10.588278Z","steps":["trace[1984198712] 'agreement among raft nodes before linearized reading' (duration: 526.639811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:10.588327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:10.061471Z","time spent":"526.848786ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T21:01:10.927807Z","caller":"traceutil/trace.go:171","msg":"trace[1069587312] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"116.378013ms","start":"2026-07-21T21:01:10.811401Z","end":"2026-07-21T21:01:10.927779Z","steps":["trace[1069587312] 'read index received' (duration: 116.185708ms)","trace[1069587312] 'applied index is now lower than readState.Index' (duration: 188.155µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:10.928224Z","caller":"traceutil/trace.go:171","msg":"trace[2107409749] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"335.83784ms","start":"2026-07-21T21:01:10.592361Z","end":"2026-07-21T21:01:10.928199Z","steps":["trace[2107409749] 'process raft request' (duration: 335.233015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:10.92833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:10.592338Z","time spent":"335.925142ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:01:10.928542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.146243ms","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-07-21T21:01:10.928587Z","caller":"traceutil/trace.go:171","msg":"trace[2048770918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"117.228325ms","start":"2026-07-21T21:01:10.81135Z","end":"2026-07-21T21:01:10.928578Z","steps":["trace[2048770918] 'agreement among raft nodes before linearized reading' (duration: 117.110662ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:27.424793Z","caller":"traceutil/trace.go:171","msg":"trace[1593311219] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1446; }","duration":"345.401828ms","start":"2026-07-21T21:01:27.079361Z","end":"2026-07-21T21:01:27.424763Z","steps":["trace[1593311219] 'read index received' (duration: 345.10357ms)","trace[1593311219] 'applied index is now lower than readState.Index' (duration: 296.978µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:27.425116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.731516ms","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-07-21T21:01:27.425135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.353143ms","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-07-21T21:01:27.425175Z","caller":"traceutil/trace.go:171","msg":"trace[2048266537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1387; }","duration":"345.845419ms","start":"2026-07-21T21:01:27.079311Z","end":"2026-07-21T21:01:27.425157Z","steps":["trace[2048266537] 'agreement among raft nodes before linearized reading' (duration: 345.639383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:27.42516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.099982ms","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-07-21T21:01:27.425195Z","caller":"traceutil/trace.go:171","msg":"trace[1954702230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1387; }","duration":"332.452856ms","start":"2026-07-21T21:01:27.092725Z","end":"2026-07-21T21:01:27.425178Z","steps":["trace[1954702230] 'agreement among raft nodes before linearized reading' (duration: 332.283492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:27.425223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:27.079292Z","time spent":"345.91924ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T21:01:27.425227Z","caller":"traceutil/trace.go:171","msg":"trace[719539383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1387; }","duration":"187.204875ms","start":"2026-07-21T21:01:27.238007Z","end":"2026-07-21T21:01:27.425211Z","steps":["trace[719539383] 'agreement among raft nodes before linearized reading' (duration: 187.033271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:27.425238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:27.092707Z","time spent":"332.521647ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T21:01:27.425428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.476566ms","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-07-21T21:01:27.425471Z","caller":"traceutil/trace.go:171","msg":"trace[1314322060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1387; }","duration":"188.551258ms","start":"2026-07-21T21:01:27.236906Z","end":"2026-07-21T21:01:27.425458Z","steps":["trace[1314322060] 'agreement among raft nodes before linearized reading' (duration: 188.434745ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:27.425583Z","caller":"traceutil/trace.go:171","msg":"trace[942859697] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"406.098256ms","start":"2026-07-21T21:01:27.019451Z","end":"2026-07-21T21:01:27.425549Z","steps":["trace[942859697] 'process raft request' (duration: 405.128992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:27.425726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:27.019439Z","time spent":"406.21392ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:27.601604Z","caller":"traceutil/trace.go:171","msg":"trace[89494707] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"170.406937ms","start":"2026-07-21T21:01:27.431177Z","end":"2026-07-21T21:01:27.601584Z","steps":["trace[89494707] 'process raft request' (duration: 170.341205ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:27.601805Z","caller":"traceutil/trace.go:171","msg":"trace[513715443] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"170.599212ms","start":"2026-07-21T21:01:27.431184Z","end":"2026-07-21T21:01:27.601783Z","steps":["trace[513715443] 'process raft request' (duration: 170.367136ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:27.601898Z","caller":"traceutil/trace.go:171","msg":"trace[15608125] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"171.710509ms","start":"2026-07-21T21:01:27.430177Z","end":"2026-07-21T21:01:27.601887Z","steps":["trace[15608125] 'process raft request' (duration: 96.579431ms)","trace[15608125] 'compare' (duration: 74.642396ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:27.602101Z","caller":"traceutil/trace.go:171","msg":"trace[1513836298] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"170.880248ms","start":"2026-07-21T21:01:27.431211Z","end":"2026-07-21T21:01:27.602092Z","steps":["trace[1513836298] 'process raft request' (duration: 170.511009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:28.617424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.674605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:28.617512Z","caller":"traceutil/trace.go:171","msg":"trace[909181893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1391; }","duration":"707.817907ms","start":"2026-07-21T21:01:27.909675Z","end":"2026-07-21T21:01:28.617493Z","steps":["trace[909181893] 'range keys from in-memory index tree' (duration: 707.587232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:28.617557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:27.90965Z","time spent":"707.8982ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T21:01:28.617614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.296401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T21:01:28.617673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.385125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:01:28.617713Z","caller":"traceutil/trace.go:171","msg":"trace[1299831722] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1391; }","duration":"914.456165ms","start":"2026-07-21T21:01:27.703231Z","end":"2026-07-21T21:01:28.617687Z","steps":["trace[1299831722] 'count revisions from in-memory index tree' (duration: 914.175539ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:28.617741Z","caller":"traceutil/trace.go:171","msg":"trace[698810765] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"563.834648ms","start":"2026-07-21T21:01:28.053894Z","end":"2026-07-21T21:01:28.617729Z","steps":["trace[698810765] 'process raft request' (duration: 348.343461ms)","trace[698810765] 'compare' (duration: 215.21655ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:28.617775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:28.053854Z","time spent":"563.90801ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:01:28.617773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:27.703204Z","time spent":"914.551848ms","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:01:28.873358Z","caller":"traceutil/trace.go:171","msg":"trace[1253695738] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1452; }","duration":"253.635536ms","start":"2026-07-21T21:01:28.619696Z","end":"2026-07-21T21:01:28.873331Z","steps":["trace[1253695738] 'read index received' (duration: 239.606387ms)","trace[1253695738] 'applied index is now lower than readState.Index' (duration: 14.027739ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:28.873418Z","caller":"traceutil/trace.go:171","msg":"trace[1995710796] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"377.497475ms","start":"2026-07-21T21:01:28.495878Z","end":"2026-07-21T21:01:28.873375Z","steps":["trace[1995710796] 'process raft request' (duration: 363.485707ms)","trace[1995710796] 'compare' (duration: 13.799683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:28.873505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.769649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:28.873541Z","caller":"traceutil/trace.go:171","msg":"trace[1491365129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"253.865872ms","start":"2026-07-21T21:01:28.619665Z","end":"2026-07-21T21:01:28.873531Z","steps":["trace[1491365129] 'agreement among raft nodes before linearized reading' (duration: 253.763089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:28.873595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:28.495843Z","time spent":"377.659099ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:29.53902Z","caller":"traceutil/trace.go:171","msg":"trace[119446123] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1455; }","duration":"109.177285ms","start":"2026-07-21T21:01:29.429813Z","end":"2026-07-21T21:01:29.538991Z","steps":["trace[119446123] 'read index received' (duration: 30.96326ms)","trace[119446123] 'applied index is now lower than readState.Index' (duration: 78.212005ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:29.539276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.442741ms","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-07-21T21:01:29.539379Z","caller":"traceutil/trace.go:171","msg":"trace[643814242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1394; }","duration":"109.553903ms","start":"2026-07-21T21:01:29.429776Z","end":"2026-07-21T21:01:29.53933Z","steps":["trace[643814242] 'agreement among raft nodes before linearized reading' (duration: 109.321317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:30.119214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.244748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:01:30.119395Z","caller":"traceutil/trace.go:171","msg":"trace[2106785270] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1458; }","duration":"211.107208ms","start":"2026-07-21T21:01:29.908275Z","end":"2026-07-21T21:01:30.119382Z","steps":["trace[2106785270] 'read index received' (duration: 36.991µs)","trace[2106785270] 'applied index is now lower than readState.Index' (duration: 211.069427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:30.119463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.19347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:30.119482Z","caller":"traceutil/trace.go:171","msg":"trace[600673063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1399; }","duration":"211.243612ms","start":"2026-07-21T21:01:29.908232Z","end":"2026-07-21T21:01:30.119475Z","steps":["trace[600673063] 'agreement among raft nodes before linearized reading' (duration: 211.18697ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:30.119521Z","caller":"traceutil/trace.go:171","msg":"trace[960436549] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"509.317363ms","start":"2026-07-21T21:01:29.610162Z","end":"2026-07-21T21:01:30.11948Z","steps":["trace[960436549] 'process raft request' (duration: 90.758697ms)","trace[960436549] 'compare' (duration: 417.580211ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:30.119623Z","caller":"traceutil/trace.go:171","msg":"trace[1589440995] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"508.580644ms","start":"2026-07-21T21:01:29.611033Z","end":"2026-07-21T21:01:30.119614Z","steps":["trace[1589440995] 'process raft request' (duration: 508.255396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:30.119672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:29.61101Z","time spent":"508.635906ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:30.11979Z","caller":"traceutil/trace.go:171","msg":"trace[940834901] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"506.639606ms","start":"2026-07-21T21:01:29.613144Z","end":"2026-07-21T21:01:30.119784Z","steps":["trace[940834901] 'process raft request' (duration: 506.207545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:30.119827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:29.613134Z","time spent":"506.674207ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:01:30.119876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:29.610144Z","time spent":"509.448105ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:30.95374Z","caller":"traceutil/trace.go:171","msg":"trace[260371270] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"296.862361ms","start":"2026-07-21T21:01:30.656855Z","end":"2026-07-21T21:01:30.953717Z","steps":["trace[260371270] 'read index received' (duration: 296.791939ms)","trace[260371270] 'applied index is now lower than readState.Index' (duration: 69.301µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:30.953843Z","caller":"traceutil/trace.go:171","msg":"trace[945385337] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"374.800588ms","start":"2026-07-21T21:01:30.579033Z","end":"2026-07-21T21:01:30.953834Z","steps":["trace[945385337] 'process raft request' (duration: 374.561832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:30.953936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:30.57901Z","time spent":"374.85785ms","remote":"127.0.0.1:55914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T21:01:30.953997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.124517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:30.954049Z","caller":"traceutil/trace.go:171","msg":"trace[1245448376] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1400; }","duration":"297.205519ms","start":"2026-07-21T21:01:30.656828Z","end":"2026-07-21T21:01:30.954034Z","steps":["trace[1245448376] 'agreement among raft nodes before linearized reading' (duration: 297.086096ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:31.097887Z","caller":"traceutil/trace.go:171","msg":"trace[1309220188] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"219.99708ms","start":"2026-07-21T21:01:30.877684Z","end":"2026-07-21T21:01:31.097682Z","steps":["trace[1309220188] 'process raft request' (duration: 129.359736ms)","trace[1309220188] 'compare' (duration: 90.47365ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:31.098003Z","caller":"traceutil/trace.go:171","msg":"trace[1604622591] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"144.192084ms","start":"2026-07-21T21:01:30.953805Z","end":"2026-07-21T21:01:31.097997Z","steps":["trace[1604622591] 'read index received' (duration: 53.186652ms)","trace[1604622591] 'applied index is now lower than readState.Index' (duration: 91.004582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:31.098065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.267793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:31.098086Z","caller":"traceutil/trace.go:171","msg":"trace[1899350765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1403; }","duration":"392.354355ms","start":"2026-07-21T21:01:30.705726Z","end":"2026-07-21T21:01:31.098081Z","steps":["trace[1899350765] 'agreement among raft nodes before linearized reading' (duration: 392.291983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:31.098101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:30.705698Z","time spent":"392.398146ms","remote":"127.0.0.1:36886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:01:31.098105Z","caller":"traceutil/trace.go:171","msg":"trace[1489236006] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"140.076293ms","start":"2026-07-21T21:01:30.958014Z","end":"2026-07-21T21:01:31.098091Z","steps":["trace[1489236006] 'process raft request' (duration: 139.624362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:31.098252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.998569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:31.098352Z","caller":"traceutil/trace.go:171","msg":"trace[184818074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1403; }","duration":"189.154243ms","start":"2026-07-21T21:01:30.909178Z","end":"2026-07-21T21:01:31.098332Z","steps":["trace[184818074] 'agreement among raft nodes before linearized reading' (duration: 188.982499ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:31.300137Z","caller":"traceutil/trace.go:171","msg":"trace[1509664601] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"197.448779ms","start":"2026-07-21T21:01:31.10264Z","end":"2026-07-21T21:01:31.300089Z","steps":["trace[1509664601] 'process raft request' (duration: 100.229272ms)","trace[1509664601] 'compare' (duration: 96.994082ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:31.300218Z","caller":"traceutil/trace.go:171","msg":"trace[1218800429] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"105.36115ms","start":"2026-07-21T21:01:31.194839Z","end":"2026-07-21T21:01:31.3002Z","steps":["trace[1218800429] 'process raft request' (duration: 105.229837ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:31.300782Z","caller":"traceutil/trace.go:171","msg":"trace[1698310884] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"123.264944ms","start":"2026-07-21T21:01:31.176866Z","end":"2026-07-21T21:01:31.300131Z","steps":["trace[1698310884] 'process raft request' (duration: 123.140951ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:31.981391Z","caller":"traceutil/trace.go:171","msg":"trace[806568339] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"346.444423ms","start":"2026-07-21T21:01:31.634921Z","end":"2026-07-21T21:01:31.981366Z","steps":["trace[806568339] 'read index received' (duration: 346.293829ms)","trace[806568339] 'applied index is now lower than readState.Index' (duration: 149.874µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:31.981556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.618667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T21:01:31.981583Z","caller":"traceutil/trace.go:171","msg":"trace[806487054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1408; }","duration":"346.699399ms","start":"2026-07-21T21:01:31.634877Z","end":"2026-07-21T21:01:31.981577Z","steps":["trace[806487054] 'agreement among raft nodes before linearized reading' (duration: 346.572046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:31.981613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:31.634855Z","time spent":"346.748431ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T21:01:31.981652Z","caller":"traceutil/trace.go:171","msg":"trace[503792217] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"371.475446ms","start":"2026-07-21T21:01:31.610152Z","end":"2026-07-21T21:01:31.981628Z","steps":["trace[503792217] 'process raft request' (duration: 371.113257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:31.981758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:31.610131Z","time spent":"371.568188ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:32.269665Z","caller":"traceutil/trace.go:171","msg":"trace[786360317] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1470; }","duration":"146.031511ms","start":"2026-07-21T21:01:32.12361Z","end":"2026-07-21T21:01:32.269641Z","steps":["trace[786360317] 'read index received' (duration: 145.820016ms)","trace[786360317] 'applied index is now lower than readState.Index' (duration: 210.515µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:32.269943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.314468ms","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-07-21T21:01:32.27Z","caller":"traceutil/trace.go:171","msg":"trace[864336706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1409; }","duration":"146.419091ms","start":"2026-07-21T21:01:32.123568Z","end":"2026-07-21T21:01:32.269987Z","steps":["trace[864336706] 'agreement among raft nodes before linearized reading' (duration: 146.240566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:32.270071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.41033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T21:01:32.270088Z","caller":"traceutil/trace.go:171","msg":"trace[1233327937] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"283.803616ms","start":"2026-07-21T21:01:31.986246Z","end":"2026-07-21T21:01:32.270049Z","steps":["trace[1233327937] 'process raft request' (duration: 283.270403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:32.270146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.569689ms","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-07-21T21:01:32.270116Z","caller":"traceutil/trace.go:171","msg":"trace[389150738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1409; }","duration":"146.530334ms","start":"2026-07-21T21:01:32.123575Z","end":"2026-07-21T21:01:32.270105Z","steps":["trace[389150738] 'agreement among raft nodes before linearized reading' (duration: 146.39054ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:32.270237Z","caller":"traceutil/trace.go:171","msg":"trace[1518975582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1409; }","duration":"145.696552ms","start":"2026-07-21T21:01:32.124524Z","end":"2026-07-21T21:01:32.27022Z","steps":["trace[1518975582] 'agreement among raft nodes before linearized reading' (duration: 145.454996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:32.494105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.798044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:01:32.494365Z","caller":"traceutil/trace.go:171","msg":"trace[1681637957] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"219.894587ms","start":"2026-07-21T21:01:32.274449Z","end":"2026-07-21T21:01:32.494344Z","steps":["trace[1681637957] 'process raft request' (duration: 219.841815ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:32.4944Z","caller":"traceutil/trace.go:171","msg":"trace[1999145350] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"220.01482ms","start":"2026-07-21T21:01:32.274368Z","end":"2026-07-21T21:01:32.494383Z","steps":["trace[1999145350] 'process raft request' (duration: 219.852036ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:32.494527Z","caller":"traceutil/trace.go:171","msg":"trace[608503037] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"220.205055ms","start":"2026-07-21T21:01:32.274303Z","end":"2026-07-21T21:01:32.494508Z","steps":["trace[608503037] 'process raft request' (duration: 71.915118ms)","trace[608503037] 'compare' (duration: 147.654781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:42.222513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644377565,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T21:01:42.426191Z","caller":"traceutil/trace.go:171","msg":"trace[80618508] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"704.529767ms","start":"2026-07-21T21:01:41.721642Z","end":"2026-07-21T21:01:42.426172Z","steps":["trace[80618508] 'read index received' (duration: 704.333462ms)","trace[80618508] 'applied index is now lower than readState.Index' (duration: 195.505µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:42.426255Z","caller":"traceutil/trace.go:171","msg":"trace[1681704035] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"749.3101ms","start":"2026-07-21T21:01:41.676912Z","end":"2026-07-21T21:01:42.426222Z","steps":["trace[1681704035] 'process raft request' (duration: 749.137415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:42.426378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.737591ms","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-07-21T21:01:42.426406Z","caller":"traceutil/trace.go:171","msg":"trace[929296647] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1489; }","duration":"704.824803ms","start":"2026-07-21T21:01:41.721572Z","end":"2026-07-21T21:01:42.426397Z","steps":["trace[929296647] 'agreement among raft nodes before linearized reading' (duration: 704.71509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:42.42641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:41.676891Z","time spent":"749.439782ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:01:42.426462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.958738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:01:42.426482Z","caller":"traceutil/trace.go:171","msg":"trace[1769945219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1489; }","duration":"516.005279ms","start":"2026-07-21T21:01:41.91047Z","end":"2026-07-21T21:01:42.426475Z","steps":["trace[1769945219] 'agreement among raft nodes before linearized reading' (duration: 515.980238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:42.426496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:41.910451Z","time spent":"516.042191ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T21:01:42.426579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.100467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T21:01:42.426639Z","caller":"traceutil/trace.go:171","msg":"trace[1478604387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1489; }","duration":"116.197219ms","start":"2026-07-21T21:01:42.310427Z","end":"2026-07-21T21:01:42.426624Z","steps":["trace[1478604387] 'agreement among raft nodes before linearized reading' (duration: 116.035555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:42.426431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:41.721391Z","time spent":"705.034269ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-21T21:01:42.42674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.150517ms","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-07-21T21:01:42.426787Z","caller":"traceutil/trace.go:171","msg":"trace[1522476164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1489; }","duration":"338.199719ms","start":"2026-07-21T21:01:42.088575Z","end":"2026-07-21T21:01:42.426775Z","steps":["trace[1522476164] 'agreement among raft nodes before linearized reading' (duration: 337.998224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:42.426815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.088525Z","time spent":"338.28263ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T21:01:42.986475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227671644377569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:01:43.41903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.655846ms","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-07-21T21:01:43.419148Z","caller":"traceutil/trace.go:171","msg":"trace[987646159] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1489; }","duration":"321.769919ms","start":"2026-07-21T21:01:43.097345Z","end":"2026-07-21T21:01:43.419115Z","steps":["trace[987646159] 'range keys from in-memory index tree' (duration: 321.640886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:43.419572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.085837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:01:43.41965Z","caller":"traceutil/trace.go:171","msg":"trace[926380702] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"989.385627ms","start":"2026-07-21T21:01:42.43025Z","end":"2026-07-21T21:01:43.419636Z","steps":["trace[926380702] 'process raft request' (duration: 704.161887ms)","trace[926380702] 'compare' (duration: 284.942893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:43.41969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.430235Z","time spent":"989.434478ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:43.42356Z","caller":"traceutil/trace.go:171","msg":"trace[1030531037] linearizableReadLoop","detail":"{readStateIndex:1556; appliedIndex:1553; }","duration":"937.941258ms","start":"2026-07-21T21:01:42.4856Z","end":"2026-07-21T21:01:43.423541Z","steps":["trace[1030531037] 'read index received' (duration: 648.922143ms)","trace[1030531037] 'applied index is now lower than readState.Index' (duration: 289.018475ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:43.423657Z","caller":"traceutil/trace.go:171","msg":"trace[420623576] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"992.473694ms","start":"2026-07-21T21:01:42.431154Z","end":"2026-07-21T21:01:43.423628Z","steps":["trace[420623576] 'process raft request' (duration: 992.32194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:43.423779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.913604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T21:01:43.423791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.500537ms","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-07-21T21:01:43.423794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.431146Z","time spent":"992.580156ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:01:43.42382Z","caller":"traceutil/trace.go:171","msg":"trace[1768763326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1493; }","duration":"885.986447ms","start":"2026-07-21T21:01:42.537824Z","end":"2026-07-21T21:01:43.42381Z","steps":["trace[1768763326] 'agreement among raft nodes before linearized reading' (duration: 885.847233ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:43.423828Z","caller":"traceutil/trace.go:171","msg":"trace[1348312462] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1493; }","duration":"429.60387ms","start":"2026-07-21T21:01:42.99421Z","end":"2026-07-21T21:01:43.423814Z","steps":["trace[1348312462] 'agreement among raft nodes before linearized reading' (duration: 429.514977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:43.423849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.537783Z","time spent":"886.058378ms","remote":"127.0.0.1:56086","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-07-21T21:01:43.423869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.767577ms","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-07-21T21:01:43.423669Z","caller":"traceutil/trace.go:171","msg":"trace[549479672] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"290.84909ms","start":"2026-07-21T21:01:43.13281Z","end":"2026-07-21T21:01:43.423659Z","steps":["trace[549479672] 'process raft request' (duration: 290.702666ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:43.423949Z","caller":"traceutil/trace.go:171","msg":"trace[1890471060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1493; }","duration":"882.863939ms","start":"2026-07-21T21:01:42.541074Z","end":"2026-07-21T21:01:43.423938Z","steps":["trace[1890471060] 'agreement among raft nodes before linearized reading' (duration: 882.728135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:43.423958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.994189Z","time spent":"429.683232ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:01:43.423981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.541064Z","time spent":"882.90909ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T21:01:43.423988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.067604ms","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":"warn","ts":"2026-07-21T21:01:43.42407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.675971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T21:01:43.424075Z","caller":"traceutil/trace.go:171","msg":"trace[1763671126] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1493; }","duration":"630.214078ms","start":"2026-07-21T21:01:42.793849Z","end":"2026-07-21T21:01:43.424063Z","steps":["trace[1763671126] 'agreement among raft nodes before linearized reading' (duration: 629.994592ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:43.424094Z","caller":"traceutil/trace.go:171","msg":"trace[569561435] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1493; }","duration":"400.732592ms","start":"2026-07-21T21:01:43.023354Z","end":"2026-07-21T21:01:43.424087Z","steps":["trace[569561435] 'agreement among raft nodes before linearized reading' (duration: 400.685131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:43.424091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.489261ms","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":"warn","ts":"2026-07-21T21:01:43.424101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.550216ms","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-07-21T21:01:43.424111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.793829Z","time spent":"630.272309ms","remote":"127.0.0.1:56252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:01:43.424122Z","caller":"traceutil/trace.go:171","msg":"trace[626009375] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1493; }","duration":"938.548833ms","start":"2026-07-21T21:01:42.485566Z","end":"2026-07-21T21:01:43.424114Z","steps":["trace[626009375] 'agreement among raft nodes before linearized reading' (duration: 938.43763ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:43.424126Z","caller":"traceutil/trace.go:171","msg":"trace[441748252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1493; }","duration":"885.589136ms","start":"2026-07-21T21:01:42.53853Z","end":"2026-07-21T21:01:43.424119Z","steps":["trace[441748252] 'agreement among raft nodes before linearized reading' (duration: 885.498244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:43.424144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.48554Z","time spent":"938.599675ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-21T21:01:43.424137Z","caller":"traceutil/trace.go:171","msg":"trace[1557360134] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"993.368556ms","start":"2026-07-21T21:01:42.430279Z","end":"2026-07-21T21:01:43.423648Z","steps":["trace[1557360134] 'process raft request' (duration: 993.077689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:43.424274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.430261Z","time spent":"993.93653ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:01:43.424152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:42.538524Z","time spent":"885.622358ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T21:01:43.42412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:43.02333Z","time spent":"400.783343ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:05:38.966317Z","caller":"traceutil/trace.go:171","msg":"trace[238357009] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"100.466966ms","start":"2026-07-21T21:05:38.865827Z","end":"2026-07-21T21:05:38.966294Z","steps":["trace[238357009] 'read index received' (duration: 58.11879ms)","trace[238357009] 'applied index is now lower than readState.Index' (duration: 42.347136ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:05:38.966472Z","caller":"traceutil/trace.go:171","msg":"trace[496593129] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"105.978383ms","start":"2026-07-21T21:05:38.860477Z","end":"2026-07-21T21:05:38.966456Z","steps":["trace[496593129] 'process raft request' (duration: 63.517975ms)","trace[496593129] 'compare' (duration: 42.14261ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:05:38.96667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.816545ms","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-07-21T21:05:38.966786Z","caller":"traceutil/trace.go:171","msg":"trace[1023031710] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2574; }","duration":"100.984179ms","start":"2026-07-21T21:05:38.865789Z","end":"2026-07-21T21:05:38.966773Z","steps":["trace[1023031710] 'agreement among raft nodes before linearized reading' (duration: 100.701322ms)"],"step_count":1}