{"level":"warn","ts":"2026-02-01T18:54:03.247007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:54:03.2472Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-01T18:54:03.247352Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:54:03.247367Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-02-01T18:54:03.247414Z","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-02-01T18:54:03.2482Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-02-01T18:54:03.248346Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-01T18:54:03.252882Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.243146ms"} {"level":"info","ts":"2026-02-01T18:54:03.264755Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-02-01T18:54:03.26481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:54:03.264959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-02-01T18:54:03.264978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:54:03.264995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-02-01T18:54:03.265084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-02-01T18:54:03.283137Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:54:03.285138Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:54:03.289248Z","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-02-01T18:54:03.291548Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:54:03.307801Z","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-02-01T18:54:03.30786Z","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-02-01T18:54:03.307868Z","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-02-01T18:54:03.30802Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:54:03.308365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-02-01T18:54:03.308533Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-02-01T18:54:03.308784Z","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-02-01T18:54:03.308974Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-02-01T18:54:03.30899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-02-01T18:54:03.30916Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:54:03.309184Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:54:03.866656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:54:03.866697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:54:03.86672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-02-01T18:54:03.866733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:54:03.866744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-02-01T18:54:03.866754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-02-01T18:54:03.866765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-02-01T18:54:03.867962Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:54:03.874614Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:03.874763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:54:03.87504Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:54:03.887843Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:54:03.88791Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:54:03.890211Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:54:03.898328Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:03.898513Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:03.898595Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:54:03.926755Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-02-01T18:54:15.970819Z","caller":"traceutil/trace.go:171","msg":"trace[1522071913] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"110.792756ms","start":"2026-02-01T18:54:15.860005Z","end":"2026-02-01T18:54:15.970797Z","steps":["trace[1522071913] 'process raft request' (duration: 110.395166ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:16.434277Z","caller":"traceutil/trace.go:171","msg":"trace[207990156] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"141.955653ms","start":"2026-02-01T18:54:16.292299Z","end":"2026-02-01T18:54:16.434255Z","steps":["trace[207990156] 'process raft request' (duration: 141.772038ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:22.013127Z","caller":"traceutil/trace.go:171","msg":"trace[375893910] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"740.498206ms","start":"2026-02-01T18:54:21.27254Z","end":"2026-02-01T18:54:22.013038Z","steps":["trace[375893910] 'process raft request' (duration: 740.24739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:22.01558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:21.272523Z","time spent":"740.993458ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6669,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:23.221728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036142313s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:23.221829Z","caller":"traceutil/trace.go:171","msg":"trace[10330756] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"1.157576015s","start":"2026-02-01T18:54:22.064237Z","end":"2026-02-01T18:54:23.221813Z","steps":["trace[10330756] 'read index received' (duration: 121.077743ms)","trace[10330756] 'applied index is now lower than readState.Index' (duration: 1.036497542s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:23.221875Z","caller":"traceutil/trace.go:171","msg":"trace[964004204] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.183363478s","start":"2026-02-01T18:54:22.038504Z","end":"2026-02-01T18:54:23.221868Z","steps":["trace[964004204] 'process raft request' (duration: 146.898896ms)","trace[964004204] 'compare' (duration: 1.036043801s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:23.221915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:22.038493Z","time spent":"1.183400998s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:23.221965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157731838s","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-02-01T18:54:23.222047Z","caller":"traceutil/trace.go:171","msg":"trace[1877716381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"1.157846872s","start":"2026-02-01T18:54:22.064181Z","end":"2026-02-01T18:54:23.222028Z","steps":["trace[1877716381] 'agreement among raft nodes before linearized reading' (duration: 1.157698958s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:23.222086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:22.064168Z","time spent":"1.157908853s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:23.964951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.672737ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-01T18:54:23.965248Z","caller":"traceutil/trace.go:171","msg":"trace[1139437959] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"743.376568ms","start":"2026-02-01T18:54:23.221846Z","end":"2026-02-01T18:54:23.965222Z","steps":["trace[1139437959] 'read index received' (duration: 177.499567ms)","trace[1139437959] 'applied index is now lower than readState.Index' (duration: 565.871701ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:23.965398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81684552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:23.96545Z","caller":"traceutil/trace.go:171","msg":"trace[505704749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"1.816943142s","start":"2026-02-01T18:54:22.148493Z","end":"2026-02-01T18:54:23.965436Z","steps":["trace[505704749] 'agreement among raft nodes before linearized reading' (duration: 1.81685024s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:23.965495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:22.148479Z","time spent":"1.817004715s","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:23.966012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.917021ms","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-02-01T18:54:23.966097Z","caller":"traceutil/trace.go:171","msg":"trace[1432466919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"838.037824ms","start":"2026-02-01T18:54:23.128045Z","end":"2026-02-01T18:54:23.966083Z","steps":["trace[1432466919] 'agreement among raft nodes before linearized reading' (duration: 837.909151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:23.966141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:23.128026Z","time spent":"838.103325ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:23.966331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047565178s","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-02-01T18:54:23.966385Z","caller":"traceutil/trace.go:171","msg":"trace[201371861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"1.047645449s","start":"2026-02-01T18:54:22.918727Z","end":"2026-02-01T18:54:23.966372Z","steps":["trace[201371861] 'agreement among raft nodes before linearized reading' (duration: 1.047539467s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:23.966426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:22.918709Z","time spent":"1.047706201s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:23.966546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217752483s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:23.966638Z","caller":"traceutil/trace.go:171","msg":"trace[824543241] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:416; }","duration":"1.217844406s","start":"2026-02-01T18:54:22.748781Z","end":"2026-02-01T18:54:23.966625Z","steps":["trace[824543241] 'agreement among raft nodes before linearized reading' (duration: 1.217738283s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:23.96694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751784372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-01T18:54:23.967003Z","caller":"traceutil/trace.go:171","msg":"trace[609775907] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:416; }","duration":"1.751874485s","start":"2026-02-01T18:54:22.215112Z","end":"2026-02-01T18:54:23.966986Z","steps":["trace[609775907] 'agreement among raft nodes before linearized reading' (duration: 1.751775902s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:23.967049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:22.215092Z","time spent":"1.751944327s","remote":"127.0.0.1:53902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4058,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-01T18:54:23.970133Z","caller":"traceutil/trace.go:171","msg":"trace[847289510] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"744.289161ms","start":"2026-02-01T18:54:23.225825Z","end":"2026-02-01T18:54:23.970114Z","steps":["trace[847289510] 'process raft request' (duration: 739.341158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:23.970257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:23.225809Z","time spent":"744.378054ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:29.419293Z","caller":"traceutil/trace.go:171","msg":"trace[444279590] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"168.968235ms","start":"2026-02-01T18:54:29.250299Z","end":"2026-02-01T18:54:29.419267Z","steps":["trace[444279590] 'process raft request' (duration: 168.831021ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:32.182933Z","caller":"traceutil/trace.go:171","msg":"trace[1554704069] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"115.315951ms","start":"2026-02-01T18:54:32.067602Z","end":"2026-02-01T18:54:32.182918Z","steps":["trace[1554704069] 'process raft request' (duration: 115.28183ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:32.183188Z","caller":"traceutil/trace.go:171","msg":"trace[816898301] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"116.829357ms","start":"2026-02-01T18:54:32.06635Z","end":"2026-02-01T18:54:32.18318Z","steps":["trace[816898301] 'process raft request' (duration: 100.364757ms)","trace[816898301] 'compare' (duration: 16.094631ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:35.123468Z","caller":"traceutil/trace.go:171","msg":"trace[601486618] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"149.843869ms","start":"2026-02-01T18:54:34.973605Z","end":"2026-02-01T18:54:35.123448Z","steps":["trace[601486618] 'process raft request' (duration: 149.819398ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:35.12367Z","caller":"traceutil/trace.go:171","msg":"trace[1982963808] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"150.157446ms","start":"2026-02-01T18:54:34.973496Z","end":"2026-02-01T18:54:35.123653Z","steps":["trace[1982963808] 'process raft request' (duration: 57.683034ms)","trace[1982963808] 'compare' (duration: 92.131063ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:35.354118Z","caller":"traceutil/trace.go:171","msg":"trace[964418223] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"153.331135ms","start":"2026-02-01T18:54:35.200762Z","end":"2026-02-01T18:54:35.354093Z","steps":["trace[964418223] 'process raft request' (duration: 153.284404ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:35.354287Z","caller":"traceutil/trace.go:171","msg":"trace[476168356] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"153.680285ms","start":"2026-02-01T18:54:35.200587Z","end":"2026-02-01T18:54:35.354267Z","steps":["trace[476168356] 'process raft request' (duration: 60.079855ms)","trace[476168356] 'compare' (duration: 93.049905ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:35.468822Z","caller":"traceutil/trace.go:171","msg":"trace[2054500682] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"110.608363ms","start":"2026-02-01T18:54:35.358199Z","end":"2026-02-01T18:54:35.468807Z","steps":["trace[2054500682] 'process raft request' (duration: 100.122212ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:35.683038Z","caller":"traceutil/trace.go:171","msg":"trace[1750656349] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"175.827966ms","start":"2026-02-01T18:54:35.507188Z","end":"2026-02-01T18:54:35.683016Z","steps":["trace[1750656349] 'process raft request' (duration: 146.086405ms)","trace[1750656349] 'compare' (duration: 29.638628ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:35.809037Z","caller":"traceutil/trace.go:171","msg":"trace[1921041798] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"116.442718ms","start":"2026-02-01T18:54:35.692574Z","end":"2026-02-01T18:54:35.809017Z","steps":["trace[1921041798] 'process raft request' (duration: 114.392907ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.04708Z","caller":"traceutil/trace.go:171","msg":"trace[287742453] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"208.810637ms","start":"2026-02-01T18:54:35.838248Z","end":"2026-02-01T18:54:36.047059Z","steps":["trace[287742453] 'process raft request' (duration: 208.734635ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.047229Z","caller":"traceutil/trace.go:171","msg":"trace[2138216689] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"221.213165ms","start":"2026-02-01T18:54:35.826005Z","end":"2026-02-01T18:54:36.047218Z","steps":["trace[2138216689] 'process raft request' (duration: 200.473419ms)","trace[2138216689] 'compare' (duration: 19.601038ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:36.060561Z","caller":"traceutil/trace.go:171","msg":"trace[158476897] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:507; }","duration":"209.121103ms","start":"2026-02-01T18:54:35.851427Z","end":"2026-02-01T18:54:36.060548Z","steps":["trace[158476897] 'read index received' (duration: 175.312052ms)","trace[158476897] 'applied index is now lower than readState.Index' (duration: 33.808371ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:36.060709Z","caller":"traceutil/trace.go:171","msg":"trace[145849930] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"221.788039ms","start":"2026-02-01T18:54:35.838913Z","end":"2026-02-01T18:54:36.060701Z","steps":["trace[145849930] 'process raft request' (duration: 221.299917ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.061861Z","caller":"traceutil/trace.go:171","msg":"trace[1059321945] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"220.458175ms","start":"2026-02-01T18:54:35.841391Z","end":"2026-02-01T18:54:36.061849Z","steps":["trace[1059321945] 'process raft request' (duration: 218.901917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.062052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.648612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-01T18:54:36.062105Z","caller":"traceutil/trace.go:171","msg":"trace[481126634] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:496; }","duration":"210.724503ms","start":"2026-02-01T18:54:35.851366Z","end":"2026-02-01T18:54:36.062091Z","steps":["trace[481126634] 'agreement among raft nodes before linearized reading' (duration: 210.614581ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.062508Z","caller":"traceutil/trace.go:171","msg":"trace[1388703220] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"208.548619ms","start":"2026-02-01T18:54:35.853946Z","end":"2026-02-01T18:54:36.062495Z","steps":["trace[1388703220] 'process raft request' (duration: 206.429697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:39.008588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.07346ms","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-02-01T18:54:39.008663Z","caller":"traceutil/trace.go:171","msg":"trace[1988745232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"741.195524ms","start":"2026-02-01T18:54:38.26745Z","end":"2026-02-01T18:54:39.008645Z","steps":["trace[1988745232] 'range keys from in-memory index tree' (duration: 740.919696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:39.008706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:38.267432Z","time spent":"741.264475ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:39.009181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.408ms","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-02-01T18:54:39.009214Z","caller":"traceutil/trace.go:171","msg":"trace[1079736651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"860.465752ms","start":"2026-02-01T18:54:38.148737Z","end":"2026-02-01T18:54:39.009203Z","steps":["trace[1079736651] 'range keys from in-memory index tree' (duration: 860.322858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:39.009241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:38.148709Z","time spent":"860.526443ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:39.009361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.246916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:39.009387Z","caller":"traceutil/trace.go:171","msg":"trace[456553111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"860.282768ms","start":"2026-02-01T18:54:38.149096Z","end":"2026-02-01T18:54:39.009379Z","steps":["trace[456553111] 'range keys from in-memory index tree' (duration: 860.194635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:39.010698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:38.149088Z","time spent":"861.593421ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:39.010997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.064325ms","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-02-01T18:54:39.011036Z","caller":"traceutil/trace.go:171","msg":"trace[797829683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"746.156317ms","start":"2026-02-01T18:54:38.264867Z","end":"2026-02-01T18:54:39.011023Z","steps":["trace[797829683] 'range keys from in-memory index tree' (duration: 745.935681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:39.011055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:38.264848Z","time spent":"746.201569ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-01T18:54:40.004423Z","caller":"traceutil/trace.go:171","msg":"trace[1511403932] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"406.903447ms","start":"2026-02-01T18:54:39.597505Z","end":"2026-02-01T18:54:40.004408Z","steps":["trace[1511403932] 'read index received' (duration: 406.791384ms)","trace[1511403932] 'applied index is now lower than readState.Index' (duration: 111.143µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:40.004551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.03379ms","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-02-01T18:54:40.004568Z","caller":"traceutil/trace.go:171","msg":"trace[162442095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"407.103181ms","start":"2026-02-01T18:54:39.597459Z","end":"2026-02-01T18:54:40.004562Z","steps":["trace[162442095] 'agreement among raft nodes before linearized reading' (duration: 407.007099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:40.004585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:39.597446Z","time spent":"407.134882ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T18:54:40.004738Z","caller":"traceutil/trace.go:171","msg":"trace[879278191] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"408.735791ms","start":"2026-02-01T18:54:39.595997Z","end":"2026-02-01T18:54:40.004733Z","steps":["trace[879278191] 'process raft request' (duration: 408.335171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:40.00478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:39.595981Z","time spent":"408.769682ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:41.549981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089843323,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:54:42.050607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089843323,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:54:42.078335Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033201602s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:54:42.07858Z","caller":"traceutil/trace.go:171","msg":"trace[796271949] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"1.029285853s","start":"2026-02-01T18:54:41.049278Z","end":"2026-02-01T18:54:42.078564Z","steps":["trace[796271949] 'read index received' (duration: 1.02916507s)","trace[796271949] 'applied index is now lower than readState.Index' (duration: 120.293µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:42.078768Z","caller":"traceutil/trace.go:171","msg":"trace[175427711] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.033719344s","start":"2026-02-01T18:54:41.045041Z","end":"2026-02-01T18:54:42.078761Z","steps":["trace[175427711] 'process raft request' (duration: 1.033439427s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.078828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.045023Z","time spent":"1.033760005s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:42.078995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029715254s","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-02-01T18:54:42.07901Z","caller":"traceutil/trace.go:171","msg":"trace[392131937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"1.029751564s","start":"2026-02-01T18:54:41.049254Z","end":"2026-02-01T18:54:42.079006Z","steps":["trace[392131937] 'agreement among raft nodes before linearized reading' (duration: 1.029668212s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.079025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.049246Z","time spent":"1.029774435s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:42.079092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029206741s","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-02-01T18:54:42.079103Z","caller":"traceutil/trace.go:171","msg":"trace[1401874861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"1.029228561s","start":"2026-02-01T18:54:41.049871Z","end":"2026-02-01T18:54:42.079099Z","steps":["trace[1401874861] 'agreement among raft nodes before linearized reading' (duration: 1.029207681s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.079114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.049863Z","time spent":"1.029248042s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:42.644297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.846907ms","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-02-01T18:54:42.644444Z","caller":"traceutil/trace.go:171","msg":"trace[1429152137] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:522; }","duration":"475.010281ms","start":"2026-02-01T18:54:42.169417Z","end":"2026-02-01T18:54:42.644427Z","steps":["trace[1429152137] 'range keys from in-memory index tree' (duration: 474.832416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.644987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.228376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:42.645079Z","caller":"traceutil/trace.go:171","msg":"trace[496821194] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"484.411154ms","start":"2026-02-01T18:54:42.16066Z","end":"2026-02-01T18:54:42.645071Z","steps":["trace[496821194] 'read index received' (duration: 2.992884ms)","trace[496821194] 'applied index is now lower than readState.Index' (duration: 481.41734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:42.64514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.479666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:42.645152Z","caller":"traceutil/trace.go:171","msg":"trace[1024046763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"484.534337ms","start":"2026-02-01T18:54:42.160613Z","end":"2026-02-01T18:54:42.645147Z","steps":["trace[1024046763] 'agreement among raft nodes before linearized reading' (duration: 484.479496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.645172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:42.160582Z","time spent":"484.585839ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:54:42.645567Z","caller":"traceutil/trace.go:171","msg":"trace[1223348173] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"561.400299ms","start":"2026-02-01T18:54:42.084156Z","end":"2026-02-01T18:54:42.645557Z","steps":["trace[1223348173] 'process raft request' (duration: 79.536558ms)","trace[1223348173] 'compare' (duration: 480.502348ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:42.645612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:42.08415Z","time spent":"561.445141ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:42.645697Z","caller":"traceutil/trace.go:171","msg":"trace[1522857092] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"561.279226ms","start":"2026-02-01T18:54:42.084407Z","end":"2026-02-01T18:54:42.645686Z","steps":["trace[1522857092] 'process raft request' (duration: 560.62619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.645759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:42.084401Z","time spent":"561.310557ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:42.796483Z","caller":"traceutil/trace.go:171","msg":"trace[102354442] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"144.909655ms","start":"2026-02-01T18:54:42.651554Z","end":"2026-02-01T18:54:42.796464Z","steps":["trace[102354442] 'read index received' (duration: 91.150178ms)","trace[102354442] 'applied index is now lower than readState.Index' (duration: 53.758637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:42.796564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.990937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:42.796581Z","caller":"traceutil/trace.go:171","msg":"trace[757384096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"145.044658ms","start":"2026-02-01T18:54:42.65153Z","end":"2026-02-01T18:54:42.796575Z","steps":["trace[757384096] 'agreement among raft nodes before linearized reading' (duration: 144.993617ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:45.282943Z","caller":"traceutil/trace.go:171","msg":"trace[567917600] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"580.7285ms","start":"2026-02-01T18:54:44.702186Z","end":"2026-02-01T18:54:45.282914Z","steps":["trace[567917600] 'process raft request' (duration: 580.591477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:45.283191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:44.702169Z","time spent":"580.872214ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:45.64372Z","caller":"traceutil/trace.go:171","msg":"trace[269520399] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"497.8959ms","start":"2026-02-01T18:54:45.145793Z","end":"2026-02-01T18:54:45.643689Z","steps":["trace[269520399] 'read index received' (duration: 137.989863ms)","trace[269520399] 'applied index is now lower than readState.Index' (duration: 359.901907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:45.643889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.071205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:45.643932Z","caller":"traceutil/trace.go:171","msg":"trace[255904785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"498.183727ms","start":"2026-02-01T18:54:45.145735Z","end":"2026-02-01T18:54:45.643919Z","steps":["trace[255904785] 'agreement among raft nodes before linearized reading' (duration: 498.066635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:45.643976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:45.14571Z","time spent":"498.253099ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:54:45.644349Z","caller":"traceutil/trace.go:171","msg":"trace[341946550] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"938.909843ms","start":"2026-02-01T18:54:44.705422Z","end":"2026-02-01T18:54:45.644332Z","steps":["trace[341946550] 'process raft request' (duration: 937.359695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:45.644484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:44.705416Z","time spent":"938.969875ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:48.724104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089843372,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:54:49.742855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.919146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:49.742946Z","caller":"traceutil/trace.go:171","msg":"trace[1887040991] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"1.519767959s","start":"2026-02-01T18:54:48.223158Z","end":"2026-02-01T18:54:49.742926Z","steps":["trace[1887040991] 'read index received' (duration: 897.696949ms)","trace[1887040991] 'applied index is now lower than readState.Index' (duration: 622.07025ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:49.743107Z","caller":"traceutil/trace.go:171","msg":"trace[65364520] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.559255421s","start":"2026-02-01T18:54:48.18383Z","end":"2026-02-01T18:54:49.743086Z","steps":["trace[65364520] 'process raft request' (duration: 937.045208ms)","trace[65364520] 'compare' (duration: 621.607328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:49.743148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:48.183819Z","time spent":"1.559306992s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:49.743279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520120087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rnckw\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-01T18:54:49.743299Z","caller":"traceutil/trace.go:171","msg":"trace[1170138254] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rnckw; range_end:; response_count:1; response_revision:539; }","duration":"1.520171569s","start":"2026-02-01T18:54:48.22312Z","end":"2026-02-01T18:54:49.743292Z","steps":["trace[1170138254] 'agreement among raft nodes before linearized reading' (duration: 1.520130218s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:49.743338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:48.223101Z","time spent":"1.52023206s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-rnckw\" "} {"level":"warn","ts":"2026-02-01T18:54:49.743448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.347724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:49.743465Z","caller":"traceutil/trace.go:171","msg":"trace[408779528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"597.422116ms","start":"2026-02-01T18:54:49.146038Z","end":"2026-02-01T18:54:49.74346Z","steps":["trace[408779528] 'agreement among raft nodes before linearized reading' (duration: 597.396265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:49.743481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:49.146016Z","time spent":"597.461697ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:49.744628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.755913ms","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-02-01T18:54:49.744651Z","caller":"traceutil/trace.go:171","msg":"trace[1527287738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"441.825285ms","start":"2026-02-01T18:54:49.302819Z","end":"2026-02-01T18:54:49.744645Z","steps":["trace[1527287738] 'agreement among raft nodes before linearized reading' (duration: 441.759583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:49.744669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:49.302798Z","time spent":"441.866955ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:51.936753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.596103ms","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-02-01T18:54:51.936933Z","caller":"traceutil/trace.go:171","msg":"trace[896073629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"186.829469ms","start":"2026-02-01T18:54:51.750084Z","end":"2026-02-01T18:54:51.936913Z","steps":["trace[896073629] 'range keys from in-memory index tree' (duration: 186.434189ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:55.350122Z","caller":"traceutil/trace.go:171","msg":"trace[921513946] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"101.957788ms","start":"2026-02-01T18:54:55.248142Z","end":"2026-02-01T18:54:55.350099Z","steps":["trace[921513946] 'process raft request' (duration: 101.65358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:00.638136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.554271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:00.63825Z","caller":"traceutil/trace.go:171","msg":"trace[1988535020] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"158.439551ms","start":"2026-02-01T18:55:00.479792Z","end":"2026-02-01T18:55:00.638231Z","steps":["trace[1988535020] 'process raft request' (duration: 158.409681ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:00.63948Z","caller":"traceutil/trace.go:171","msg":"trace[138889426] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"269.530928ms","start":"2026-02-01T18:55:00.369939Z","end":"2026-02-01T18:55:00.63947Z","steps":["trace[138889426] 'process raft request' (duration: 120.568691ms)","trace[138889426] 'compare' (duration: 147.391007ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:00.639763Z","caller":"traceutil/trace.go:171","msg":"trace[1654947119] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"214.314773ms","start":"2026-02-01T18:55:00.425441Z","end":"2026-02-01T18:55:00.639756Z","steps":["trace[1654947119] 'read index received' (duration: 65.073329ms)","trace[1654947119] 'applied index is now lower than readState.Index' (duration: 149.240584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:00.639837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.392615ms","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-02-01T18:55:00.639849Z","caller":"traceutil/trace.go:171","msg":"trace[2048086098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:663; }","duration":"214.435586ms","start":"2026-02-01T18:55:00.425409Z","end":"2026-02-01T18:55:00.639845Z","steps":["trace[2048086098] 'agreement among raft nodes before linearized reading' (duration: 214.366744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.491998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089843623,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:02.626028Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.953480286s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:55:02.626288Z","caller":"traceutil/trace.go:171","msg":"trace[514143122] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.953799435s","start":"2026-02-01T18:55:00.67247Z","end":"2026-02-01T18:55:02.626269Z","steps":["trace[514143122] 'process raft request' (duration: 1.953682292s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.626419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:00.672455Z","time spent":"1.953882146s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:02.655901Z","caller":"traceutil/trace.go:171","msg":"trace[1695511579] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.976414288s","start":"2026-02-01T18:55:00.679465Z","end":"2026-02-01T18:55:02.655879Z","steps":["trace[1695511579] 'process raft request' (duration: 1.972825208s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.656005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:00.679431Z","time spent":"1.97651507s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18043,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:02.656398Z","caller":"traceutil/trace.go:171","msg":"trace[960622094] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.974800888s","start":"2026-02-01T18:55:00.68159Z","end":"2026-02-01T18:55:02.656391Z","steps":["trace[960622094] 'process raft request' (duration: 1.970860159s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.656427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:00.681584Z","time spent":"1.974829479s","remote":"127.0.0.1:32794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:55:02.670399Z","caller":"traceutil/trace.go:171","msg":"trace[1572032974] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:687; }","duration":"678.623855ms","start":"2026-02-01T18:55:01.991759Z","end":"2026-02-01T18:55:02.670383Z","steps":["trace[1572032974] 'read index received' (duration: 634.998185ms)","trace[1572032974] 'applied index is now lower than readState.Index' (duration: 43.62447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:02.670597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.81992ms","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-02-01T18:55:02.670621Z","caller":"traceutil/trace.go:171","msg":"trace[1853944163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"678.910532ms","start":"2026-02-01T18:55:01.991704Z","end":"2026-02-01T18:55:02.670615Z","steps":["trace[1853944163] 'agreement among raft nodes before linearized reading' (duration: 678.789349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.67064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:01.99169Z","time spent":"678.945423ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:55:02.684955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.558318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-01T18:55:02.684963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.085414ms","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-02-01T18:55:02.685003Z","caller":"traceutil/trace.go:171","msg":"trace[1869609451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"655.648091ms","start":"2026-02-01T18:55:02.029343Z","end":"2026-02-01T18:55:02.684991Z","steps":["trace[1869609451] 'agreement among raft nodes before linearized reading' (duration: 655.478107ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:02.685019Z","caller":"traceutil/trace.go:171","msg":"trace[888281163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"402.184846ms","start":"2026-02-01T18:55:02.282822Z","end":"2026-02-01T18:55:02.685007Z","steps":["trace[888281163] 'agreement among raft nodes before linearized reading' (duration: 402.035813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.685029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.029331Z","time spent":"655.693572ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:02.685045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.282807Z","time spent":"402.232587ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:02.685196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.385405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:02.685211Z","caller":"traceutil/trace.go:171","msg":"trace[223343046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"542.430726ms","start":"2026-02-01T18:55:02.142776Z","end":"2026-02-01T18:55:02.685207Z","steps":["trace[223343046] 'agreement among raft nodes before linearized reading' (duration: 542.407366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.685224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.142762Z","time spent":"542.457807ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:02.685327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.375374ms","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-02-01T18:55:02.68534Z","caller":"traceutil/trace.go:171","msg":"trace[208598904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"655.395174ms","start":"2026-02-01T18:55:02.02994Z","end":"2026-02-01T18:55:02.685336Z","steps":["trace[208598904] 'agreement among raft nodes before linearized reading' (duration: 655.347613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.685353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.029935Z","time spent":"655.415416ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-01T18:55:02.959645Z","caller":"traceutil/trace.go:171","msg":"trace[723782203] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"283.496804ms","start":"2026-02-01T18:55:02.676129Z","end":"2026-02-01T18:55:02.959626Z","steps":["trace[723782203] 'process raft request' (duration: 283.443483ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:02.959833Z","caller":"traceutil/trace.go:171","msg":"trace[1196721891] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"286.463028ms","start":"2026-02-01T18:55:02.673349Z","end":"2026-02-01T18:55:02.959812Z","steps":["trace[1196721891] 'process raft request' (duration: 285.945995ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:02.989535Z","caller":"traceutil/trace.go:171","msg":"trace[1943960243] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:691; }","duration":"299.535454ms","start":"2026-02-01T18:55:02.689984Z","end":"2026-02-01T18:55:02.98952Z","steps":["trace[1943960243] 'read index received' (duration: 269.514295ms)","trace[1943960243] 'applied index is now lower than readState.Index' (duration: 30.020339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:02.989647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.639828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:02.989666Z","caller":"traceutil/trace.go:171","msg":"trace[1771297148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"299.678509ms","start":"2026-02-01T18:55:02.689982Z","end":"2026-02-01T18:55:02.989661Z","steps":["trace[1771297148] 'agreement among raft nodes before linearized reading' (duration: 299.595427ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:02.989896Z","caller":"traceutil/trace.go:171","msg":"trace[977287126] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"299.959745ms","start":"2026-02-01T18:55:02.689929Z","end":"2026-02-01T18:55:02.989889Z","steps":["trace[977287126] 'process raft request' (duration: 299.480694ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:02.989976Z","caller":"traceutil/trace.go:171","msg":"trace[625832819] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"296.639972ms","start":"2026-02-01T18:55:02.693331Z","end":"2026-02-01T18:55:02.989971Z","steps":["trace[625832819] 'process raft request' (duration: 296.14921ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:02.990028Z","caller":"traceutil/trace.go:171","msg":"trace[2147271835] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"296.656193ms","start":"2026-02-01T18:55:02.693368Z","end":"2026-02-01T18:55:02.990024Z","steps":["trace[2147271835] 'process raft request' (duration: 296.13282ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:03.575205Z","caller":"traceutil/trace.go:171","msg":"trace[363984236] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"109.307608ms","start":"2026-02-01T18:55:03.465881Z","end":"2026-02-01T18:55:03.575188Z","steps":["trace[363984236] 'process raft request' (duration: 109.214336ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:04.50501Z","caller":"traceutil/trace.go:171","msg":"trace[344590880] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"143.798619ms","start":"2026-02-01T18:55:04.361179Z","end":"2026-02-01T18:55:04.504978Z","steps":["trace[344590880] 'process raft request' (duration: 121.82693ms)","trace[344590880] 'compare' (duration: 21.857525ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:04.6633Z","caller":"traceutil/trace.go:171","msg":"trace[1230093893] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"135.629554ms","start":"2026-02-01T18:55:04.527658Z","end":"2026-02-01T18:55:04.663287Z","steps":["trace[1230093893] 'read index received' (duration: 22.744647ms)","trace[1230093893] 'applied index is now lower than readState.Index' (duration: 112.884297ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:04.663393Z","caller":"traceutil/trace.go:171","msg":"trace[1748478277] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"135.603173ms","start":"2026-02-01T18:55:04.527785Z","end":"2026-02-01T18:55:04.663388Z","steps":["trace[1748478277] 'process raft request' (duration: 135.45013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:04.663548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.87775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-q2cgt\" ","response":"range_response_count:1 size:5025"} {"level":"info","ts":"2026-02-01T18:55:04.663567Z","caller":"traceutil/trace.go:171","msg":"trace[960520763] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-q2cgt; range_end:; response_count:1; response_revision:683; }","duration":"135.911321ms","start":"2026-02-01T18:55:04.527648Z","end":"2026-02-01T18:55:04.66356Z","steps":["trace[960520763] 'agreement among raft nodes before linearized reading' (duration: 135.815998ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:04.663301Z","caller":"traceutil/trace.go:171","msg":"trace[722407946] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"135.913691ms","start":"2026-02-01T18:55:04.527369Z","end":"2026-02-01T18:55:04.663283Z","steps":["trace[722407946] 'process raft request' (duration: 92.20093ms)","trace[722407946] 'compare' (duration: 43.549297ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:05.050939Z","caller":"traceutil/trace.go:171","msg":"trace[499320057] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"375.950281ms","start":"2026-02-01T18:55:04.674968Z","end":"2026-02-01T18:55:05.050919Z","steps":["trace[499320057] 'process raft request' (duration: 375.834699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.051067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:04.674954Z","time spent":"376.043563ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4832,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:05.158916Z","caller":"traceutil/trace.go:171","msg":"trace[68958307] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"195.371675ms","start":"2026-02-01T18:55:04.963525Z","end":"2026-02-01T18:55:05.158896Z","steps":["trace[68958307] 'read index received' (duration: 87.410231ms)","trace[68958307] 'applied index is now lower than readState.Index' (duration: 107.960514ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:05.158994Z","caller":"traceutil/trace.go:171","msg":"trace[1363510939] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"404.068902ms","start":"2026-02-01T18:55:04.754916Z","end":"2026-02-01T18:55:05.158985Z","steps":["trace[1363510939] 'process raft request' (duration: 344.551208ms)","trace[1363510939] 'compare' (duration: 59.3343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:05.15907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:04.754899Z","time spent":"404.120354ms","remote":"127.0.0.1:53526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:55:05.159162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.626191ms","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-02-01T18:55:05.159193Z","caller":"traceutil/trace.go:171","msg":"trace[1762424178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"195.690003ms","start":"2026-02-01T18:55:04.963495Z","end":"2026-02-01T18:55:05.159184Z","steps":["trace[1762424178] 'agreement among raft nodes before linearized reading' (duration: 195.55197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.209153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.77884ms","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-02-01T18:55:05.209205Z","caller":"traceutil/trace.go:171","msg":"trace[1199389577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"214.876173ms","start":"2026-02-01T18:55:04.994316Z","end":"2026-02-01T18:55:05.209192Z","steps":["trace[1199389577] 'agreement among raft nodes before linearized reading' (duration: 214.734999ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.209253Z","caller":"traceutil/trace.go:171","msg":"trace[811263106] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"151.46843ms","start":"2026-02-01T18:55:05.057775Z","end":"2026-02-01T18:55:05.209244Z","steps":["trace[811263106] 'process raft request' (duration: 151.130482ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.209322Z","caller":"traceutil/trace.go:171","msg":"trace[1140313757] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"149.593223ms","start":"2026-02-01T18:55:05.059725Z","end":"2026-02-01T18:55:05.209318Z","steps":["trace[1140313757] 'process raft request' (duration: 149.301636ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.209397Z","caller":"traceutil/trace.go:171","msg":"trace[504721469] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"151.329757ms","start":"2026-02-01T18:55:05.058062Z","end":"2026-02-01T18:55:05.209392Z","steps":["trace[504721469] 'process raft request' (duration: 150.934857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.209481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.01253ms","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-02-01T18:55:05.209503Z","caller":"traceutil/trace.go:171","msg":"trace[1066300072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"214.050731ms","start":"2026-02-01T18:55:04.995448Z","end":"2026-02-01T18:55:05.209499Z","steps":["trace[1066300072] 'agreement among raft nodes before linearized reading' (duration: 214.00308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.209577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.870162ms","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-02-01T18:55:05.209588Z","caller":"traceutil/trace.go:171","msg":"trace[1334600025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"214.895992ms","start":"2026-02-01T18:55:04.994688Z","end":"2026-02-01T18:55:05.209584Z","steps":["trace[1334600025] 'agreement among raft nodes before linearized reading' (duration: 214.860342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.394106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.640346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:05.394292Z","caller":"traceutil/trace.go:171","msg":"trace[193382503] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:715; }","duration":"168.289859ms","start":"2026-02-01T18:55:05.22599Z","end":"2026-02-01T18:55:05.39428Z","steps":["trace[193382503] 'read index received' (duration: 46.355346ms)","trace[193382503] 'applied index is now lower than readState.Index' (duration: 121.933843ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:05.394458Z","caller":"traceutil/trace.go:171","msg":"trace[727353107] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"179.764065ms","start":"2026-02-01T18:55:05.214685Z","end":"2026-02-01T18:55:05.394449Z","steps":["trace[727353107] 'process raft request' (duration: 57.698299ms)","trace[727353107] 'compare' (duration: 121.4077ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:05.39461Z","caller":"traceutil/trace.go:171","msg":"trace[440212273] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"178.080743ms","start":"2026-02-01T18:55:05.216522Z","end":"2026-02-01T18:55:05.394603Z","steps":["trace[440212273] 'process raft request' (duration: 177.658933ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.394755Z","caller":"traceutil/trace.go:171","msg":"trace[453937257] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"177.631972ms","start":"2026-02-01T18:55:05.217117Z","end":"2026-02-01T18:55:05.394749Z","steps":["trace[453937257] 'process raft request' (duration: 177.103649ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.394839Z","caller":"traceutil/trace.go:171","msg":"trace[1875478195] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"174.121925ms","start":"2026-02-01T18:55:05.220712Z","end":"2026-02-01T18:55:05.394834Z","steps":["trace[1875478195] 'process raft request' (duration: 173.542831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.394964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.964196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:05.394998Z","caller":"traceutil/trace.go:171","msg":"trace[1913088397] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:695; }","duration":"169.022628ms","start":"2026-02-01T18:55:05.225965Z","end":"2026-02-01T18:55:05.394988Z","steps":["trace[1913088397] 'agreement among raft nodes before linearized reading' (duration: 168.964996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:05.395102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.464385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:05.395123Z","caller":"traceutil/trace.go:171","msg":"trace[691372195] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:695; }","duration":"128.508487ms","start":"2026-02-01T18:55:05.266609Z","end":"2026-02-01T18:55:05.395118Z","steps":["trace[691372195] 'agreement among raft nodes before linearized reading' (duration: 128.476406ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.518662Z","caller":"traceutil/trace.go:171","msg":"trace[311905035] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"110.500416ms","start":"2026-02-01T18:55:05.408152Z","end":"2026-02-01T18:55:05.518652Z","steps":["trace[311905035] 'process raft request' (duration: 108.675762ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.518172Z","caller":"traceutil/trace.go:171","msg":"trace[1913507454] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"107.471331ms","start":"2026-02-01T18:55:05.410683Z","end":"2026-02-01T18:55:05.518154Z","steps":["trace[1913507454] 'process raft request' (duration: 107.209675ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.725737Z","caller":"traceutil/trace.go:171","msg":"trace[1439222359] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"141.663635ms","start":"2026-02-01T18:55:05.584051Z","end":"2026-02-01T18:55:05.725715Z","steps":["trace[1439222359] 'read index received' (duration: 109.675787ms)","trace[1439222359] 'applied index is now lower than readState.Index' (duration: 31.986958ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:05.725823Z","caller":"traceutil/trace.go:171","msg":"trace[1364228274] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"189.710464ms","start":"2026-02-01T18:55:05.536103Z","end":"2026-02-01T18:55:05.725813Z","steps":["trace[1364228274] 'process raft request' (duration: 157.617894ms)","trace[1364228274] 'compare' (duration: 31.908545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:05.725856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.468787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-d427j\" ","response":"range_response_count:1 size:4998"} {"level":"info","ts":"2026-02-01T18:55:05.725887Z","caller":"traceutil/trace.go:171","msg":"trace[569422975] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-d427j; range_end:; response_count:1; response_revision:701; }","duration":"189.525059ms","start":"2026-02-01T18:55:05.536353Z","end":"2026-02-01T18:55:05.725878Z","steps":["trace[569422975] 'agreement among raft nodes before linearized reading' (duration: 189.454867ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.753143Z","caller":"traceutil/trace.go:171","msg":"trace[1384311595] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"160.876115ms","start":"2026-02-01T18:55:05.592244Z","end":"2026-02-01T18:55:05.75312Z","steps":["trace[1384311595] 'process raft request' (duration: 160.768452ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.920684Z","caller":"traceutil/trace.go:171","msg":"trace[25094075] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"189.023038ms","start":"2026-02-01T18:55:05.731641Z","end":"2026-02-01T18:55:05.920664Z","steps":["trace[25094075] 'process raft request' (duration: 169.957552ms)","trace[25094075] 'compare' (duration: 18.931152ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:05.920791Z","caller":"traceutil/trace.go:171","msg":"trace[148611444] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:727; }","duration":"162.325211ms","start":"2026-02-01T18:55:05.758457Z","end":"2026-02-01T18:55:05.920783Z","steps":["trace[148611444] 'read index received' (duration: 143.121751ms)","trace[148611444] 'applied index is now lower than readState.Index' (duration: 19.20285ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:05.920916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.441273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-02-01T18:55:05.920934Z","caller":"traceutil/trace.go:171","msg":"trace[877582740] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:707; }","duration":"162.494495ms","start":"2026-02-01T18:55:05.758434Z","end":"2026-02-01T18:55:05.920928Z","steps":["trace[877582740] 'agreement among raft nodes before linearized reading' (duration: 162.389563ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.92097Z","caller":"traceutil/trace.go:171","msg":"trace[1290155216] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"184.173346ms","start":"2026-02-01T18:55:05.736791Z","end":"2026-02-01T18:55:05.920965Z","steps":["trace[1290155216] 'process raft request' (duration: 183.905609ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.921084Z","caller":"traceutil/trace.go:171","msg":"trace[1483563624] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"184.344321ms","start":"2026-02-01T18:55:05.736735Z","end":"2026-02-01T18:55:05.921079Z","steps":["trace[1483563624] 'process raft request' (duration: 183.89291ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.92109Z","caller":"traceutil/trace.go:171","msg":"trace[1818653430] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"184.210077ms","start":"2026-02-01T18:55:05.736875Z","end":"2026-02-01T18:55:05.921085Z","steps":["trace[1818653430] 'process raft request' (duration: 183.846848ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:05.921223Z","caller":"traceutil/trace.go:171","msg":"trace[753720269] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"180.986596ms","start":"2026-02-01T18:55:05.740231Z","end":"2026-02-01T18:55:05.921218Z","steps":["trace[753720269] 'process raft request' (duration: 180.530305ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:07.529799Z","caller":"traceutil/trace.go:171","msg":"trace[899655721] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"190.147575ms","start":"2026-02-01T18:55:07.339623Z","end":"2026-02-01T18:55:07.52977Z","steps":["trace[899655721] 'process raft request' (duration: 144.623729ms)","trace[899655721] 'compare' (duration: 45.396213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:07.529965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.101264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:55:07.529793Z","caller":"traceutil/trace.go:171","msg":"trace[1589294413] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"189.92548ms","start":"2026-02-01T18:55:07.339846Z","end":"2026-02-01T18:55:07.529771Z","steps":["trace[1589294413] 'read index received' (duration: 144.368943ms)","trace[1589294413] 'applied index is now lower than readState.Index' (duration: 45.555667ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:07.529994Z","caller":"traceutil/trace.go:171","msg":"trace[178553100] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:719; }","duration":"190.161935ms","start":"2026-02-01T18:55:07.339824Z","end":"2026-02-01T18:55:07.529986Z","steps":["trace[178553100] 'agreement among raft nodes before linearized reading' (duration: 190.011721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.530272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.876601ms","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-02-01T18:55:07.530295Z","caller":"traceutil/trace.go:171","msg":"trace[1321753434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"129.946903ms","start":"2026-02-01T18:55:07.400341Z","end":"2026-02-01T18:55:07.530288Z","steps":["trace[1321753434] 'agreement among raft nodes before linearized reading' (duration: 129.870941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.530396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.417965ms","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-02-01T18:55:07.530419Z","caller":"traceutil/trace.go:171","msg":"trace[611750216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"126.454537ms","start":"2026-02-01T18:55:07.403958Z","end":"2026-02-01T18:55:07.530413Z","steps":["trace[611750216] 'agreement among raft nodes before linearized reading' (duration: 126.396574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:07.530505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.516188ms","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-02-01T18:55:07.530525Z","caller":"traceutil/trace.go:171","msg":"trace[206441019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"126.558569ms","start":"2026-02-01T18:55:07.40396Z","end":"2026-02-01T18:55:07.530519Z","steps":["trace[206441019] 'agreement among raft nodes before linearized reading' (duration: 126.505578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.284616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.082092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.28468Z","caller":"traceutil/trace.go:171","msg":"trace[220179930] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:737; }","duration":"777.184774ms","start":"2026-02-01T18:55:13.50748Z","end":"2026-02-01T18:55:14.284665Z","steps":["trace[220179930] 'count revisions from in-memory index tree' (duration: 777.040261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.284712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.507475Z","time spent":"777.229815ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:14.284849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.41532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.28489Z","caller":"traceutil/trace.go:171","msg":"trace[366536767] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:737; }","duration":"777.488732ms","start":"2026-02-01T18:55:13.507395Z","end":"2026-02-01T18:55:14.284884Z","steps":["trace[366536767] 'range keys from in-memory index tree' (duration: 777.274597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.284909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.507378Z","time spent":"777.525113ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:55:14.284984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.42691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.285Z","caller":"traceutil/trace.go:171","msg":"trace[1727097773] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:737; }","duration":"777.453111ms","start":"2026-02-01T18:55:13.507542Z","end":"2026-02-01T18:55:14.284995Z","steps":["trace[1727097773] 'range keys from in-memory index tree' (duration: 777.319078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.285017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.507539Z","time spent":"777.473542ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:55:14.285091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.822951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.285127Z","caller":"traceutil/trace.go:171","msg":"trace[797099325] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:737; }","duration":"763.862531ms","start":"2026-02-01T18:55:13.521257Z","end":"2026-02-01T18:55:14.285119Z","steps":["trace[797099325] 'count revisions from in-memory index tree' (duration: 763.797941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.285145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.521254Z","time spent":"763.885342ms","remote":"127.0.0.1:53320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:14.285254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.031346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.285271Z","caller":"traceutil/trace.go:171","msg":"trace[522254444] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:737; }","duration":"764.096637ms","start":"2026-02-01T18:55:13.521169Z","end":"2026-02-01T18:55:14.285266Z","steps":["trace[522254444] 'range keys from in-memory index tree' (duration: 763.924003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.28529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.521154Z","time spent":"764.129648ms","remote":"127.0.0.1:53320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:55:14.285356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.042396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.285371Z","caller":"traceutil/trace.go:171","msg":"trace[377061219] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:737; }","duration":"764.058016ms","start":"2026-02-01T18:55:13.521308Z","end":"2026-02-01T18:55:14.285366Z","steps":["trace[377061219] 'range keys from in-memory index tree' (duration: 764.018166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.285389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.521299Z","time spent":"764.085327ms","remote":"127.0.0.1:53320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:55:14.285452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.301702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.285469Z","caller":"traceutil/trace.go:171","msg":"trace[118046122] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:737; }","duration":"758.319133ms","start":"2026-02-01T18:55:13.527146Z","end":"2026-02-01T18:55:14.285465Z","steps":["trace[118046122] 'range keys from in-memory index tree' (duration: 758.281342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.285486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.527132Z","time spent":"758.348934ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:55:14.28641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.307598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.286429Z","caller":"traceutil/trace.go:171","msg":"trace[1948296005] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:737; }","duration":"759.346488ms","start":"2026-02-01T18:55:13.527077Z","end":"2026-02-01T18:55:14.286423Z","steps":["trace[1948296005] 'range keys from in-memory index tree' (duration: 759.203615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.286444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.527064Z","time spent":"759.376029ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:55:14.286554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.362238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:14.286569Z","caller":"traceutil/trace.go:171","msg":"trace[373101758] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:737; }","duration":"759.386449ms","start":"2026-02-01T18:55:13.527178Z","end":"2026-02-01T18:55:14.286565Z","steps":["trace[373101758] 'count revisions from in-memory index tree' (duration: 759.341468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.286583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.527176Z","time spent":"759.40325ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:14.286753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.287677ms","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-02-01T18:55:14.286767Z","caller":"traceutil/trace.go:171","msg":"trace[839681301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"727.333649ms","start":"2026-02-01T18:55:13.559429Z","end":"2026-02-01T18:55:14.286763Z","steps":["trace[839681301] 'range keys from in-memory index tree' (duration: 727.173585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.286779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.559413Z","time spent":"727.363599ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:14.286911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.690098ms","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-02-01T18:55:14.286926Z","caller":"traceutil/trace.go:171","msg":"trace[26281293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"715.713559ms","start":"2026-02-01T18:55:13.571208Z","end":"2026-02-01T18:55:14.286921Z","steps":["trace[26281293] 'range keys from in-memory index tree' (duration: 715.647538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.286937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.571203Z","time spent":"715.731289ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:14.287054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.875513ms","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-02-01T18:55:14.287068Z","caller":"traceutil/trace.go:171","msg":"trace[825967508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"715.890813ms","start":"2026-02-01T18:55:13.571173Z","end":"2026-02-01T18:55:14.287064Z","steps":["trace[825967508] 'range keys from in-memory index tree' (duration: 715.77364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.287081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.571136Z","time spent":"715.940764ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-01T18:55:14.506344Z","caller":"traceutil/trace.go:171","msg":"trace[1978298889] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"209.56019ms","start":"2026-02-01T18:55:14.296768Z","end":"2026-02-01T18:55:14.506328Z","steps":["trace[1978298889] 'process raft request' (duration: 209.464327ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:14.514107Z","caller":"traceutil/trace.go:171","msg":"trace[117267763] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"216.952024ms","start":"2026-02-01T18:55:14.297146Z","end":"2026-02-01T18:55:14.514098Z","steps":["trace[117267763] 'process raft request' (duration: 216.834581ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:14.514201Z","caller":"traceutil/trace.go:171","msg":"trace[1648367198] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"215.686632ms","start":"2026-02-01T18:55:14.298507Z","end":"2026-02-01T18:55:14.514194Z","steps":["trace[1648367198] 'process raft request' (duration: 215.555669ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:15.075307Z","caller":"traceutil/trace.go:171","msg":"trace[1977394814] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"232.987004ms","start":"2026-02-01T18:55:14.842303Z","end":"2026-02-01T18:55:15.07529Z","steps":["trace[1977394814] 'process raft request' (duration: 232.869041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:15.640139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.369081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:15.640206Z","caller":"traceutil/trace.go:171","msg":"trace[2085001116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"497.481973ms","start":"2026-02-01T18:55:15.142708Z","end":"2026-02-01T18:55:15.64019Z","steps":["trace[2085001116] 'range keys from in-memory index tree' (duration: 497.300299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:15.640245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:15.142691Z","time spent":"497.547085ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:15.640141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.071683ms","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-02-01T18:55:15.640392Z","caller":"traceutil/trace.go:171","msg":"trace[2023393914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"271.373062ms","start":"2026-02-01T18:55:15.369011Z","end":"2026-02-01T18:55:15.640384Z","steps":["trace[2023393914] 'range keys from in-memory index tree' (duration: 270.9163ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:16.751688Z","caller":"traceutil/trace.go:171","msg":"trace[1285457027] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"231.615809ms","start":"2026-02-01T18:55:16.520056Z","end":"2026-02-01T18:55:16.751671Z","steps":["trace[1285457027] 'process raft request' (duration: 231.518177ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:16.7521Z","caller":"traceutil/trace.go:171","msg":"trace[1369592771] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:771; }","duration":"231.824814ms","start":"2026-02-01T18:55:16.520268Z","end":"2026-02-01T18:55:16.752092Z","steps":["trace[1369592771] 'read index received' (duration: 231.821874ms)","trace[1369592771] 'applied index is now lower than readState.Index' (duration: 2.43µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:16.752209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.921357ms","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-02-01T18:55:16.752226Z","caller":"traceutil/trace.go:171","msg":"trace[982738970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"231.954988ms","start":"2026-02-01T18:55:16.520266Z","end":"2026-02-01T18:55:16.752221Z","steps":["trace[982738970] 'agreement among raft nodes before linearized reading' (duration: 231.860865ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:16.753995Z","caller":"traceutil/trace.go:171","msg":"trace[746872271] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"231.506457ms","start":"2026-02-01T18:55:16.52248Z","end":"2026-02-01T18:55:16.753987Z","steps":["trace[746872271] 'process raft request' (duration: 231.377384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:16.754024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.428488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:16.754053Z","caller":"traceutil/trace.go:171","msg":"trace[1326685046] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:744; }","duration":"163.452768ms","start":"2026-02-01T18:55:16.590589Z","end":"2026-02-01T18:55:16.754042Z","steps":["trace[1326685046] 'agreement among raft nodes before linearized reading' (duration: 163.422068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:16.753993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.36034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:16.754109Z","caller":"traceutil/trace.go:171","msg":"trace[1618175624] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:744; }","duration":"214.522114ms","start":"2026-02-01T18:55:16.539581Z","end":"2026-02-01T18:55:16.754103Z","steps":["trace[1618175624] 'agreement among raft nodes before linearized reading' (duration: 214.3531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:23.10861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.46063ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-01T18:55:23.108678Z","caller":"traceutil/trace.go:171","msg":"trace[1419733291] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"334.585918ms","start":"2026-02-01T18:55:22.774081Z","end":"2026-02-01T18:55:23.108667Z","steps":["trace[1419733291] 'read index received' (duration: 19.69µs)","trace[1419733291] 'applied index is now lower than readState.Index' (duration: 334.564948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:23.108928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:22.77792Z","time spent":"331.005321ms","remote":"127.0.0.1:45300","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-01T18:55:23.109245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.153583ms","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-02-01T18:55:23.109262Z","caller":"traceutil/trace.go:171","msg":"trace[1521655931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"335.199315ms","start":"2026-02-01T18:55:22.774057Z","end":"2026-02-01T18:55:23.109256Z","steps":["trace[1521655931] 'agreement among raft nodes before linearized reading' (duration: 335.118333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:23.109275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:22.774044Z","time spent":"335.228265ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:23.109368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.307308ms","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-02-01T18:55:23.109383Z","caller":"traceutil/trace.go:171","msg":"trace[1667770872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"331.329139ms","start":"2026-02-01T18:55:22.778047Z","end":"2026-02-01T18:55:23.109377Z","steps":["trace[1667770872] 'agreement among raft nodes before linearized reading' (duration: 331.291648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:23.109395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:22.778025Z","time spent":"331.366879ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:23.109568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.664953ms","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-02-01T18:55:23.109581Z","caller":"traceutil/trace.go:171","msg":"trace[646573994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"326.687753ms","start":"2026-02-01T18:55:22.782889Z","end":"2026-02-01T18:55:23.109577Z","steps":["trace[646573994] 'agreement among raft nodes before linearized reading' (duration: 326.653842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:23.109594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:22.782884Z","time spent":"326.707783ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:23.438005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.921573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:23.43819Z","caller":"traceutil/trace.go:171","msg":"trace[196546791] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"321.38417ms","start":"2026-02-01T18:55:23.11678Z","end":"2026-02-01T18:55:23.438164Z","steps":["trace[196546791] 'process raft request' (duration: 193.201431ms)","trace[196546791] 'compare' (duration: 127.772139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:23.438318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:23.116771Z","time spent":"321.468092ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:23.438298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.250028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:23.438383Z","caller":"traceutil/trace.go:171","msg":"trace[2123688792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"293.358441ms","start":"2026-02-01T18:55:23.145014Z","end":"2026-02-01T18:55:23.438372Z","steps":["trace[2123688792] 'agreement among raft nodes before linearized reading' (duration: 293.217368ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:23.438203Z","caller":"traceutil/trace.go:171","msg":"trace[173751498] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:795; }","duration":"293.140966ms","start":"2026-02-01T18:55:23.145044Z","end":"2026-02-01T18:55:23.438185Z","steps":["trace[173751498] 'read index received' (duration: 62.139792ms)","trace[173751498] 'applied index is now lower than readState.Index' (duration: 231.000254ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:23.438527Z","caller":"traceutil/trace.go:171","msg":"trace[388304346] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"321.079172ms","start":"2026-02-01T18:55:23.117442Z","end":"2026-02-01T18:55:23.438521Z","steps":["trace[388304346] 'process raft request' (duration: 320.655192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:23.438557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:23.117422Z","time spent":"321.117813ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:23.438661Z","caller":"traceutil/trace.go:171","msg":"trace[1842931331] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"320.306932ms","start":"2026-02-01T18:55:23.118343Z","end":"2026-02-01T18:55:23.43865Z","steps":["trace[1842931331] 'process raft request' (duration: 319.80488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:23.438725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:23.118333Z","time spent":"320.356464ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:23.922746Z","caller":"traceutil/trace.go:171","msg":"trace[311188727] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"190.413761ms","start":"2026-02-01T18:55:23.732315Z","end":"2026-02-01T18:55:23.922729Z","steps":["trace[311188727] 'process raft request' (duration: 190.304178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.464524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.903663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:24.464585Z","caller":"traceutil/trace.go:171","msg":"trace[1930008291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"318.031376ms","start":"2026-02-01T18:55:24.14654Z","end":"2026-02-01T18:55:24.464572Z","steps":["trace[1930008291] 'range keys from in-memory index tree' (duration: 317.77125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:24.464633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.146517Z","time spent":"318.105287ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:24.464738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.986958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:24.464798Z","caller":"traceutil/trace.go:171","msg":"trace[881348320] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:770; }","duration":"133.091881ms","start":"2026-02-01T18:55:24.331691Z","end":"2026-02-01T18:55:24.464783Z","steps":["trace[881348320] 'count revisions from in-memory index tree' (duration: 132.930367ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:30.991132Z","caller":"traceutil/trace.go:171","msg":"trace[1529439911] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"137.949532ms","start":"2026-02-01T18:55:30.853158Z","end":"2026-02-01T18:55:30.991108Z","steps":["trace[1529439911] 'process raft request' (duration: 137.795518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:31.49154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.832285ms","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-02-01T18:55:31.491614Z","caller":"traceutil/trace.go:171","msg":"trace[189394620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"138.968468ms","start":"2026-02-01T18:55:31.352631Z","end":"2026-02-01T18:55:31.4916Z","steps":["trace[189394620] 'range keys from in-memory index tree' (duration: 138.685811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:31.492035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.149299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:31.492055Z","caller":"traceutil/trace.go:171","msg":"trace[423143480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"346.22201ms","start":"2026-02-01T18:55:31.145826Z","end":"2026-02-01T18:55:31.492048Z","steps":["trace[423143480] 'range keys from in-memory index tree' (duration: 346.078617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:31.492076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:31.145805Z","time spent":"346.262651ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:32.014222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089843913,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:55:32.087193Z","caller":"traceutil/trace.go:171","msg":"trace[678219011] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"573.223064ms","start":"2026-02-01T18:55:31.513947Z","end":"2026-02-01T18:55:32.087171Z","steps":["trace[678219011] 'read index received' (duration: 573.026219ms)","trace[678219011] 'applied index is now lower than readState.Index' (duration: 196.075µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:32.087306Z","caller":"traceutil/trace.go:171","msg":"trace[1367492128] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"590.096906ms","start":"2026-02-01T18:55:31.497199Z","end":"2026-02-01T18:55:32.087296Z","steps":["trace[1367492128] 'process raft request' (duration: 589.843419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:32.08744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:31.497188Z","time spent":"590.172917ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:32.087669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.942193ms","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-02-01T18:55:32.087739Z","caller":"traceutil/trace.go:171","msg":"trace[277275371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"562.049806ms","start":"2026-02-01T18:55:31.52567Z","end":"2026-02-01T18:55:32.08772Z","steps":["trace[277275371] 'agreement among raft nodes before linearized reading' (duration: 561.859742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:32.087782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:31.525657Z","time spent":"562.114337ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:32.088023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.565764ms","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-02-01T18:55:32.088062Z","caller":"traceutil/trace.go:171","msg":"trace[1350767756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"561.621255ms","start":"2026-02-01T18:55:31.526432Z","end":"2026-02-01T18:55:32.088053Z","steps":["trace[1350767756] 'agreement among raft nodes before linearized reading' (duration: 561.531743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:32.088088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:31.526423Z","time spent":"561.658686ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:32.088414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.472895ms","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-02-01T18:55:32.088443Z","caller":"traceutil/trace.go:171","msg":"trace[32753053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"574.545708ms","start":"2026-02-01T18:55:31.513889Z","end":"2026-02-01T18:55:32.088434Z","steps":["trace[32753053] 'agreement among raft nodes before linearized reading' (duration: 574.466276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:32.088469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:31.513862Z","time spent":"574.601358ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:32.092649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.981969ms","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-02-01T18:55:32.092755Z","caller":"traceutil/trace.go:171","msg":"trace[1935945245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"113.129443ms","start":"2026-02-01T18:55:31.979611Z","end":"2026-02-01T18:55:32.09274Z","steps":["trace[1935945245] 'agreement among raft nodes before linearized reading' (duration: 108.143039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:32.651089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089843916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:32.995147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.174226ms","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-02-01T18:55:32.998123Z","caller":"traceutil/trace.go:171","msg":"trace[463148807] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:789; }","duration":"829.177791ms","start":"2026-02-01T18:55:32.168927Z","end":"2026-02-01T18:55:32.998104Z","steps":["trace[463148807] 'range keys from in-memory index tree' (duration: 826.147326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:32.99656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.742544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:32.999389Z","caller":"traceutil/trace.go:171","msg":"trace[327212051] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"902.946502ms","start":"2026-02-01T18:55:32.096415Z","end":"2026-02-01T18:55:32.999361Z","steps":["trace[327212051] 'process raft request' (duration: 902.659655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:32.999968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.096405Z","time spent":"903.523776ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:32.999778Z","caller":"traceutil/trace.go:171","msg":"trace[1835905676] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"904.139182ms","start":"2026-02-01T18:55:32.095623Z","end":"2026-02-01T18:55:32.999762Z","steps":["trace[1835905676] 'process raft request' (duration: 691.125576ms)","trace[1835905676] 'compare' (duration: 209.652472ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:33.000361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.095606Z","time spent":"904.686056ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:32.999824Z","caller":"traceutil/trace.go:171","msg":"trace[1007285229] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"901.925257ms","start":"2026-02-01T18:55:32.09789Z","end":"2026-02-01T18:55:32.999815Z","steps":["trace[1007285229] 'process raft request' (duration: 901.264261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:33.002258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.097873Z","time spent":"904.344847ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:32.999865Z","caller":"traceutil/trace.go:171","msg":"trace[365717247] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"901.489186ms","start":"2026-02-01T18:55:32.09837Z","end":"2026-02-01T18:55:32.999859Z","steps":["trace[365717247] 'process raft request' (duration: 900.814339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:33.002974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.09836Z","time spent":"904.576063ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:33.1795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.257518ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-01T18:55:33.179615Z","caller":"traceutil/trace.go:171","msg":"trace[1367376420] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:820; }","duration":"1.029776126s","start":"2026-02-01T18:55:32.149819Z","end":"2026-02-01T18:55:33.179595Z","steps":["trace[1367376420] 'read index received' (duration: 636.946454ms)","trace[1367376420] 'applied index is now lower than readState.Index' (duration: 392.827912ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:33.179757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02992409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:33.179788Z","caller":"traceutil/trace.go:171","msg":"trace[742855057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"1.029998172s","start":"2026-02-01T18:55:32.14978Z","end":"2026-02-01T18:55:33.179779Z","steps":["trace[742855057] 'agreement among raft nodes before linearized reading' (duration: 1.02992614s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:33.179824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.149763Z","time spent":"1.030053535s","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:33.18013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.373642ms","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-02-01T18:55:33.180202Z","caller":"traceutil/trace.go:171","msg":"trace[388309198] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:793; }","duration":"806.505316ms","start":"2026-02-01T18:55:32.373677Z","end":"2026-02-01T18:55:33.180182Z","steps":["trace[388309198] 'agreement among raft nodes before linearized reading' (duration: 806.376902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:33.180248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.373657Z","time spent":"806.580207ms","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:33.180409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.386903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:55:33.180462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.11135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:33.180461Z","caller":"traceutil/trace.go:171","msg":"trace[611927820] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:793; }","duration":"368.623799ms","start":"2026-02-01T18:55:32.811823Z","end":"2026-02-01T18:55:33.180447Z","steps":["trace[611927820] 'agreement among raft nodes before linearized reading' (duration: 368.404334ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:33.180493Z","caller":"traceutil/trace.go:171","msg":"trace[1413755512] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:793; }","duration":"482.171032ms","start":"2026-02-01T18:55:32.698312Z","end":"2026-02-01T18:55:33.180483Z","steps":["trace[1413755512] 'agreement among raft nodes before linearized reading' (duration: 482.121261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:33.180517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.811798Z","time spent":"368.70428ms","remote":"127.0.0.1:53718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:33.180525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:32.698294Z","time spent":"482.220503ms","remote":"127.0.0.1:53798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:55:41.752455Z","caller":"traceutil/trace.go:171","msg":"trace[2101851390] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"120.894918ms","start":"2026-02-01T18:55:41.631549Z","end":"2026-02-01T18:55:41.752443Z","steps":["trace[2101851390] 'process raft request' (duration: 120.818776ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:41.913162Z","caller":"traceutil/trace.go:171","msg":"trace[784298621] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"151.723816ms","start":"2026-02-01T18:55:41.761419Z","end":"2026-02-01T18:55:41.913143Z","steps":["trace[784298621] 'process raft request' (duration: 151.695285ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:41.91344Z","caller":"traceutil/trace.go:171","msg":"trace[186181314] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"153.416769ms","start":"2026-02-01T18:55:41.760015Z","end":"2026-02-01T18:55:41.913432Z","steps":["trace[186181314] 'process raft request' (duration: 146.816924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.396615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.052069ms","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-02-01T18:55:45.396678Z","caller":"traceutil/trace.go:171","msg":"trace[794102119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"258.153383ms","start":"2026-02-01T18:55:45.138509Z","end":"2026-02-01T18:55:45.396662Z","steps":["trace[794102119] 'range keys from in-memory index tree' (duration: 257.861884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.396709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.334091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-01T18:55:45.396754Z","caller":"traceutil/trace.go:171","msg":"trace[1901297399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"257.451264ms","start":"2026-02-01T18:55:45.139292Z","end":"2026-02-01T18:55:45.396743Z","steps":["trace[1901297399] 'range keys from in-memory index tree' (duration: 257.238318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:45.396895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.317977ms","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-02-01T18:55:45.396916Z","caller":"traceutil/trace.go:171","msg":"trace[1812887548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"258.345647ms","start":"2026-02-01T18:55:45.138565Z","end":"2026-02-01T18:55:45.396911Z","steps":["trace[1812887548] 'range keys from in-memory index tree' (duration: 258.270526ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:45.516845Z","caller":"traceutil/trace.go:171","msg":"trace[1774854852] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"110.879497ms","start":"2026-02-01T18:55:45.40595Z","end":"2026-02-01T18:55:45.51683Z","steps":["trace[1774854852] 'process raft request' (duration: 110.854796ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:45.51716Z","caller":"traceutil/trace.go:171","msg":"trace[449953955] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"112.359805ms","start":"2026-02-01T18:55:45.404794Z","end":"2026-02-01T18:55:45.517154Z","steps":["trace[449953955] 'process raft request' (duration: 111.917003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:46.560661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.240067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:46.560727Z","caller":"traceutil/trace.go:171","msg":"trace[1140994676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"418.341308ms","start":"2026-02-01T18:55:46.14237Z","end":"2026-02-01T18:55:46.560711Z","steps":["trace[1140994676] 'range keys from in-memory index tree' (duration: 418.164054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:46.560762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:46.142353Z","time spent":"418.4017ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:46.56102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.332352ms","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-02-01T18:55:46.571746Z","caller":"traceutil/trace.go:171","msg":"trace[413867207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"340.382173ms","start":"2026-02-01T18:55:46.220656Z","end":"2026-02-01T18:55:46.561038Z","steps":["trace[413867207] 'range keys from in-memory index tree' (duration: 340.217219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:46.571815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:46.220631Z","time spent":"351.160073ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:55:47.489196Z","caller":"traceutil/trace.go:171","msg":"trace[104198235] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"346.113107ms","start":"2026-02-01T18:55:47.14306Z","end":"2026-02-01T18:55:47.489173Z","steps":["trace[104198235] 'read index received' (duration: 345.940943ms)","trace[104198235] 'applied index is now lower than readState.Index' (duration: 171.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:47.489307Z","caller":"traceutil/trace.go:171","msg":"trace[452501405] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"378.576388ms","start":"2026-02-01T18:55:47.11072Z","end":"2026-02-01T18:55:47.489296Z","steps":["trace[452501405] 'process raft request' (duration: 378.337371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.489385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.988428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-01T18:55:47.489409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.11071Z","time spent":"378.622619ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:47.48943Z","caller":"traceutil/trace.go:171","msg":"trace[391872074] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:880; }","duration":"159.07616ms","start":"2026-02-01T18:55:47.330341Z","end":"2026-02-01T18:55:47.489417Z","steps":["trace[391872074] 'agreement among raft nodes before linearized reading' (duration: 158.955717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.48954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.479807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:47.489553Z","caller":"traceutil/trace.go:171","msg":"trace[149811482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"346.517857ms","start":"2026-02-01T18:55:47.143031Z","end":"2026-02-01T18:55:47.489549Z","steps":["trace[149811482] 'agreement among raft nodes before linearized reading' (duration: 346.497066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.489565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.143018Z","time spent":"346.543958ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:47.489788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.117078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:47.489826Z","caller":"traceutil/trace.go:171","msg":"trace[2044027856] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:880; }","duration":"138.175429ms","start":"2026-02-01T18:55:47.351639Z","end":"2026-02-01T18:55:47.489815Z","steps":["trace[2044027856] 'agreement among raft nodes before linearized reading' (duration: 138.109827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.815013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.556918ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-01T18:55:47.81525Z","caller":"traceutil/trace.go:171","msg":"trace[1973115035] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"298.9281ms","start":"2026-02-01T18:55:47.516304Z","end":"2026-02-01T18:55:47.815233Z","steps":["trace[1973115035] 'read index received' (duration: 67.086224ms)","trace[1973115035] 'applied index is now lower than readState.Index' (duration: 231.841045ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:47.815362Z","caller":"traceutil/trace.go:171","msg":"trace[1347809247] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"320.395894ms","start":"2026-02-01T18:55:47.494957Z","end":"2026-02-01T18:55:47.815353Z","steps":["trace[1347809247] 'process raft request' (duration: 320.157409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.815451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.494945Z","time spent":"320.443155ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:47.815544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.491834Z","time spent":"323.701418ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-01T18:55:47.815676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.36863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:47.815711Z","caller":"traceutil/trace.go:171","msg":"trace[1849452574] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:881; }","duration":"299.452002ms","start":"2026-02-01T18:55:47.516249Z","end":"2026-02-01T18:55:47.815701Z","steps":["trace[1849452574] 'agreement among raft nodes before linearized reading' (duration: 299.395551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.815932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.006252ms","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-02-01T18:55:47.815962Z","caller":"traceutil/trace.go:171","msg":"trace[787108974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:881; }","duration":"295.057032ms","start":"2026-02-01T18:55:47.520897Z","end":"2026-02-01T18:55:47.815954Z","steps":["trace[787108974] 'agreement among raft nodes before linearized reading' (duration: 294.95302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:47.816127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.165925ms","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-02-01T18:55:47.816156Z","caller":"traceutil/trace.go:171","msg":"trace[244483044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"295.216546ms","start":"2026-02-01T18:55:47.520932Z","end":"2026-02-01T18:55:47.816148Z","steps":["trace[244483044] 'agreement among raft nodes before linearized reading' (duration: 295.139384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:48.650222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089844125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:49.14978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001918571s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:49.149899Z","caller":"traceutil/trace.go:171","msg":"trace[317425396] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"1.332288215s","start":"2026-02-01T18:55:47.817592Z","end":"2026-02-01T18:55:49.14988Z","steps":["trace[317425396] 'process raft request' (duration: 330.19445ms)","trace[317425396] 'compare' (duration: 1.001703765s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:49.149955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.817571Z","time spent":"1.332356507s","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:49.153118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089844125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:49.653208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089844125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:49.766641Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.618922427s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T18:55:50.149968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00011962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-01T18:55:50.150032Z","caller":"traceutil/trace.go:171","msg":"trace[1530872074] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000229423s","start":"2026-02-01T18:55:48.149788Z","end":"2026-02-01T18:55:50.150018Z","steps":["trace[1530872074] 'agreement among raft nodes before linearized reading' (duration: 2.000148811s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.150069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:48.14977Z","time spent":"2.000291885s","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/01 18:55:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-01T18:55:50.360256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.337995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:50.360393Z","caller":"traceutil/trace.go:171","msg":"trace[1262987829] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"2.535606703s","start":"2026-02-01T18:55:47.824769Z","end":"2026-02-01T18:55:50.360375Z","steps":["trace[1262987829] 'process raft request' (duration: 2.535545022s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.360435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.824753Z","time spent":"2.535664064s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:50.360446Z","caller":"traceutil/trace.go:171","msg":"trace[83598789] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"2.538493045s","start":"2026-02-01T18:55:47.821929Z","end":"2026-02-01T18:55:50.360422Z","steps":["trace[83598789] 'process raft request' (duration: 1.944940605s)","trace[83598789] 'compare' (duration: 593.214762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.360551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:47.82191Z","time spent":"2.538581397s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:50.360886Z","caller":"traceutil/trace.go:171","msg":"trace[1110378605] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:918; }","duration":"2.211033044s","start":"2026-02-01T18:55:48.149826Z","end":"2026-02-01T18:55:50.360859Z","steps":["trace[1110378605] 'read index received' (duration: 1.617052152s)","trace[1110378605] 'applied index is now lower than readState.Index' (duration: 593.980312ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:50.360987Z","caller":"traceutil/trace.go:171","msg":"trace[1453959063] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"2.062779715s","start":"2026-02-01T18:55:48.298202Z","end":"2026-02-01T18:55:50.360981Z","steps":["trace[1453959063] 'process raft request' (duration: 2.062608111s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.361024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:48.29818Z","time spent":"2.062821357s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:50.361177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695866789s","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-02-01T18:55:50.361198Z","caller":"traceutil/trace.go:171","msg":"trace[1375645274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"1.69592937s","start":"2026-02-01T18:55:48.665262Z","end":"2026-02-01T18:55:50.361192Z","steps":["trace[1375645274] 'agreement among raft nodes before linearized reading' (duration: 1.695847188s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.361219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:48.665245Z","time spent":"1.695969632s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:50.361318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.043067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.361336Z","caller":"traceutil/trace.go:171","msg":"trace[1882795143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"211.083078ms","start":"2026-02-01T18:55:50.150246Z","end":"2026-02-01T18:55:50.36133Z","steps":["trace[1882795143] 'agreement among raft nodes before linearized reading' (duration: 211.056397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.361426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.186181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.361442Z","caller":"traceutil/trace.go:171","msg":"trace[1176876361] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:885; }","duration":"403.205931ms","start":"2026-02-01T18:55:49.958231Z","end":"2026-02-01T18:55:50.361437Z","steps":["trace[1176876361] 'agreement among raft nodes before linearized reading' (duration: 403.178911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.361458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.958197Z","time spent":"403.256533ms","remote":"127.0.0.1:53596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:50.361564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.257846ms","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-02-01T18:55:50.36158Z","caller":"traceutil/trace.go:171","msg":"trace[1178323537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"541.296037ms","start":"2026-02-01T18:55:49.820279Z","end":"2026-02-01T18:55:50.361575Z","steps":["trace[1178323537] 'agreement among raft nodes before linearized reading' (duration: 541.248926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.361595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.820263Z","time spent":"541.328608ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:50.361683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.376504ms","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-02-01T18:55:50.361701Z","caller":"traceutil/trace.go:171","msg":"trace[819434750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"867.415816ms","start":"2026-02-01T18:55:49.49428Z","end":"2026-02-01T18:55:50.361696Z","steps":["trace[819434750] 'agreement among raft nodes before linearized reading' (duration: 867.371585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.361716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.494268Z","time spent":"867.444406ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:55:50.361792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208165178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-01T18:55:50.361807Z","caller":"traceutil/trace.go:171","msg":"trace[1105070572] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:885; }","duration":"1.208210029s","start":"2026-02-01T18:55:49.153593Z","end":"2026-02-01T18:55:50.361803Z","steps":["trace[1105070572] 'agreement among raft nodes before linearized reading' (duration: 1.208180949s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.361822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.153577Z","time spent":"1.20824182s","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-01T18:55:50.496196Z","caller":"traceutil/trace.go:171","msg":"trace[1471053531] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"118.328263ms","start":"2026-02-01T18:55:50.377854Z","end":"2026-02-01T18:55:50.496182Z","steps":["trace[1471053531] 'process raft request' (duration: 118.307162ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:50.49706Z","caller":"traceutil/trace.go:171","msg":"trace[417027491] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"120.302232ms","start":"2026-02-01T18:55:50.376749Z","end":"2026-02-01T18:55:50.497051Z","steps":["trace[417027491] 'process raft request' (duration: 110.945319ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:50.497132Z","caller":"traceutil/trace.go:171","msg":"trace[970623703] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"120.342443ms","start":"2026-02-01T18:55:50.376786Z","end":"2026-02-01T18:55:50.497128Z","steps":["trace[970623703] 'process raft request' (duration: 119.356139ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:51.724971Z","caller":"traceutil/trace.go:171","msg":"trace[593926455] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"259.858705ms","start":"2026-02-01T18:55:51.465097Z","end":"2026-02-01T18:55:51.724956Z","steps":["trace[593926455] 'process raft request' (duration: 259.766722ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:57.736756Z","caller":"traceutil/trace.go:171","msg":"trace[175722510] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"196.060232ms","start":"2026-02-01T18:55:57.54068Z","end":"2026-02-01T18:55:57.73674Z","steps":["trace[175722510] 'read index received' (duration: 195.967919ms)","trace[175722510] 'applied index is now lower than readState.Index' (duration: 91.593µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:57.736777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:57.330945Z","time spent":"405.818047ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-01T18:55:57.736836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.139224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:57.736857Z","caller":"traceutil/trace.go:171","msg":"trace[1222886970] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:905; }","duration":"196.176445ms","start":"2026-02-01T18:55:57.540675Z","end":"2026-02-01T18:55:57.736852Z","steps":["trace[1222886970] 'agreement among raft nodes before linearized reading' (duration: 196.118063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:58.200934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.952673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:58.200993Z","caller":"traceutil/trace.go:171","msg":"trace[1171498931] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"255.437954ms","start":"2026-02-01T18:55:57.945546Z","end":"2026-02-01T18:55:58.200983Z","steps":["trace[1171498931] 'read index received' (duration: 115.358199ms)","trace[1171498931] 'applied index is now lower than readState.Index' (duration: 140.079115ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:58.20105Z","caller":"traceutil/trace.go:171","msg":"trace[204145383] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"463.607709ms","start":"2026-02-01T18:55:57.737437Z","end":"2026-02-01T18:55:58.201045Z","steps":["trace[204145383] 'process raft request' (duration: 323.498692ms)","trace[204145383] 'compare' (duration: 139.884781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:58.201079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:57.737424Z","time spent":"463.641709ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:58.201232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.68581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:58.201251Z","caller":"traceutil/trace.go:171","msg":"trace[2090401279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"255.725592ms","start":"2026-02-01T18:55:57.94552Z","end":"2026-02-01T18:55:58.201246Z","steps":["trace[2090401279] 'agreement among raft nodes before linearized reading' (duration: 255.694661ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:04.819741Z","caller":"traceutil/trace.go:171","msg":"trace[9857312] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"277.790261ms","start":"2026-02-01T18:56:04.541928Z","end":"2026-02-01T18:56:04.819719Z","steps":["trace[9857312] 'read index received' (duration: 277.528115ms)","trace[9857312] 'applied index is now lower than readState.Index' (duration: 261.266µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:04.819863Z","caller":"traceutil/trace.go:171","msg":"trace[1541702669] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"312.332374ms","start":"2026-02-01T18:56:04.50752Z","end":"2026-02-01T18:56:04.819852Z","steps":["trace[1541702669] 'process raft request' (duration: 312.006486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:04.819963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.50751Z","time spent":"312.377845ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:04.820074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.173856ms","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-02-01T18:56:04.820117Z","caller":"traceutil/trace.go:171","msg":"trace[758804900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"273.251918ms","start":"2026-02-01T18:56:04.546855Z","end":"2026-02-01T18:56:04.820107Z","steps":["trace[758804900] 'agreement among raft nodes before linearized reading' (duration: 273.136815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:04.820203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.279524ms","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-02-01T18:56:04.820215Z","caller":"traceutil/trace.go:171","msg":"trace[1832615391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"278.326384ms","start":"2026-02-01T18:56:04.541884Z","end":"2026-02-01T18:56:04.820211Z","steps":["trace[1832615391] 'agreement among raft nodes before linearized reading' (duration: 278.293323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:04.820357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.945336ms","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-02-01T18:56:04.820394Z","caller":"traceutil/trace.go:171","msg":"trace[1678037160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"262.001358ms","start":"2026-02-01T18:56:04.558381Z","end":"2026-02-01T18:56:04.820383Z","steps":["trace[1678037160] 'agreement among raft nodes before linearized reading' (duration: 261.886345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:04.820415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.706992ms","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-02-01T18:56:04.820435Z","caller":"traceutil/trace.go:171","msg":"trace[1026598868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"211.740953ms","start":"2026-02-01T18:56:04.608689Z","end":"2026-02-01T18:56:04.82043Z","steps":["trace[1026598868] 'agreement among raft nodes before linearized reading' (duration: 211.697182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:05.01171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.663021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:05.012341Z","caller":"traceutil/trace.go:171","msg":"trace[1112173213] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"187.202012ms","start":"2026-02-01T18:56:04.825108Z","end":"2026-02-01T18:56:05.01231Z","steps":["trace[1112173213] 'process raft request' (duration: 69.861983ms)","trace[1112173213] 'compare' (duration: 116.547218ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:05.339578Z","caller":"traceutil/trace.go:171","msg":"trace[1579234443] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"513.223858ms","start":"2026-02-01T18:56:04.826336Z","end":"2026-02-01T18:56:05.33956Z","steps":["trace[1579234443] 'process raft request' (duration: 449.594901ms)","trace[1579234443] 'compare' (duration: 63.234677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:05.339672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.826331Z","time spent":"513.29393ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:05.341468Z","caller":"traceutil/trace.go:171","msg":"trace[1619742495] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"513.773172ms","start":"2026-02-01T18:56:04.827686Z","end":"2026-02-01T18:56:05.341459Z","steps":["trace[1619742495] 'process raft request' (duration: 511.70154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:05.341521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.827678Z","time spent":"513.818853ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:05.342248Z","caller":"traceutil/trace.go:171","msg":"trace[65836396] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:964; }","duration":"195.15772ms","start":"2026-02-01T18:56:05.14708Z","end":"2026-02-01T18:56:05.342238Z","steps":["trace[65836396] 'read index received' (duration: 128.805675ms)","trace[65836396] 'applied index is now lower than readState.Index' (duration: 66.351395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:05.342309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.224692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:05.342329Z","caller":"traceutil/trace.go:171","msg":"trace[1283365039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"195.247202ms","start":"2026-02-01T18:56:05.147074Z","end":"2026-02-01T18:56:05.342322Z","steps":["trace[1283365039] 'agreement among raft nodes before linearized reading' (duration: 195.200161ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:05.342827Z","caller":"traceutil/trace.go:171","msg":"trace[280166698] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"512.680845ms","start":"2026-02-01T18:56:04.830137Z","end":"2026-02-01T18:56:05.342818Z","steps":["trace[280166698] 'process raft request' (duration: 509.389762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:05.342873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:04.830129Z","time spent":"512.718676ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:07.226695Z","caller":"traceutil/trace.go:171","msg":"trace[1130096283] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"206.000677ms","start":"2026-02-01T18:56:07.020675Z","end":"2026-02-01T18:56:07.226675Z","steps":["trace[1130096283] 'process raft request' (duration: 205.641128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:07.803642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.146622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:56:07.803708Z","caller":"traceutil/trace.go:171","msg":"trace[801687885] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:930; }","duration":"472.252965ms","start":"2026-02-01T18:56:07.331438Z","end":"2026-02-01T18:56:07.803691Z","steps":["trace[801687885] 'range keys from in-memory index tree' (duration: 472.006849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:07.803748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:07.331421Z","time spent":"472.315977ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-02-01T18:56:07.803957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.03525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-01T18:56:07.803946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.724647ms","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-02-01T18:56:07.803985Z","caller":"traceutil/trace.go:171","msg":"trace[633077739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"456.07157ms","start":"2026-02-01T18:56:07.347905Z","end":"2026-02-01T18:56:07.803977Z","steps":["trace[633077739] 'range keys from in-memory index tree' (duration: 455.975249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:07.804011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:07.347901Z","time spent":"456.105042ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-01T18:56:07.804013Z","caller":"traceutil/trace.go:171","msg":"trace[557529586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"458.807689ms","start":"2026-02-01T18:56:07.345187Z","end":"2026-02-01T18:56:07.803995Z","steps":["trace[557529586] 'range keys from in-memory index tree' (duration: 458.610905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:07.804054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:07.345183Z","time spent":"458.861631ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:07.804356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.189568ms","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-02-01T18:56:07.804388Z","caller":"traceutil/trace.go:171","msg":"trace[2008257447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"461.234639ms","start":"2026-02-01T18:56:07.343142Z","end":"2026-02-01T18:56:07.804377Z","steps":["trace[2008257447] 'range keys from in-memory index tree' (duration: 461.054875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:07.804419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:07.343133Z","time spent":"461.27968ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:56:08.127671Z","caller":"traceutil/trace.go:171","msg":"trace[152947237] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"317.892571ms","start":"2026-02-01T18:56:07.809758Z","end":"2026-02-01T18:56:08.127651Z","steps":["trace[152947237] 'process raft request' (duration: 310.018924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:08.12789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:07.80975Z","time spent":"318.032084ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:08.127679Z","caller":"traceutil/trace.go:171","msg":"trace[1911731369] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"316.26324ms","start":"2026-02-01T18:56:07.811389Z","end":"2026-02-01T18:56:08.127652Z","steps":["trace[1911731369] 'process raft request' (duration: 316.218129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:08.128037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:07.811381Z","time spent":"316.600748ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:08.127726Z","caller":"traceutil/trace.go:171","msg":"trace[1318564914] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"317.927922ms","start":"2026-02-01T18:56:07.809792Z","end":"2026-02-01T18:56:08.12772Z","steps":["trace[1318564914] 'process raft request' (duration: 317.784328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:08.12814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:07.809789Z","time spent":"318.333512ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:08.148666Z","caller":"traceutil/trace.go:171","msg":"trace[130083706] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"233.949117ms","start":"2026-02-01T18:56:07.914701Z","end":"2026-02-01T18:56:08.148651Z","steps":["trace[130083706] 'process raft request' (duration: 233.862325ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:09.124231Z","caller":"traceutil/trace.go:171","msg":"trace[1840181494] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"276.545594ms","start":"2026-02-01T18:56:08.847467Z","end":"2026-02-01T18:56:09.124012Z","steps":["trace[1840181494] 'process raft request' (duration: 276.411121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:09.975825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.338247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:09.975893Z","caller":"traceutil/trace.go:171","msg":"trace[2020527817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"831.440509ms","start":"2026-02-01T18:56:09.144436Z","end":"2026-02-01T18:56:09.975876Z","steps":["trace[2020527817] 'range keys from in-memory index tree' (duration: 831.282485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:09.975947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:09.144421Z","time spent":"831.517892ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:56:09.976373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.097718ms","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-02-01T18:56:09.976401Z","caller":"traceutil/trace.go:171","msg":"trace[937329071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"745.339464ms","start":"2026-02-01T18:56:09.231052Z","end":"2026-02-01T18:56:09.976392Z","steps":["trace[937329071] 'range keys from in-memory index tree' (duration: 744.875072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:09.976426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:09.231034Z","time spent":"745.386135ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T18:56:10.131591Z","caller":"traceutil/trace.go:171","msg":"trace[1629734124] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"146.060267ms","start":"2026-02-01T18:56:09.985507Z","end":"2026-02-01T18:56:10.131567Z","steps":["trace[1629734124] 'process raft request' (duration: 145.936204ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:10.303886Z","caller":"traceutil/trace.go:171","msg":"trace[2041957050] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"158.412057ms","start":"2026-02-01T18:56:10.145454Z","end":"2026-02-01T18:56:10.303866Z","steps":["trace[2041957050] 'read index received' (duration: 66.467285ms)","trace[2041957050] 'applied index is now lower than readState.Index' (duration: 91.943912ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:10.303934Z","caller":"traceutil/trace.go:171","msg":"trace[1247552303] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"165.259908ms","start":"2026-02-01T18:56:10.138648Z","end":"2026-02-01T18:56:10.303907Z","steps":["trace[1247552303] 'process raft request' (duration: 165.109694ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:10.304098Z","caller":"traceutil/trace.go:171","msg":"trace[2063294112] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"165.69763ms","start":"2026-02-01T18:56:10.138389Z","end":"2026-02-01T18:56:10.304086Z","steps":["trace[2063294112] 'process raft request' (duration: 73.482251ms)","trace[2063294112] 'compare' (duration: 91.608783ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:10.304159Z","caller":"traceutil/trace.go:171","msg":"trace[2076779935] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"159.453243ms","start":"2026-02-01T18:56:10.144691Z","end":"2026-02-01T18:56:10.304145Z","steps":["trace[2076779935] 'process raft request' (duration: 159.113475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:10.304335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.865368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:10.30437Z","caller":"traceutil/trace.go:171","msg":"trace[1407409481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"158.930559ms","start":"2026-02-01T18:56:10.145429Z","end":"2026-02-01T18:56:10.30436Z","steps":["trace[1407409481] 'agreement among raft nodes before linearized reading' (duration: 158.864588ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:17.523564Z","caller":"traceutil/trace.go:171","msg":"trace[1171144353] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"326.983168ms","start":"2026-02-01T18:56:17.196557Z","end":"2026-02-01T18:56:17.52354Z","steps":["trace[1171144353] 'process raft request' (duration: 326.627119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:17.523792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:17.196541Z","time spent":"327.108281ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:17.5241Z","caller":"traceutil/trace.go:171","msg":"trace[1125087134] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"192.436849ms","start":"2026-02-01T18:56:17.33165Z","end":"2026-02-01T18:56:17.524087Z","steps":["trace[1125087134] 'read index received' (duration: 191.474965ms)","trace[1125087134] 'applied index is now lower than readState.Index' (duration: 960.634µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:17.524246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.595393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:56:17.524283Z","caller":"traceutil/trace.go:171","msg":"trace[785781694] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:984; }","duration":"192.662215ms","start":"2026-02-01T18:56:17.331611Z","end":"2026-02-01T18:56:17.524273Z","steps":["trace[785781694] 'agreement among raft nodes before linearized reading' (duration: 192.544032ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:21.943972Z","caller":"traceutil/trace.go:171","msg":"trace[1014381648] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"406.382026ms","start":"2026-02-01T18:56:21.537566Z","end":"2026-02-01T18:56:21.943948Z","steps":["trace[1014381648] 'process raft request' (duration: 406.200931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:21.944111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:21.537557Z","time spent":"406.482008ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:22.271919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.231265ms","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-02-01T18:56:22.271999Z","caller":"traceutil/trace.go:171","msg":"trace[1806932241] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1011; }","duration":"103.318077ms","start":"2026-02-01T18:56:22.168668Z","end":"2026-02-01T18:56:22.271987Z","steps":["trace[1806932241] 'range keys from in-memory index tree' (duration: 103.221645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:22.895171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089844421,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:56:22.963024Z","caller":"traceutil/trace.go:171","msg":"trace[400248980] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"580.66822ms","start":"2026-02-01T18:56:22.382334Z","end":"2026-02-01T18:56:22.963002Z","steps":["trace[400248980] 'process raft request' (duration: 580.548097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:22.963182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.382313Z","time spent":"580.777303ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:23.170175Z","caller":"traceutil/trace.go:171","msg":"trace[1927562730] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1055; }","duration":"775.817517ms","start":"2026-02-01T18:56:22.394337Z","end":"2026-02-01T18:56:23.170154Z","steps":["trace[1927562730] 'read index received' (duration: 569.264165ms)","trace[1927562730] 'applied index is now lower than readState.Index' (duration: 206.552402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:23.170432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.078143ms","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-02-01T18:56:23.170486Z","caller":"traceutil/trace.go:171","msg":"trace[1412626772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"776.158796ms","start":"2026-02-01T18:56:22.394313Z","end":"2026-02-01T18:56:23.170472Z","steps":["trace[1412626772] 'agreement among raft nodes before linearized reading' (duration: 776.002462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:23.170512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.394302Z","time spent":"776.204067ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:23.170514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.022742ms","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-02-01T18:56:23.170553Z","caller":"traceutil/trace.go:171","msg":"trace[2079549496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"385.101653ms","start":"2026-02-01T18:56:22.785442Z","end":"2026-02-01T18:56:23.170543Z","steps":["trace[2079549496] 'agreement among raft nodes before linearized reading' (duration: 384.960429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:23.170607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.785427Z","time spent":"385.171716ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-01T18:56:23.17066Z","caller":"traceutil/trace.go:171","msg":"trace[612092075] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"634.262763ms","start":"2026-02-01T18:56:22.536387Z","end":"2026-02-01T18:56:23.17065Z","steps":["trace[612092075] 'process raft request' (duration: 542.924846ms)","trace[612092075] 'compare' (duration: 90.666719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:23.17073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.536369Z","time spent":"634.321394ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:23.170829Z","caller":"traceutil/trace.go:171","msg":"trace[683875150] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"372.679212ms","start":"2026-02-01T18:56:22.798143Z","end":"2026-02-01T18:56:23.170822Z","steps":["trace[683875150] 'process raft request' (duration: 371.947363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:23.17087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:22.798128Z","time spent":"372.722983ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:23.574845Z","caller":"traceutil/trace.go:171","msg":"trace[362845355] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"392.901508ms","start":"2026-02-01T18:56:23.181921Z","end":"2026-02-01T18:56:23.574822Z","steps":["trace[362845355] 'process raft request' (duration: 299.52182ms)","trace[362845355] 'compare' (duration: 92.993848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:23.574948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:23.181909Z","time spent":"392.98577ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:23.925797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.21914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:23.926133Z","caller":"traceutil/trace.go:171","msg":"trace[673717414] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"743.22467ms","start":"2026-02-01T18:56:23.182887Z","end":"2026-02-01T18:56:23.926111Z","steps":["trace[673717414] 'process raft request' (duration: 513.619811ms)","trace[673717414] 'compare' (duration: 229.051375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:23.926218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:23.18287Z","time spent":"743.310223ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:23.926286Z","caller":"traceutil/trace.go:171","msg":"trace[794221324] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"742.159924ms","start":"2026-02-01T18:56:23.184102Z","end":"2026-02-01T18:56:23.926262Z","steps":["trace[794221324] 'process raft request' (duration: 741.771524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:23.926407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:23.184094Z","time spent":"742.247346ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:26.156449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.01113356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-01T18:56:26.156533Z","caller":"traceutil/trace.go:171","msg":"trace[813862822] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.011262493s","start":"2026-02-01T18:56:24.145254Z","end":"2026-02-01T18:56:26.156516Z","steps":["trace[813862822] 'agreement among raft nodes before linearized reading' (duration: 2.011152811s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.156625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:24.145227Z","time spent":"2.011379016s","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/01 18:56:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-01T18:56:26.579515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.65097226s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-01T18:56:26.57967Z","caller":"traceutil/trace.go:171","msg":"trace[1318952318] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"2.627926334s","start":"2026-02-01T18:56:23.951716Z","end":"2026-02-01T18:56:26.579642Z","steps":["trace[1318952318] 'read index received' (duration: 38.231µs)","trace[1318952318] 'applied index is now lower than readState.Index' (duration: 2.627883803s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:26.58104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.629301499s","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-02-01T18:56:26.581093Z","caller":"traceutil/trace.go:171","msg":"trace[889506322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"2.629402752s","start":"2026-02-01T18:56:23.951677Z","end":"2026-02-01T18:56:26.58108Z","steps":["trace[889506322] 'agreement among raft nodes before linearized reading' (duration: 2.629217937s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.581135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:23.951639Z","time spent":"2.629487364s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:26.581385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613108204s","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-02-01T18:56:26.581422Z","caller":"traceutil/trace.go:171","msg":"trace[1348756105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"1.613144465s","start":"2026-02-01T18:56:24.968268Z","end":"2026-02-01T18:56:26.581412Z","steps":["trace[1348756105] 'agreement among raft nodes before linearized reading' (duration: 1.613049462s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.581456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:24.968225Z","time spent":"1.613224627s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:56:26.58171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.113024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:26.581747Z","caller":"traceutil/trace.go:171","msg":"trace[1367921997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"151.169685ms","start":"2026-02-01T18:56:26.430568Z","end":"2026-02-01T18:56:26.581738Z","steps":["trace[1367921997] 'agreement among raft nodes before linearized reading' (duration: 151.112763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.581904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.379861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:56:26.581939Z","caller":"traceutil/trace.go:171","msg":"trace[1663234434] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1017; }","duration":"151.463863ms","start":"2026-02-01T18:56:26.430467Z","end":"2026-02-01T18:56:26.581931Z","steps":["trace[1663234434] 'agreement among raft nodes before linearized reading' (duration: 151.407271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.582073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.912204ms","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-02-01T18:56:26.58211Z","caller":"traceutil/trace.go:171","msg":"trace[1096086978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"651.974945ms","start":"2026-02-01T18:56:25.930127Z","end":"2026-02-01T18:56:26.582102Z","steps":["trace[1096086978] 'agreement among raft nodes before linearized reading' (duration: 651.917283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.582138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:25.930095Z","time spent":"652.036387ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:26.582314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.264007ms","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-02-01T18:56:26.582349Z","caller":"traceutil/trace.go:171","msg":"trace[2123860431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"998.321418ms","start":"2026-02-01T18:56:25.584019Z","end":"2026-02-01T18:56:26.582341Z","steps":["trace[2123860431] 'agreement among raft nodes before linearized reading' (duration: 998.225855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.582377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:25.584004Z","time spent":"998.366249ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:26.582536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16372024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-01T18:56:26.58257Z","caller":"traceutil/trace.go:171","msg":"trace[1772581955] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1017; }","duration":"1.163789302s","start":"2026-02-01T18:56:25.418773Z","end":"2026-02-01T18:56:26.582562Z","steps":["trace[1772581955] 'agreement among raft nodes before linearized reading' (duration: 1.163707999s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.582604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:25.418753Z","time spent":"1.163844444s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-01T18:56:26.582767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405248658s","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-02-01T18:56:26.5828Z","caller":"traceutil/trace.go:171","msg":"trace[1747368413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"1.40531586s","start":"2026-02-01T18:56:25.177476Z","end":"2026-02-01T18:56:26.582792Z","steps":["trace[1747368413] 'agreement among raft nodes before linearized reading' (duration: 1.405234878s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.582833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:25.177458Z","time spent":"1.405368702s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:56:26.582991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573691376s","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:536"} {"level":"info","ts":"2026-02-01T18:56:26.583027Z","caller":"traceutil/trace.go:171","msg":"trace[262438399] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1017; }","duration":"1.573756888s","start":"2026-02-01T18:56:25.009261Z","end":"2026-02-01T18:56:26.583018Z","steps":["trace[262438399] 'agreement among raft nodes before linearized reading' (duration: 1.573672026s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.583063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:25.009246Z","time spent":"1.573807519s","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-01T18:56:26.833436Z","caller":"traceutil/trace.go:171","msg":"trace[637966627] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"233.963318ms","start":"2026-02-01T18:56:26.599452Z","end":"2026-02-01T18:56:26.833416Z","steps":["trace[637966627] 'read index received' (duration: 60.142095ms)","trace[637966627] 'applied index is now lower than readState.Index' (duration: 173.820203ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:26.834038Z","caller":"traceutil/trace.go:171","msg":"trace[368510531] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"237.464105ms","start":"2026-02-01T18:56:26.596534Z","end":"2026-02-01T18:56:26.833998Z","steps":["trace[368510531] 'process raft request' (duration: 152.9886ms)","trace[368510531] 'compare' (duration: 83.646625ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:26.834358Z","caller":"traceutil/trace.go:171","msg":"trace[1444476185] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"233.23042ms","start":"2026-02-01T18:56:26.601098Z","end":"2026-02-01T18:56:26.834328Z","steps":["trace[1444476185] 'process raft request' (duration: 232.184434ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.834479Z","caller":"traceutil/trace.go:171","msg":"trace[2047637051] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"231.336172ms","start":"2026-02-01T18:56:26.603136Z","end":"2026-02-01T18:56:26.834472Z","steps":["trace[2047637051] 'process raft request' (duration: 230.181074ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.834613Z","caller":"traceutil/trace.go:171","msg":"trace[981399115] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"231.421404ms","start":"2026-02-01T18:56:26.603182Z","end":"2026-02-01T18:56:26.834604Z","steps":["trace[981399115] 'process raft request' (duration: 230.159793ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.835801Z","caller":"traceutil/trace.go:171","msg":"trace[1343481811] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"231.408975ms","start":"2026-02-01T18:56:26.604372Z","end":"2026-02-01T18:56:26.835781Z","steps":["trace[1343481811] 'process raft request' (duration: 228.991655ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:26.835932Z","caller":"traceutil/trace.go:171","msg":"trace[1055757709] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"230.929722ms","start":"2026-02-01T18:56:26.604994Z","end":"2026-02-01T18:56:26.835923Z","steps":["trace[1055757709] 'process raft request' (duration: 228.393528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.836087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.616714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890344b04eedb5b\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-01T18:56:26.836113Z","caller":"traceutil/trace.go:171","msg":"trace[1582975807] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1890344b04eedb5b; range_end:; response_count:1; response_revision:1024; }","duration":"236.681716ms","start":"2026-02-01T18:56:26.599421Z","end":"2026-02-01T18:56:26.836102Z","steps":["trace[1582975807] 'agreement among raft nodes before linearized reading' (duration: 236.563013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:26.836491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.841526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:26.836552Z","caller":"traceutil/trace.go:171","msg":"trace[1616831421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"235.917877ms","start":"2026-02-01T18:56:26.600625Z","end":"2026-02-01T18:56:26.836543Z","steps":["trace[1616831421] 'agreement among raft nodes before linearized reading' (duration: 235.840335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:27.128358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.740572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:27.128458Z","caller":"traceutil/trace.go:171","msg":"trace[2082472609] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"287.200471ms","start":"2026-02-01T18:56:26.841236Z","end":"2026-02-01T18:56:27.128436Z","steps":["trace[2082472609] 'process raft request' (duration: 178.306485ms)","trace[2082472609] 'compare' (duration: 108.62324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:27.654743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276560089844442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:56:28.798717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.264161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:28.798801Z","caller":"traceutil/trace.go:171","msg":"trace[1563801670] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"1.645025522s","start":"2026-02-01T18:56:27.153761Z","end":"2026-02-01T18:56:28.798787Z","steps":["trace[1563801670] 'read index received' (duration: 800.556656ms)","trace[1563801670] 'applied index is now lower than readState.Index' (duration: 844.467476ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:28.798875Z","caller":"traceutil/trace.go:171","msg":"trace[1512950460] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"1.647190645s","start":"2026-02-01T18:56:27.151674Z","end":"2026-02-01T18:56:28.798864Z","steps":["trace[1512950460] 'process raft request' (duration: 802.706569ms)","trace[1512950460] 'compare' (duration: 843.736837ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:28.798919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:27.151655Z","time spent":"1.647242017s","remote":"127.0.0.1:53526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":865,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:56:28.799206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645435082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:28.799251Z","caller":"traceutil/trace.go:171","msg":"trace[587874790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"1.645505174s","start":"2026-02-01T18:56:27.153733Z","end":"2026-02-01T18:56:28.799238Z","steps":["trace[587874790] 'agreement among raft nodes before linearized reading' (duration: 1.64534251s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:28.79928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:27.153724Z","time spent":"1.645549405s","remote":"127.0.0.1:53452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:56:28.80192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469890626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:56:28.801959Z","caller":"traceutil/trace.go:171","msg":"trace[653945592] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1026; }","duration":"1.469968678s","start":"2026-02-01T18:56:27.331979Z","end":"2026-02-01T18:56:28.801948Z","steps":["trace[653945592] 'agreement among raft nodes before linearized reading' (duration: 1.469840835s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:28.80201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:27.331962Z","time spent":"1.47004112s","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-02-01T18:56:28.802213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.270888ms","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-02-01T18:56:28.802241Z","caller":"traceutil/trace.go:171","msg":"trace[1889836440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"139.318188ms","start":"2026-02-01T18:56:28.662914Z","end":"2026-02-01T18:56:28.802232Z","steps":["trace[1889836440] 'agreement among raft nodes before linearized reading' (duration: 139.232356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:28.802384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.577698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:28.802409Z","caller":"traceutil/trace.go:171","msg":"trace[1984632698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"852.647959ms","start":"2026-02-01T18:56:27.949753Z","end":"2026-02-01T18:56:28.802401Z","steps":["trace[1984632698] 'agreement among raft nodes before linearized reading' (duration: 852.607909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:28.802434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:27.949728Z","time spent":"852.700031ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:56:29.065136Z","caller":"traceutil/trace.go:171","msg":"trace[1888766928] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"217.811624ms","start":"2026-02-01T18:56:28.847187Z","end":"2026-02-01T18:56:29.064998Z","steps":["trace[1888766928] 'process raft request' (duration: 129.902662ms)","trace[1888766928] 'compare' (duration: 87.745388ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:29.066422Z","caller":"traceutil/trace.go:171","msg":"trace[1429994191] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1071; }","duration":"197.154116ms","start":"2026-02-01T18:56:28.86922Z","end":"2026-02-01T18:56:29.066374Z","steps":["trace[1429994191] 'read index received' (duration: 108.023044ms)","trace[1429994191] 'applied index is now lower than readState.Index' (duration: 89.130252ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:29.070897Z","caller":"traceutil/trace.go:171","msg":"trace[608645136] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"203.816914ms","start":"2026-02-01T18:56:28.867051Z","end":"2026-02-01T18:56:29.070868Z","steps":["trace[608645136] 'process raft request' (duration: 198.787728ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:29.071093Z","caller":"traceutil/trace.go:171","msg":"trace[837411725] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"201.628309ms","start":"2026-02-01T18:56:28.869406Z","end":"2026-02-01T18:56:29.071034Z","steps":["trace[837411725] 'process raft request' (duration: 196.851659ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:29.071207Z","caller":"traceutil/trace.go:171","msg":"trace[1312793762] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"201.757722ms","start":"2026-02-01T18:56:28.869442Z","end":"2026-02-01T18:56:29.0712Z","steps":["trace[1312793762] 'process raft request' (duration: 196.89398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:29.071463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.227633ms","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-02-01T18:56:29.071496Z","caller":"traceutil/trace.go:171","msg":"trace[1747177489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"202.299425ms","start":"2026-02-01T18:56:28.869188Z","end":"2026-02-01T18:56:29.071488Z","steps":["trace[1747177489] 'agreement among raft nodes before linearized reading' (duration: 202.10384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:29.073705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.228606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-01T18:56:29.073746Z","caller":"traceutil/trace.go:171","msg":"trace[533902618] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1031; }","duration":"143.322319ms","start":"2026-02-01T18:56:28.930411Z","end":"2026-02-01T18:56:29.073734Z","steps":["trace[533902618] 'agreement among raft nodes before linearized reading' (duration: 143.051002ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:42.066468Z","caller":"traceutil/trace.go:171","msg":"trace[1521872261] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"137.025031ms","start":"2026-02-01T18:56:41.929417Z","end":"2026-02-01T18:56:42.066442Z","steps":["trace[1521872261] 'read index received' (duration: 47.419847ms)","trace[1521872261] 'applied index is now lower than readState.Index' (duration: 89.604324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:42.066758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.233236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:56:42.066822Z","caller":"traceutil/trace.go:171","msg":"trace[632768012] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1102; }","duration":"137.39669ms","start":"2026-02-01T18:56:41.929413Z","end":"2026-02-01T18:56:42.06681Z","steps":["trace[632768012] 'agreement among raft nodes before linearized reading' (duration: 137.167534ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:42.067038Z","caller":"traceutil/trace.go:171","msg":"trace[68752298] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"193.444964ms","start":"2026-02-01T18:56:41.873566Z","end":"2026-02-01T18:56:42.06701Z","steps":["trace[68752298] 'process raft request' (duration: 103.294357ms)","trace[68752298] 'compare' (duration: 89.46057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:42.303691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.163304ms","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-02-01T18:56:42.30397Z","caller":"traceutil/trace.go:171","msg":"trace[1660895510] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1102; }","duration":"135.477353ms","start":"2026-02-01T18:56:42.168474Z","end":"2026-02-01T18:56:42.303951Z","steps":["trace[1660895510] 'range keys from in-memory index tree' (duration: 135.147374ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:44.397922Z","caller":"traceutil/trace.go:171","msg":"trace[1715092774] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"134.221221ms","start":"2026-02-01T18:56:44.263681Z","end":"2026-02-01T18:56:44.397902Z","steps":["trace[1715092774] 'process raft request' (duration: 81.186283ms)","trace[1715092774] 'compare' (duration: 52.772872ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:45.335076Z","caller":"traceutil/trace.go:171","msg":"trace[569036111] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"138.511349ms","start":"2026-02-01T18:56:45.196544Z","end":"2026-02-01T18:56:45.335055Z","steps":["trace[569036111] 'process raft request' (duration: 110.388325ms)","trace[569036111] 'compare' (duration: 27.969969ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:45.335653Z","caller":"traceutil/trace.go:171","msg":"trace[1228337443] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"132.139009ms","start":"2026-02-01T18:56:45.203506Z","end":"2026-02-01T18:56:45.335645Z","steps":["trace[1228337443] 'process raft request' (duration: 132.117568ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:45.33582Z","caller":"traceutil/trace.go:171","msg":"trace[2103820572] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"136.846627ms","start":"2026-02-01T18:56:45.198966Z","end":"2026-02-01T18:56:45.335813Z","steps":["trace[2103820572] 'process raft request' (duration: 136.596701ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:45.335894Z","caller":"traceutil/trace.go:171","msg":"trace[1315280313] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"134.382965ms","start":"2026-02-01T18:56:45.201505Z","end":"2026-02-01T18:56:45.335888Z","steps":["trace[1315280313] 'process raft request' (duration: 134.096708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:57:27.587161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.725402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:57:27.587295Z","caller":"traceutil/trace.go:171","msg":"trace[643766786] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"180.191931ms","start":"2026-02-01T18:57:27.407079Z","end":"2026-02-01T18:57:27.587271Z","steps":["trace[643766786] 'process raft request' (duration: 34.155351ms)","trace[643766786] 'compare' (duration: 145.482986ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:57:27.868246Z","caller":"traceutil/trace.go:171","msg":"trace[1522527922] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"158.70914ms","start":"2026-02-01T18:57:27.70945Z","end":"2026-02-01T18:57:27.868159Z","steps":["trace[1522527922] 'process raft request' (duration: 158.504695ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:29.739094Z","caller":"traceutil/trace.go:171","msg":"trace[220494524] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"147.059235ms","start":"2026-02-01T18:57:29.591995Z","end":"2026-02-01T18:57:29.739054Z","steps":["trace[220494524] 'read index received' (duration: 146.755298ms)","trace[220494524] 'applied index is now lower than readState.Index' (duration: 303.057µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:57:29.739296Z","caller":"traceutil/trace.go:171","msg":"trace[218231584] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"165.21778ms","start":"2026-02-01T18:57:29.574067Z","end":"2026-02-01T18:57:29.739285Z","steps":["trace[218231584] 'process raft request' (duration: 164.783859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:57:29.739292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.282931ms","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-02-01T18:57:29.73934Z","caller":"traceutil/trace.go:171","msg":"trace[824401403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"147.367024ms","start":"2026-02-01T18:57:29.591966Z","end":"2026-02-01T18:57:29.739333Z","steps":["trace[824401403] 'agreement among raft nodes before linearized reading' (duration: 147.204349ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:32.120718Z","caller":"traceutil/trace.go:171","msg":"trace[844087808] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"132.064585ms","start":"2026-02-01T18:57:31.988632Z","end":"2026-02-01T18:57:32.120696Z","steps":["trace[844087808] 'process raft request' (duration: 132.021394ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:32.121237Z","caller":"traceutil/trace.go:171","msg":"trace[1345441808] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"132.813726ms","start":"2026-02-01T18:57:31.988409Z","end":"2026-02-01T18:57:32.121222Z","steps":["trace[1345441808] 'process raft request' (duration: 85.347047ms)","trace[1345441808] 'compare' (duration: 46.804891ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:57:32.37332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.109273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:57:32.37465Z","caller":"traceutil/trace.go:171","msg":"trace[1736998562] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"185.90724ms","start":"2026-02-01T18:57:32.188722Z","end":"2026-02-01T18:57:32.374629Z","steps":["trace[1736998562] 'process raft request' (duration: 51.442953ms)","trace[1736998562] 'compare' (duration: 132.942048ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:57:32.374646Z","caller":"traceutil/trace.go:171","msg":"trace[81182686] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"155.845436ms","start":"2026-02-01T18:57:32.218775Z","end":"2026-02-01T18:57:32.374621Z","steps":["trace[81182686] 'read index received' (duration: 21.44941ms)","trace[81182686] 'applied index is now lower than readState.Index' (duration: 134.393016ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:57:32.376484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.673974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:57:32.37654Z","caller":"traceutil/trace.go:171","msg":"trace[1706312408] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1306; }","duration":"157.780147ms","start":"2026-02-01T18:57:32.218745Z","end":"2026-02-01T18:57:32.376525Z","steps":["trace[1706312408] 'agreement among raft nodes before linearized reading' (duration: 156.063922ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:57.462898Z","caller":"traceutil/trace.go:171","msg":"trace[1345325004] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"123.354348ms","start":"2026-02-01T18:57:57.339516Z","end":"2026-02-01T18:57:57.462871Z","steps":["trace[1345325004] 'process raft request' (duration: 61.984367ms)","trace[1345325004] 'compare' (duration: 61.221187ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:48.805897Z","caller":"traceutil/trace.go:171","msg":"trace[923471762] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"133.614342ms","start":"2026-02-01T18:59:48.672263Z","end":"2026-02-01T18:59:48.805878Z","steps":["trace[923471762] 'process raft request' (duration: 129.689694ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:57.828702Z","caller":"traceutil/trace.go:171","msg":"trace[149279936] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2015; }","duration":"459.971321ms","start":"2026-02-01T18:59:57.36871Z","end":"2026-02-01T18:59:57.828682Z","steps":["trace[149279936] 'read index received' (duration: 372.79183ms)","trace[149279936] 'applied index is now lower than readState.Index' (duration: 87.178501ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:57.828955Z","caller":"traceutil/trace.go:171","msg":"trace[1362017376] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"471.017816ms","start":"2026-02-01T18:59:57.35792Z","end":"2026-02-01T18:59:57.828938Z","steps":["trace[1362017376] 'process raft request' (duration: 383.607729ms)","trace[1362017376] 'compare' (duration: 87.039267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:59:57.829139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:59:57.357901Z","time spent":"471.138589ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:59:57.829505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.786782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-01T18:59:57.829594Z","caller":"traceutil/trace.go:171","msg":"trace[716087694] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1928; }","duration":"460.898345ms","start":"2026-02-01T18:59:57.368685Z","end":"2026-02-01T18:59:57.829583Z","steps":["trace[716087694] 'agreement among raft nodes before linearized reading' (duration: 460.710201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:57.829678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:59:57.368672Z","time spent":"460.994347ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-01T18:59:58.006777Z","caller":"traceutil/trace.go:171","msg":"trace[158927294] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"164.824155ms","start":"2026-02-01T18:59:57.84193Z","end":"2026-02-01T18:59:58.006754Z","steps":["trace[158927294] 'process raft request' (duration: 159.215956ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:58.007271Z","caller":"traceutil/trace.go:171","msg":"trace[809253472] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"164.21641ms","start":"2026-02-01T18:59:57.843043Z","end":"2026-02-01T18:59:58.007259Z","steps":["trace[809253472] 'process raft request' (duration: 163.295357ms)"],"step_count":1}