{"level":"warn","ts":"2026-02-04T01:52:59.117693Z","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-04T01:52:59.117803Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-04T01:52:59.117883Z","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-04T01:52:59.117904Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-04T01:52:59.117946Z","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-04T01:52:59.118636Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-02-04T01:52:59.118737Z","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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-04T01:52:59.122905Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.934551ms"} {"level":"info","ts":"2026-02-04T01:52:59.132776Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-02-04T01:52:59.132891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:52:59.132956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-02-04T01:52:59.132985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:52:59.133104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-02-04T01:52:59.133175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-02-04T01:52:59.142664Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:52:59.149945Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:52:59.152339Z","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-04T01:52:59.162627Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:52:59.171173Z","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-04T01:52:59.171241Z","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-04T01:52:59.171259Z","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-04T01:52:59.171976Z","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-04T01:52:59.172153Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:52:59.172199Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:52:59.172253Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:52:59.172439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-04T01:52:59.172484Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-04T01:52:59.173756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-02-04T01:52:59.174881Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-04T01:52:59.233973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:52:59.23406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:52:59.234112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-02-04T01:52:59.234189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:52:59.234211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-04T01:52:59.234235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-02-04T01:52:59.234265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-04T01:52:59.241747Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:52:59.243242Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:52:59.243356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:52:59.243603Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:52:59.243757Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:52:59.243798Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:52:59.247102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-02-04T01:52:59.248279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:52:59.248771Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:52:59.24889Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:52:59.248975Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:53:16.254638Z","caller":"traceutil/trace.go:171","msg":"trace[113755190] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"190.64736ms","start":"2026-02-04T01:53:16.063977Z","end":"2026-02-04T01:53:16.254625Z","steps":["trace[113755190] 'process raft request' (duration: 190.389405ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:53:29.087147Z","caller":"traceutil/trace.go:171","msg":"trace[435992178] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"130.194526ms","start":"2026-02-04T01:53:28.95693Z","end":"2026-02-04T01:53:29.087124Z","steps":["trace[435992178] 'process raft request' (duration: 130.027183ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:53:35.702073Z","caller":"traceutil/trace.go:171","msg":"trace[1304707214] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"163.787619ms","start":"2026-02-04T01:53:35.538268Z","end":"2026-02-04T01:53:35.702056Z","steps":["trace[1304707214] 'read index received' (duration: 163.642836ms)","trace[1304707214] 'applied index is now lower than readState.Index' (duration: 144.293µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:53:35.702223Z","caller":"traceutil/trace.go:171","msg":"trace[1147541829] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"270.639069ms","start":"2026-02-04T01:53:35.431569Z","end":"2026-02-04T01:53:35.702208Z","steps":["trace[1147541829] 'process raft request' (duration: 270.391394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:53:35.702331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.053313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:53:35.702366Z","caller":"traceutil/trace.go:171","msg":"trace[200712246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"164.122235ms","start":"2026-02-04T01:53:35.538236Z","end":"2026-02-04T01:53:35.702358Z","steps":["trace[200712246] 'agreement among raft nodes before linearized reading' (duration: 164.064554ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:53:51.874353Z","caller":"traceutil/trace.go:171","msg":"trace[1469954118] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"199.956369ms","start":"2026-02-04T01:53:51.674382Z","end":"2026-02-04T01:53:51.874338Z","steps":["trace[1469954118] 'process raft request' (duration: 199.729225ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:53:51.999229Z","caller":"traceutil/trace.go:171","msg":"trace[2079922975] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"118.446622ms","start":"2026-02-04T01:53:51.880765Z","end":"2026-02-04T01:53:51.999212Z","steps":["trace[2079922975] 'process raft request' (duration: 118.421722ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:53:51.99941Z","caller":"traceutil/trace.go:171","msg":"trace[1121655427] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"119.652203ms","start":"2026-02-04T01:53:51.879735Z","end":"2026-02-04T01:53:51.999387Z","steps":["trace[1121655427] 'process raft request' (duration: 119.347218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:53:53.010851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.442131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:53:53.011112Z","caller":"traceutil/trace.go:171","msg":"trace[1185983153] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:736; }","duration":"577.799316ms","start":"2026-02-04T01:53:52.433292Z","end":"2026-02-04T01:53:53.011092Z","steps":["trace[1185983153] 'read index received' (duration: 28.735595ms)","trace[1185983153] 'applied index is now lower than readState.Index' (duration: 549.062541ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:53:53.011216Z","caller":"traceutil/trace.go:171","msg":"trace[1837996149] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"680.588271ms","start":"2026-02-04T01:53:52.330616Z","end":"2026-02-04T01:53:53.011204Z","steps":["trace[1837996149] 'process raft request' (duration: 131.387157ms)","trace[1837996149] 'compare' (duration: 541.89328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:53:53.011272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:53:52.330599Z","time spent":"680.639142ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:53:53.011506Z","caller":"traceutil/trace.go:171","msg":"trace[1113474073] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"620.774529ms","start":"2026-02-04T01:53:52.390718Z","end":"2026-02-04T01:53:53.011492Z","steps":["trace[1113474073] 'process raft request' (duration: 620.27775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:53:53.01155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:53:52.390701Z","time spent":"620.825731ms","remote":"127.0.0.1:59228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:53:53.011958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.194426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:53:53.012027Z","caller":"traceutil/trace.go:171","msg":"trace[151185409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"473.302378ms","start":"2026-02-04T01:53:52.538708Z","end":"2026-02-04T01:53:53.01201Z","steps":["trace[151185409] 'agreement among raft nodes before linearized reading' (duration: 473.184506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:53:53.012448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:53:52.538694Z","time spent":"473.357559ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:53:53.012552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.526199ms","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-04T01:53:53.012656Z","caller":"traceutil/trace.go:171","msg":"trace[857284084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:713; }","duration":"579.361684ms","start":"2026-02-04T01:53:52.433267Z","end":"2026-02-04T01:53:53.012629Z","steps":["trace[857284084] 'agreement among raft nodes before linearized reading' (duration: 578.397046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:53:53.012723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:53:52.433254Z","time spent":"579.446225ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:53:53.395066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.34282ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-04T01:53:53.395272Z","caller":"traceutil/trace.go:171","msg":"trace[1630787216] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"298.440767ms","start":"2026-02-04T01:53:53.096814Z","end":"2026-02-04T01:53:53.395255Z","steps":["trace[1630787216] 'process raft request' (duration: 298.337275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:53:53.39545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:53:53.095Z","time spent":"300.446893ms","remote":"127.0.0.1:59178","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:53:53.752332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.968934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:53:53.752502Z","caller":"traceutil/trace.go:171","msg":"trace[450344686] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"212.61706ms","start":"2026-02-04T01:53:53.53987Z","end":"2026-02-04T01:53:53.752487Z","steps":["trace[450344686] 'read index received' (duration: 24.01µs)","trace[450344686] 'applied index is now lower than readState.Index' (duration: 212.59124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:53:53.752978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.740272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:53:53.753027Z","caller":"traceutil/trace.go:171","msg":"trace[454086723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"213.174981ms","start":"2026-02-04T01:53:53.53984Z","end":"2026-02-04T01:53:53.753015Z","steps":["trace[454086723] 'agreement among raft nodes before linearized reading' (duration: 212.698972ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:53:53.753243Z","caller":"traceutil/trace.go:171","msg":"trace[322965831] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"356.573747ms","start":"2026-02-04T01:53:53.396658Z","end":"2026-02-04T01:53:53.753231Z","steps":["trace[322965831] 'process raft request' (duration: 101.629855ms)","trace[322965831] 'compare' (duration: 253.440495ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:53:53.75331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:53:53.39664Z","time spent":"356.634469ms","remote":"127.0.0.1:59178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:12.510161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526556512879485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:54:12.684995Z","caller":"traceutil/trace.go:171","msg":"trace[798620291] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"675.091181ms","start":"2026-02-04T01:54:12.00988Z","end":"2026-02-04T01:54:12.684971Z","steps":["trace[798620291] 'read index received' (duration: 674.910518ms)","trace[798620291] 'applied index is now lower than readState.Index' (duration: 179.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:12.685115Z","caller":"traceutil/trace.go:171","msg":"trace[2094093894] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"1.126883417s","start":"2026-02-04T01:54:11.55822Z","end":"2026-02-04T01:54:12.685103Z","steps":["trace[2094093894] 'process raft request' (duration: 1.126611442s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:12.685226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:11.55821Z","time spent":"1.126931067s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:12.685323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.350355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:54:12.685377Z","caller":"traceutil/trace.go:171","msg":"trace[780958285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"607.436056ms","start":"2026-02-04T01:54:12.07793Z","end":"2026-02-04T01:54:12.685366Z","steps":["trace[780958285] 'agreement among raft nodes before linearized reading' (duration: 607.303904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:12.685401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.077915Z","time spent":"607.479487ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:54:12.685553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.095915ms","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-04T01:54:12.685567Z","caller":"traceutil/trace.go:171","msg":"trace[1786656738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:770; }","duration":"598.124166ms","start":"2026-02-04T01:54:12.087438Z","end":"2026-02-04T01:54:12.685562Z","steps":["trace[1786656738] 'agreement among raft nodes before linearized reading' (duration: 598.079915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:12.685578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.087431Z","time spent":"598.144396ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:54:12.685625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.038038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:54:12.685665Z","caller":"traceutil/trace.go:171","msg":"trace[1244557457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"579.086379ms","start":"2026-02-04T01:54:12.106569Z","end":"2026-02-04T01:54:12.685655Z","steps":["trace[1244557457] 'agreement among raft nodes before linearized reading' (duration: 578.921315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:12.685694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.106564Z","time spent":"579.12374ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:54:12.685774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.893516ms","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-04T01:54:12.685791Z","caller":"traceutil/trace.go:171","msg":"trace[1685206700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"675.937707ms","start":"2026-02-04T01:54:12.009848Z","end":"2026-02-04T01:54:12.685786Z","steps":["trace[1685206700] 'agreement among raft nodes before linearized reading' (duration: 675.891266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:12.685804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.009835Z","time spent":"675.966547ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:54:12.685808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.492469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:12.685834Z","caller":"traceutil/trace.go:171","msg":"trace[2045446980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"149.547429ms","start":"2026-02-04T01:54:12.536278Z","end":"2026-02-04T01:54:12.685826Z","steps":["trace[2045446980] 'agreement among raft nodes before linearized reading' (duration: 149.511169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:13.477074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.881214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:54:13.477186Z","caller":"traceutil/trace.go:171","msg":"trace[1853580822] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"787.477373ms","start":"2026-02-04T01:54:12.689689Z","end":"2026-02-04T01:54:13.477167Z","steps":["trace[1853580822] 'process raft request' (duration: 493.427816ms)","trace[1853580822] 'compare' (duration: 293.542097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:13.477248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.689676Z","time spent":"787.541024ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:14.349618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.114536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:54:14.34978Z","caller":"traceutil/trace.go:171","msg":"trace[1717522774] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:800; }","duration":"1.417043823s","start":"2026-02-04T01:54:12.932723Z","end":"2026-02-04T01:54:14.349767Z","steps":["trace[1717522774] 'read index received' (duration: 250.495342ms)","trace[1717522774] 'applied index is now lower than readState.Index' (duration: 1.166547821s)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:14.349845Z","caller":"traceutil/trace.go:171","msg":"trace[363772705] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.654996576s","start":"2026-02-04T01:54:12.694842Z","end":"2026-02-04T01:54:14.349838Z","steps":["trace[363772705] 'process raft request' (duration: 1.654843303s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:14.349885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.694833Z","time spent":"1.655030557s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:14.350059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.244819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T01:54:14.350067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417329988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:54:14.350084Z","caller":"traceutil/trace.go:171","msg":"trace[1268949220] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:775; }","duration":"975.29793ms","start":"2026-02-04T01:54:13.374779Z","end":"2026-02-04T01:54:14.350077Z","steps":["trace[1268949220] 'agreement among raft nodes before linearized reading' (duration: 975.25815ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:14.350097Z","caller":"traceutil/trace.go:171","msg":"trace[1118812735] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:775; }","duration":"1.417397069s","start":"2026-02-04T01:54:12.932693Z","end":"2026-02-04T01:54:14.35009Z","steps":["trace[1118812735] 'agreement among raft nodes before linearized reading' (duration: 1.417287157s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:14.35011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:13.374764Z","time spent":"975.340181ms","remote":"127.0.0.1:53624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:54:14.350119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.932679Z","time spent":"1.4174356s","remote":"127.0.0.1:59178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.141\" "} {"level":"info","ts":"2026-02-04T01:54:14.349871Z","caller":"traceutil/trace.go:171","msg":"trace[590235306] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"1.657939889s","start":"2026-02-04T01:54:12.691911Z","end":"2026-02-04T01:54:14.349851Z","steps":["trace[590235306] 'process raft request' (duration: 950.508258ms)","trace[590235306] 'compare' (duration: 707.027184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:14.350226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.691868Z","time spent":"1.658336797s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:14.350233Z","caller":"traceutil/trace.go:171","msg":"trace[1290393112] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"1.654691839s","start":"2026-02-04T01:54:12.695535Z","end":"2026-02-04T01:54:14.350227Z","steps":["trace[1290393112] 'process raft request' (duration: 1.65418406s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:14.350289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:12.695529Z","time spent":"1.654720579s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:14.350424Z","caller":"traceutil/trace.go:171","msg":"trace[1337655629] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.216747688s","start":"2026-02-04T01:54:13.13367Z","end":"2026-02-04T01:54:14.350418Z","steps":["trace[1337655629] 'process raft request' (duration: 1.216071395s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:14.35046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:13.133651Z","time spent":"1.216789249s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:14.816248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.460543ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-04T01:54:14.816361Z","caller":"traceutil/trace.go:171","msg":"trace[1037438903] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"279.084373ms","start":"2026-02-04T01:54:14.53726Z","end":"2026-02-04T01:54:14.816344Z","steps":["trace[1037438903] 'read index received' (duration: 40.381µs)","trace[1037438903] 'applied index is now lower than readState.Index' (duration: 279.042242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:14.816456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:14.353213Z","time spent":"463.239724ms","remote":"127.0.0.1:59178","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:54:14.81665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.386579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:14.81668Z","caller":"traceutil/trace.go:171","msg":"trace[1073156344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"279.447531ms","start":"2026-02-04T01:54:14.537222Z","end":"2026-02-04T01:54:14.816669Z","steps":["trace[1073156344] 'agreement among raft nodes before linearized reading' (duration: 279.392299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:14.816943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.148132ms","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-04T01:54:14.816996Z","caller":"traceutil/trace.go:171","msg":"trace[1689765165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"126.240954ms","start":"2026-02-04T01:54:14.690741Z","end":"2026-02-04T01:54:14.816982Z","steps":["trace[1689765165] 'agreement among raft nodes before linearized reading' (duration: 126.046271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:15.976786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.44903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:54:15.976879Z","caller":"traceutil/trace.go:171","msg":"trace[706420422] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"1.15774219s","start":"2026-02-04T01:54:14.819119Z","end":"2026-02-04T01:54:15.976861Z","steps":["trace[706420422] 'process raft request' (duration: 386.151737ms)","trace[706420422] 'compare' (duration: 770.683437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:15.97695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:14.819105Z","time spent":"1.157803141s","remote":"127.0.0.1:59178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:15.983457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526556512879501,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:54:16.007735Z","caller":"traceutil/trace.go:171","msg":"trace[1055854326] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.1873475s","start":"2026-02-04T01:54:14.820368Z","end":"2026-02-04T01:54:16.007716Z","steps":["trace[1055854326] 'process raft request' (duration: 1.187170097s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:16.007851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:14.820351Z","time spent":"1.187436362s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:16.008039Z","caller":"traceutil/trace.go:171","msg":"trace[1494119422] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:808; }","duration":"525.583182ms","start":"2026-02-04T01:54:15.482445Z","end":"2026-02-04T01:54:16.008028Z","steps":["trace[1494119422] 'read index received' (duration: 525.579332ms)","trace[1494119422] 'applied index is now lower than readState.Index' (duration: 3.12µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:16.008417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.97407ms","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-04T01:54:16.008454Z","caller":"traceutil/trace.go:171","msg":"trace[1924903797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"526.042032ms","start":"2026-02-04T01:54:15.482403Z","end":"2026-02-04T01:54:16.008445Z","steps":["trace[1924903797] 'agreement among raft nodes before linearized reading' (duration: 525.94187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:16.00848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:15.482382Z","time spent":"526.091442ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:54:16.856448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526556512879507,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:54:17.246281Z","caller":"traceutil/trace.go:171","msg":"trace[1832662795] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"890.680816ms","start":"2026-02-04T01:54:16.355582Z","end":"2026-02-04T01:54:17.246263Z","steps":["trace[1832662795] 'read index received' (duration: 890.528463ms)","trace[1832662795] 'applied index is now lower than readState.Index' (duration: 151.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:17.246503Z","caller":"traceutil/trace.go:171","msg":"trace[549922093] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"1.040714034s","start":"2026-02-04T01:54:16.205781Z","end":"2026-02-04T01:54:17.246495Z","steps":["trace[549922093] 'process raft request' (duration: 1.040375258s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:17.246568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:16.205766Z","time spent":"1.040755834s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:17.246736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.153424ms","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-04T01:54:17.246756Z","caller":"traceutil/trace.go:171","msg":"trace[1121611664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"891.215075ms","start":"2026-02-04T01:54:16.355535Z","end":"2026-02-04T01:54:17.24675Z","steps":["trace[1121611664] 'agreement among raft nodes before linearized reading' (duration: 891.140184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:17.246774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:16.355514Z","time spent":"891.255516ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:54:17.246896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.220108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:54:17.246913Z","caller":"traceutil/trace.go:171","msg":"trace[1397739101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"890.263398ms","start":"2026-02-04T01:54:16.356645Z","end":"2026-02-04T01:54:17.246908Z","steps":["trace[1397739101] 'agreement among raft nodes before linearized reading' (duration: 890.216957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:17.246929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:16.356629Z","time spent":"890.296128ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:54:17.247085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.334013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:54:17.247109Z","caller":"traceutil/trace.go:171","msg":"trace[140970970] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:779; }","duration":"556.370614ms","start":"2026-02-04T01:54:16.69073Z","end":"2026-02-04T01:54:17.247101Z","steps":["trace[140970970] 'agreement among raft nodes before linearized reading' (duration: 556.307362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:17.247126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:16.690695Z","time spent":"556.426485ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:54:17.24722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.032339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:17.247235Z","caller":"traceutil/trace.go:171","msg":"trace[900276542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"710.06616ms","start":"2026-02-04T01:54:16.537164Z","end":"2026-02-04T01:54:17.24723Z","steps":["trace[900276542] 'agreement among raft nodes before linearized reading' (duration: 710.039439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:17.24725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:16.537152Z","time spent":"710.09537ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:54:17.247354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.619616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:54:17.24737Z","caller":"traceutil/trace.go:171","msg":"trace[1546393667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"890.660186ms","start":"2026-02-04T01:54:16.356705Z","end":"2026-02-04T01:54:17.247365Z","steps":["trace[1546393667] 'agreement among raft nodes before linearized reading' (duration: 890.613195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:17.247385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:16.356693Z","time spent":"890.688426ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:54:17.38633Z","caller":"traceutil/trace.go:171","msg":"trace[949260883] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"131.048982ms","start":"2026-02-04T01:54:17.255261Z","end":"2026-02-04T01:54:17.38631Z","steps":["trace[949260883] 'process raft request' (duration: 48.655198ms)","trace[949260883] 'compare' (duration: 82.271162ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:17.386466Z","caller":"traceutil/trace.go:171","msg":"trace[1950943861] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"129.685517ms","start":"2026-02-04T01:54:17.25675Z","end":"2026-02-04T01:54:17.386435Z","steps":["trace[1950943861] 'process raft request' (duration: 129.534224ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:18.306485Z","caller":"traceutil/trace.go:171","msg":"trace[1030870363] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"281.59855ms","start":"2026-02-04T01:54:18.02487Z","end":"2026-02-04T01:54:18.306468Z","steps":["trace[1030870363] 'read index received' (duration: 281.482928ms)","trace[1030870363] 'applied index is now lower than readState.Index' (duration: 114.942µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:18.306642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.757762ms","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-04T01:54:18.306663Z","caller":"traceutil/trace.go:171","msg":"trace[1260907502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"281.813873ms","start":"2026-02-04T01:54:18.024843Z","end":"2026-02-04T01:54:18.306657Z","steps":["trace[1260907502] 'agreement among raft nodes before linearized reading' (duration: 281.687491ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:18.306745Z","caller":"traceutil/trace.go:171","msg":"trace[506811307] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"290.654814ms","start":"2026-02-04T01:54:18.01607Z","end":"2026-02-04T01:54:18.306724Z","steps":["trace[506811307] 'process raft request' (duration: 290.299908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:18.979933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.883191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:54:18.98015Z","caller":"traceutil/trace.go:171","msg":"trace[581110991] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"670.175921ms","start":"2026-02-04T01:54:18.309947Z","end":"2026-02-04T01:54:18.980123Z","steps":["trace[581110991] 'process raft request' (duration: 60.027925ms)","trace[581110991] 'compare' (duration: 609.747789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:18.980299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:18.309932Z","time spent":"670.274153ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:19.077012Z","caller":"traceutil/trace.go:171","msg":"trace[1084391761] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:814; }","duration":"542.030103ms","start":"2026-02-04T01:54:18.534963Z","end":"2026-02-04T01:54:19.076993Z","steps":["trace[1084391761] 'read index received' (duration: 22.921µs)","trace[1084391761] 'applied index is now lower than readState.Index' (duration: 542.006372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:19.077127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.142005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:19.077197Z","caller":"traceutil/trace.go:171","msg":"trace[82906160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"542.243236ms","start":"2026-02-04T01:54:18.534938Z","end":"2026-02-04T01:54:19.077181Z","steps":["trace[82906160] 'agreement among raft nodes before linearized reading' (duration: 542.114394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:19.077233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:18.534924Z","time spent":"542.303497ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:54:20.536934Z","caller":"traceutil/trace.go:171","msg":"trace[445072893] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"218.973826ms","start":"2026-02-04T01:54:20.317937Z","end":"2026-02-04T01:54:20.536911Z","steps":["trace[445072893] 'process raft request' (duration: 218.528988ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:32.858689Z","caller":"traceutil/trace.go:171","msg":"trace[559155363] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"106.008201ms","start":"2026-02-04T01:54:32.752667Z","end":"2026-02-04T01:54:32.858676Z","steps":["trace[559155363] 'read index received' (duration: 105.868438ms)","trace[559155363] 'applied index is now lower than readState.Index' (duration: 139.253µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:32.858848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.115365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:32.85887Z","caller":"traceutil/trace.go:171","msg":"trace[1848466258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"106.230546ms","start":"2026-02-04T01:54:32.752633Z","end":"2026-02-04T01:54:32.858863Z","steps":["trace[1848466258] 'agreement among raft nodes before linearized reading' (duration: 106.112434ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:32.859089Z","caller":"traceutil/trace.go:171","msg":"trace[1928633587] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"281.863875ms","start":"2026-02-04T01:54:32.577218Z","end":"2026-02-04T01:54:32.859082Z","steps":["trace[1928633587] 'process raft request' (duration: 281.365735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:33.801491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.80432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:33.801541Z","caller":"traceutil/trace.go:171","msg":"trace[368161383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"263.896441ms","start":"2026-02-04T01:54:33.537632Z","end":"2026-02-04T01:54:33.801529Z","steps":["trace[368161383] 'range keys from in-memory index tree' (duration: 263.720708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:33.801843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.526716ms","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-04T01:54:33.801861Z","caller":"traceutil/trace.go:171","msg":"trace[717994576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"257.553756ms","start":"2026-02-04T01:54:33.544302Z","end":"2026-02-04T01:54:33.801855Z","steps":["trace[717994576] 'range keys from in-memory index tree' (duration: 257.429934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:33.80195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.564136ms","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-04T01:54:33.801972Z","caller":"traceutil/trace.go:171","msg":"trace[1351269506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"257.592197ms","start":"2026-02-04T01:54:33.544376Z","end":"2026-02-04T01:54:33.801968Z","steps":["trace[1351269506] 'range keys from in-memory index tree' (duration: 257.526436ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:35.290785Z","caller":"traceutil/trace.go:171","msg":"trace[433864477] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"427.3943ms","start":"2026-02-04T01:54:34.863376Z","end":"2026-02-04T01:54:35.29077Z","steps":["trace[433864477] 'process raft request' (duration: 427.306288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:35.290902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:34.86336Z","time spent":"427.472272ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:35.30138Z","caller":"traceutil/trace.go:171","msg":"trace[1162428275] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"159.497527ms","start":"2026-02-04T01:54:35.141873Z","end":"2026-02-04T01:54:35.30137Z","steps":["trace[1162428275] 'read index received' (duration: 149.924084ms)","trace[1162428275] 'applied index is now lower than readState.Index' (duration: 9.572953ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:35.30197Z","caller":"traceutil/trace.go:171","msg":"trace[1740654652] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"360.461142ms","start":"2026-02-04T01:54:34.941499Z","end":"2026-02-04T01:54:35.30196Z","steps":["trace[1740654652] 'process raft request' (duration: 359.824331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:35.30203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:34.941473Z","time spent":"360.519964ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:35.30245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.570296ms","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-04T01:54:35.302477Z","caller":"traceutil/trace.go:171","msg":"trace[2050012028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:879; }","duration":"160.622798ms","start":"2026-02-04T01:54:35.141846Z","end":"2026-02-04T01:54:35.302469Z","steps":["trace[2050012028] 'agreement among raft nodes before linearized reading' (duration: 160.534296ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:43.713112Z","caller":"traceutil/trace.go:171","msg":"trace[798251129] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"181.566046ms","start":"2026-02-04T01:54:43.531533Z","end":"2026-02-04T01:54:43.713099Z","steps":["trace[798251129] 'read index received' (duration: 181.459794ms)","trace[798251129] 'applied index is now lower than readState.Index' (duration: 105.742µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:43.713228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.683418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:54:43.713243Z","caller":"traceutil/trace.go:171","msg":"trace[422723432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"181.734448ms","start":"2026-02-04T01:54:43.531504Z","end":"2026-02-04T01:54:43.713239Z","steps":["trace[422723432] 'agreement among raft nodes before linearized reading' (duration: 181.645366ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:43.713373Z","caller":"traceutil/trace.go:171","msg":"trace[207296279] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"334.234959ms","start":"2026-02-04T01:54:43.379133Z","end":"2026-02-04T01:54:43.713368Z","steps":["trace[207296279] 'process raft request' (duration: 333.893333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:43.713416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:43.379124Z","time spent":"334.260199ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:48.030618Z","caller":"traceutil/trace.go:171","msg":"trace[264719173] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"301.735724ms","start":"2026-02-04T01:54:47.72887Z","end":"2026-02-04T01:54:48.030606Z","steps":["trace[264719173] 'process raft request' (duration: 301.664912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:48.030694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:47.72886Z","time spent":"301.794525ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:48.030879Z","caller":"traceutil/trace.go:171","msg":"trace[1444873909] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:953; }","duration":"301.575812ms","start":"2026-02-04T01:54:47.729298Z","end":"2026-02-04T01:54:48.030874Z","steps":["trace[1444873909] 'read index received' (duration: 301.573171ms)","trace[1444873909] 'applied index is now lower than readState.Index' (duration: 2.341µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:48.030957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.656883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:54:48.030978Z","caller":"traceutil/trace.go:171","msg":"trace[1859468192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"301.704133ms","start":"2026-02-04T01:54:47.72927Z","end":"2026-02-04T01:54:48.030974Z","steps":["trace[1859468192] 'agreement among raft nodes before linearized reading' (duration: 301.636902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:48.030993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:47.72924Z","time spent":"301.749155ms","remote":"127.0.0.1:59412","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-04T01:54:48.057244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.261833ms","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-04T01:54:48.057272Z","caller":"traceutil/trace.go:171","msg":"trace[1660119063] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:914; }","duration":"312.309844ms","start":"2026-02-04T01:54:47.744956Z","end":"2026-02-04T01:54:48.057266Z","steps":["trace[1660119063] 'agreement among raft nodes before linearized reading' (duration: 312.266693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:48.057288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:47.744947Z","time spent":"312.336764ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:54:48.057442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.620722ms","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-04T01:54:48.05746Z","caller":"traceutil/trace.go:171","msg":"trace[175378450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"143.662212ms","start":"2026-02-04T01:54:47.913794Z","end":"2026-02-04T01:54:48.057456Z","steps":["trace[175378450] 'agreement among raft nodes before linearized reading' (duration: 143.623751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:56.332824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.376881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:54:56.332902Z","caller":"traceutil/trace.go:171","msg":"trace[1133268362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"232.488064ms","start":"2026-02-04T01:54:56.1004Z","end":"2026-02-04T01:54:56.332888Z","steps":["trace[1133268362] 'range keys from in-memory index tree' (duration: 232.27196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:56.332955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.601686ms","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-04T01:54:56.333001Z","caller":"traceutil/trace.go:171","msg":"trace[604256094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"232.679277ms","start":"2026-02-04T01:54:56.100311Z","end":"2026-02-04T01:54:56.33299Z","steps":["trace[604256094] 'range keys from in-memory index tree' (duration: 232.399763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:56.333093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.27198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:54:56.333105Z","caller":"traceutil/trace.go:171","msg":"trace[975784126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"227.3027ms","start":"2026-02-04T01:54:56.105798Z","end":"2026-02-04T01:54:56.333101Z","steps":["trace[975784126] 'range keys from in-memory index tree' (duration: 227.214708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:56.333138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.061749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T01:54:56.333493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.118909ms","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-04T01:54:56.33351Z","caller":"traceutil/trace.go:171","msg":"trace[1550534586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"262.15689ms","start":"2026-02-04T01:54:56.071348Z","end":"2026-02-04T01:54:56.333505Z","steps":["trace[1550534586] 'range keys from in-memory index tree' (duration: 262.061028ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:56.35992Z","caller":"traceutil/trace.go:171","msg":"trace[1392218376] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:962; }","duration":"613.11192ms","start":"2026-02-04T01:54:55.720043Z","end":"2026-02-04T01:54:56.333155Z","steps":["trace[1392218376] 'count revisions from in-memory index tree' (duration: 613.011927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:56.360015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:55.720032Z","time spent":"639.953345ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:54:57.230439Z","caller":"traceutil/trace.go:171","msg":"trace[1406425081] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"886.273738ms","start":"2026-02-04T01:54:56.344149Z","end":"2026-02-04T01:54:57.230422Z","steps":["trace[1406425081] 'process raft request' (duration: 886.174257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:57.230541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:56.34413Z","time spent":"886.356599ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:57.628141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.456452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:54:57.628279Z","caller":"traceutil/trace.go:171","msg":"trace[1937868085] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1005; }","duration":"695.271212ms","start":"2026-02-04T01:54:56.932997Z","end":"2026-02-04T01:54:57.628268Z","steps":["trace[1937868085] 'read index received' (duration: 297.808772ms)","trace[1937868085] 'applied index is now lower than readState.Index' (duration: 397.46158ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:57.628422Z","caller":"traceutil/trace.go:171","msg":"trace[686603805] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.269007751s","start":"2026-02-04T01:54:56.359405Z","end":"2026-02-04T01:54:57.628413Z","steps":["trace[686603805] 'process raft request' (duration: 908.225774ms)","trace[686603805] 'compare' (duration: 360.117236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:57.628463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:56.359385Z","time spent":"1.269055573s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:57.628575Z","caller":"traceutil/trace.go:171","msg":"trace[816686777] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.269105944s","start":"2026-02-04T01:54:56.359462Z","end":"2026-02-04T01:54:57.628568Z","steps":["trace[816686777] 'process raft request' (duration: 1.268742987s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:57.628607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:56.359458Z","time spent":"1.269130264s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:57.628707Z","caller":"traceutil/trace.go:171","msg":"trace[1248706685] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.233621323s","start":"2026-02-04T01:54:56.39508Z","end":"2026-02-04T01:54:57.628701Z","steps":["trace[1248706685] 'process raft request' (duration: 1.233157684s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:57.628738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:56.39506Z","time spent":"1.233660685s","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:57.628825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.825892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:57.628843Z","caller":"traceutil/trace.go:171","msg":"trace[1683495649] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:966; }","duration":"695.866973ms","start":"2026-02-04T01:54:56.93297Z","end":"2026-02-04T01:54:57.628837Z","steps":["trace[1683495649] 'agreement among raft nodes before linearized reading' (duration: 695.835722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:57.62886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:56.932955Z","time spent":"695.901054ms","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:54:57.628942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.36764ms","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-04T01:54:57.628958Z","caller":"traceutil/trace.go:171","msg":"trace[2143173762] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:966; }","duration":"508.420822ms","start":"2026-02-04T01:54:57.120532Z","end":"2026-02-04T01:54:57.628953Z","steps":["trace[2143173762] 'agreement among raft nodes before linearized reading' (duration: 508.390661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:57.628973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:57.120512Z","time spent":"508.456702ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:54:57.629266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.159482ms","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-04T01:54:57.629284Z","caller":"traceutil/trace.go:171","msg":"trace[696098695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"215.212602ms","start":"2026-02-04T01:54:57.414066Z","end":"2026-02-04T01:54:57.629279Z","steps":["trace[696098695] 'agreement among raft nodes before linearized reading' (duration: 215.146581ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:57.809492Z","caller":"traceutil/trace.go:171","msg":"trace[1139761694] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"170.936673ms","start":"2026-02-04T01:54:57.638528Z","end":"2026-02-04T01:54:57.809465Z","steps":["trace[1139761694] 'process raft request' (duration: 131.492342ms)","trace[1139761694] 'compare' (duration: 39.344959ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:58.437924Z","caller":"traceutil/trace.go:171","msg":"trace[1436507282] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"202.554094ms","start":"2026-02-04T01:54:58.235351Z","end":"2026-02-04T01:54:58.437905Z","steps":["trace[1436507282] 'process raft request' (duration: 202.457872ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:01.40438Z","caller":"traceutil/trace.go:171","msg":"trace[1398064933] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"156.005414ms","start":"2026-02-04T01:55:01.248353Z","end":"2026-02-04T01:55:01.404358Z","steps":["trace[1398064933] 'process raft request' (duration: 155.76882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:36.048014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.991735ms","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-04T01:56:36.048085Z","caller":"traceutil/trace.go:171","msg":"trace[1783271617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1390; }","duration":"185.118967ms","start":"2026-02-04T01:56:35.862951Z","end":"2026-02-04T01:56:36.04807Z","steps":["trace[1783271617] 'range keys from in-memory index tree' (duration: 184.822601ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:36.290633Z","caller":"traceutil/trace.go:171","msg":"trace[1736721212] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1452; }","duration":"180.505494ms","start":"2026-02-04T01:56:36.11011Z","end":"2026-02-04T01:56:36.290616Z","steps":["trace[1736721212] 'read index received' (duration: 180.381862ms)","trace[1736721212] 'applied index is now lower than readState.Index' (duration: 122.722µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:36.290736Z","caller":"traceutil/trace.go:171","msg":"trace[18598817] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"239.907112ms","start":"2026-02-04T01:56:36.05082Z","end":"2026-02-04T01:56:36.290727Z","steps":["trace[18598817] 'process raft request' (duration: 239.668818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:36.290902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.90811ms","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-04T01:56:36.29095Z","caller":"traceutil/trace.go:171","msg":"trace[1512075141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1391; }","duration":"176.999902ms","start":"2026-02-04T01:56:36.113937Z","end":"2026-02-04T01:56:36.290937Z","steps":["trace[1512075141] 'agreement among raft nodes before linearized reading' (duration: 176.875549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:36.291182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.071054ms","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-04T01:56:36.291199Z","caller":"traceutil/trace.go:171","msg":"trace[1047030239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1391; }","duration":"181.111585ms","start":"2026-02-04T01:56:36.110083Z","end":"2026-02-04T01:56:36.291194Z","steps":["trace[1047030239] 'agreement among raft nodes before linearized reading' (duration: 181.064284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:36.496196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.362516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:36.496392Z","caller":"traceutil/trace.go:171","msg":"trace[1492018914] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"195.96626ms","start":"2026-02-04T01:56:36.300413Z","end":"2026-02-04T01:56:36.496379Z","steps":["trace[1492018914] 'process raft request' (duration: 195.94137ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:36.496406Z","caller":"traceutil/trace.go:171","msg":"trace[484586860] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"202.388454ms","start":"2026-02-04T01:56:36.293997Z","end":"2026-02-04T01:56:36.496386Z","steps":["trace[484586860] 'process raft request' (duration: 73.739853ms)","trace[484586860] 'compare' (duration: 128.157522ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:36.496513Z","caller":"traceutil/trace.go:171","msg":"trace[1296303033] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"201.930815ms","start":"2026-02-04T01:56:36.294576Z","end":"2026-02-04T01:56:36.496507Z","steps":["trace[1296303033] 'process raft request' (duration: 201.698521ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:36.496608Z","caller":"traceutil/trace.go:171","msg":"trace[1468429261] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"200.625862ms","start":"2026-02-04T01:56:36.295975Z","end":"2026-02-04T01:56:36.4966Z","steps":["trace[1468429261] 'process raft request' (duration: 200.355317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:38.614391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.183684ms","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-04T01:56:38.614494Z","caller":"traceutil/trace.go:171","msg":"trace[1297387157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1399; }","duration":"114.315176ms","start":"2026-02-04T01:56:38.500153Z","end":"2026-02-04T01:56:38.614468Z","steps":["trace[1297387157] 'range keys from in-memory index tree' (duration: 114.00904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:38.614731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.792957ms","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-04T01:56:38.614759Z","caller":"traceutil/trace.go:171","msg":"trace[1265897963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1399; }","duration":"113.839638ms","start":"2026-02-04T01:56:38.50091Z","end":"2026-02-04T01:56:38.61475Z","steps":["trace[1265897963] 'range keys from in-memory index tree' (duration: 113.707854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:38.614925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.571345ms","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-04T01:56:38.614951Z","caller":"traceutil/trace.go:171","msg":"trace[1463130119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1399; }","duration":"112.611186ms","start":"2026-02-04T01:56:38.502332Z","end":"2026-02-04T01:56:38.614943Z","steps":["trace[1463130119] 'range keys from in-memory index tree' (duration: 112.507773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:38.615123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.80583ms","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-04T01:56:38.61515Z","caller":"traceutil/trace.go:171","msg":"trace[270520910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1399; }","duration":"112.87171ms","start":"2026-02-04T01:56:38.50227Z","end":"2026-02-04T01:56:38.615141Z","steps":["trace[270520910] 'range keys from in-memory index tree' (duration: 112.658936ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:38.72074Z","caller":"traceutil/trace.go:171","msg":"trace[1323610284] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"100.56114ms","start":"2026-02-04T01:56:38.620155Z","end":"2026-02-04T01:56:38.720716Z","steps":["trace[1323610284] 'process raft request' (duration: 100.453918ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:38.767842Z","caller":"traceutil/trace.go:171","msg":"trace[2115437842] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"138.947244ms","start":"2026-02-04T01:56:38.628868Z","end":"2026-02-04T01:56:38.767815Z","steps":["trace[2115437842] 'process raft request' (duration: 138.904713ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:38.768253Z","caller":"traceutil/trace.go:171","msg":"trace[1054737591] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"146.491038ms","start":"2026-02-04T01:56:38.621749Z","end":"2026-02-04T01:56:38.76824Z","steps":["trace[1054737591] 'process raft request' (duration: 145.812616ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:38.768373Z","caller":"traceutil/trace.go:171","msg":"trace[1344713546] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"145.275766ms","start":"2026-02-04T01:56:38.623086Z","end":"2026-02-04T01:56:38.768362Z","steps":["trace[1344713546] 'process raft request' (duration: 144.622115ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:39.519608Z","caller":"traceutil/trace.go:171","msg":"trace[659753617] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"189.757999ms","start":"2026-02-04T01:56:39.329824Z","end":"2026-02-04T01:56:39.519582Z","steps":["trace[659753617] 'process raft request' (duration: 135.558614ms)","trace[659753617] 'compare' (duration: 54.080283ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:40.522692Z","caller":"traceutil/trace.go:171","msg":"trace[555995493] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"117.290758ms","start":"2026-02-04T01:56:40.405381Z","end":"2026-02-04T01:56:40.522672Z","steps":["trace[555995493] 'process raft request' (duration: 117.171106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:40.849728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.256134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:40.849791Z","caller":"traceutil/trace.go:171","msg":"trace[2013763926] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1471; }","duration":"122.844448ms","start":"2026-02-04T01:56:40.726936Z","end":"2026-02-04T01:56:40.84978Z","steps":["trace[2013763926] 'read index received' (duration: 30.371µs)","trace[2013763926] 'applied index is now lower than readState.Index' (duration: 122.813477ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:40.849851Z","caller":"traceutil/trace.go:171","msg":"trace[483024528] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"302.884553ms","start":"2026-02-04T01:56:40.546958Z","end":"2026-02-04T01:56:40.849842Z","steps":["trace[483024528] 'process raft request' (duration: 151.439036ms)","trace[483024528] 'compare' (duration: 151.190443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:40.849881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:40.546947Z","time spent":"302.916723ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:40.850177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.245885ms","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-04T01:56:40.850192Z","caller":"traceutil/trace.go:171","msg":"trace[604086677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1409; }","duration":"123.285335ms","start":"2026-02-04T01:56:40.726902Z","end":"2026-02-04T01:56:40.850187Z","steps":["trace[604086677] 'agreement among raft nodes before linearized reading' (duration: 123.245894ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:41.100103Z","caller":"traceutil/trace.go:171","msg":"trace[487530737] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"243.476866ms","start":"2026-02-04T01:56:40.856612Z","end":"2026-02-04T01:56:41.100088Z","steps":["trace[487530737] 'process raft request' (duration: 146.00887ms)","trace[487530737] 'compare' (duration: 97.171661ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:41.100175Z","caller":"traceutil/trace.go:171","msg":"trace[429571368] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"243.434645ms","start":"2026-02-04T01:56:40.856736Z","end":"2026-02-04T01:56:41.100171Z","steps":["trace[429571368] 'process raft request' (duration: 243.13922ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:41.10023Z","caller":"traceutil/trace.go:171","msg":"trace[727173897] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"242.06826ms","start":"2026-02-04T01:56:40.858144Z","end":"2026-02-04T01:56:41.100212Z","steps":["trace[727173897] 'process raft request' (duration: 241.769625ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:41.100352Z","caller":"traceutil/trace.go:171","msg":"trace[478790482] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"243.577818ms","start":"2026-02-04T01:56:40.856767Z","end":"2026-02-04T01:56:41.100345Z","steps":["trace[478790482] 'process raft request' (duration: 243.12999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:41.578697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.111904ms","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-04T01:56:41.5788Z","caller":"traceutil/trace.go:171","msg":"trace[1799985250] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1413; }","duration":"186.254206ms","start":"2026-02-04T01:56:41.392528Z","end":"2026-02-04T01:56:41.578782Z","steps":["trace[1799985250] 'count revisions from in-memory index tree' (duration: 186.037283ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:42.073732Z","caller":"traceutil/trace.go:171","msg":"trace[1231684707] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"290.776057ms","start":"2026-02-04T01:56:41.782937Z","end":"2026-02-04T01:56:42.073713Z","steps":["trace[1231684707] 'process raft request' (duration: 290.309369ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:42.76344Z","caller":"traceutil/trace.go:171","msg":"trace[1446789824] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"228.226274ms","start":"2026-02-04T01:56:42.535192Z","end":"2026-02-04T01:56:42.763418Z","steps":["trace[1446789824] 'read index received' (duration: 130.752728ms)","trace[1446789824] 'applied index is now lower than readState.Index' (duration: 97.472746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:42.763625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.416697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:42.763655Z","caller":"traceutil/trace.go:171","msg":"trace[1550279527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1416; }","duration":"228.475968ms","start":"2026-02-04T01:56:42.53517Z","end":"2026-02-04T01:56:42.763646Z","steps":["trace[1550279527] 'agreement among raft nodes before linearized reading' (duration: 228.330185ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:42.763898Z","caller":"traceutil/trace.go:171","msg":"trace[440541656] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"235.696537ms","start":"2026-02-04T01:56:42.528187Z","end":"2026-02-04T01:56:42.763883Z","steps":["trace[440541656] 'process raft request' (duration: 137.826844ms)","trace[440541656] 'compare' (duration: 97.096949ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:43.258039Z","caller":"traceutil/trace.go:171","msg":"trace[595907497] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"152.525926ms","start":"2026-02-04T01:56:43.105497Z","end":"2026-02-04T01:56:43.258023Z","steps":["trace[595907497] 'read index received' (duration: 57.151947ms)","trace[595907497] 'applied index is now lower than readState.Index' (duration: 95.373369ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:43.258118Z","caller":"traceutil/trace.go:171","msg":"trace[1985056462] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"207.698928ms","start":"2026-02-04T01:56:43.050411Z","end":"2026-02-04T01:56:43.25811Z","steps":["trace[1985056462] 'process raft request' (duration: 112.177867ms)","trace[1985056462] 'compare' (duration: 95.338278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:43.258337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.827981ms","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-04T01:56:43.258355Z","caller":"traceutil/trace.go:171","msg":"trace[928945723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1417; }","duration":"152.857421ms","start":"2026-02-04T01:56:43.105492Z","end":"2026-02-04T01:56:43.258349Z","steps":["trace[928945723] 'agreement among raft nodes before linearized reading' (duration: 152.747949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.258464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.907592ms","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-04T01:56:43.258486Z","caller":"traceutil/trace.go:171","msg":"trace[1770706282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1417; }","duration":"152.935482ms","start":"2026-02-04T01:56:43.105543Z","end":"2026-02-04T01:56:43.258479Z","steps":["trace[1770706282] 'agreement among raft nodes before linearized reading' (duration: 152.888652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.258632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.797593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:56:43.258645Z","caller":"traceutil/trace.go:171","msg":"trace[1190469585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1417; }","duration":"151.842744ms","start":"2026-02-04T01:56:43.106798Z","end":"2026-02-04T01:56:43.258641Z","steps":["trace[1190469585] 'agreement among raft nodes before linearized reading' (duration: 151.800443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.258754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.927774ms","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-04T01:56:43.25877Z","caller":"traceutil/trace.go:171","msg":"trace[1979103314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1417; }","duration":"152.951824ms","start":"2026-02-04T01:56:43.105813Z","end":"2026-02-04T01:56:43.258765Z","steps":["trace[1979103314] 'agreement among raft nodes before linearized reading' (duration: 152.906754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.534383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.126444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:43.536807Z","caller":"traceutil/trace.go:171","msg":"trace[941025000] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"267.198979ms","start":"2026-02-04T01:56:43.26959Z","end":"2026-02-04T01:56:43.536789Z","steps":["trace[941025000] 'process raft request' (duration: 267.162308ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:43.537166Z","caller":"traceutil/trace.go:171","msg":"trace[1390214544] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"269.821524ms","start":"2026-02-04T01:56:43.26734Z","end":"2026-02-04T01:56:43.537161Z","steps":["trace[1390214544] 'process raft request' (duration: 269.318005ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:43.537414Z","caller":"traceutil/trace.go:171","msg":"trace[162399438] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"270.017438ms","start":"2026-02-04T01:56:43.267392Z","end":"2026-02-04T01:56:43.53741Z","steps":["trace[162399438] 'process raft request' (duration: 269.327535ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:43.536947Z","caller":"traceutil/trace.go:171","msg":"trace[1975216816] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"270.633909ms","start":"2026-02-04T01:56:43.266301Z","end":"2026-02-04T01:56:43.536935Z","steps":["trace[1975216816] 'process raft request' (duration: 104.881708ms)","trace[1975216816] 'compare' (duration: 163.024472ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:43.536979Z","caller":"traceutil/trace.go:171","msg":"trace[2015026181] linearizableReadLoop","detail":"{readStateIndex:1482; appliedIndex:1481; }","duration":"269.716752ms","start":"2026-02-04T01:56:43.267247Z","end":"2026-02-04T01:56:43.536963Z","steps":["trace[2015026181] 'read index received' (duration: 103.970011ms)","trace[2015026181] 'applied index is now lower than readState.Index' (duration: 165.746151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:43.537089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.835264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:56:43.537906Z","caller":"traceutil/trace.go:171","msg":"trace[125438905] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1421; }","duration":"270.671179ms","start":"2026-02-04T01:56:43.26723Z","end":"2026-02-04T01:56:43.537901Z","steps":["trace[125438905] 'agreement among raft nodes before linearized reading' (duration: 269.757683ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:44.70258Z","caller":"traceutil/trace.go:171","msg":"trace[1744614962] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"174.864264ms","start":"2026-02-04T01:56:44.527694Z","end":"2026-02-04T01:56:44.702558Z","steps":["trace[1744614962] 'process raft request' (duration: 98.612116ms)","trace[1744614962] 'compare' (duration: 76.169847ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:44.886144Z","caller":"traceutil/trace.go:171","msg":"trace[975069512] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"111.296003ms","start":"2026-02-04T01:56:44.774816Z","end":"2026-02-04T01:56:44.886112Z","steps":["trace[975069512] 'process raft request' (duration: 34.712249ms)","trace[975069512] 'compare' (duration: 76.497782ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:48.601073Z","caller":"traceutil/trace.go:171","msg":"trace[454927486] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"115.002378ms","start":"2026-02-04T01:56:48.486049Z","end":"2026-02-04T01:56:48.601052Z","steps":["trace[454927486] 'process raft request' (duration: 114.520839ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:55.950773Z","caller":"traceutil/trace.go:171","msg":"trace[553104914] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"106.795371ms","start":"2026-02-04T01:56:55.84395Z","end":"2026-02-04T01:56:55.950745Z","steps":["trace[553104914] 'process raft request' (duration: 106.7367ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:55.951213Z","caller":"traceutil/trace.go:171","msg":"trace[1531599035] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"107.618707ms","start":"2026-02-04T01:56:55.843579Z","end":"2026-02-04T01:56:55.951198Z","steps":["trace[1531599035] 'process raft request' (duration: 52.383703ms)","trace[1531599035] 'compare' (duration: 54.585612ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:56.472354Z","caller":"traceutil/trace.go:171","msg":"trace[888249228] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"223.874407ms","start":"2026-02-04T01:56:56.248447Z","end":"2026-02-04T01:56:56.472322Z","steps":["trace[888249228] 'process raft request' (duration: 223.713664ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:57.735022Z","caller":"traceutil/trace.go:171","msg":"trace[1113685423] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"157.745319ms","start":"2026-02-04T01:56:57.577246Z","end":"2026-02-04T01:56:57.734991Z","steps":["trace[1113685423] 'process raft request' (duration: 157.572966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.233664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.340078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:58.233761Z","caller":"traceutil/trace.go:171","msg":"trace[743263378] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1540; }","duration":"390.013396ms","start":"2026-02-04T01:56:57.843732Z","end":"2026-02-04T01:56:58.233746Z","steps":["trace[743263378] 'read index received' (duration: 262.447924ms)","trace[743263378] 'applied index is now lower than readState.Index' (duration: 127.564672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:58.233921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.773087ms","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-04T01:56:58.23394Z","caller":"traceutil/trace.go:171","msg":"trace[1581916218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1475; }","duration":"277.829488ms","start":"2026-02-04T01:56:57.956103Z","end":"2026-02-04T01:56:58.233933Z","steps":["trace[1581916218] 'agreement among raft nodes before linearized reading' (duration: 277.712446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.233931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.187469ms","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-04T01:56:58.234004Z","caller":"traceutil/trace.go:171","msg":"trace[313235196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1475; }","duration":"390.309021ms","start":"2026-02-04T01:56:57.843674Z","end":"2026-02-04T01:56:58.233983Z","steps":["trace[313235196] 'agreement among raft nodes before linearized reading' (duration: 390.132648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.234042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.84365Z","time spent":"390.384322ms","remote":"127.0.0.1:59412","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-04T01:56:58.234139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.323816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:56:58.234162Z","caller":"traceutil/trace.go:171","msg":"trace[458638838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1475; }","duration":"274.359226ms","start":"2026-02-04T01:56:57.959794Z","end":"2026-02-04T01:56:58.234154Z","steps":["trace[458638838] 'agreement among raft nodes before linearized reading' (duration: 274.284305ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:58.234227Z","caller":"traceutil/trace.go:171","msg":"trace[305131311] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"464.112464ms","start":"2026-02-04T01:56:57.770107Z","end":"2026-02-04T01:56:58.23422Z","steps":["trace[305131311] 'process raft request' (duration: 336.123995ms)","trace[305131311] 'compare' (duration: 127.225676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:58.23427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.770094Z","time spent":"464.150156ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:58.731545Z","caller":"traceutil/trace.go:171","msg":"trace[493803206] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"484.143422ms","start":"2026-02-04T01:56:58.247378Z","end":"2026-02-04T01:56:58.731521Z","steps":["trace[493803206] 'process raft request' (duration: 484.111181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.731682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.247369Z","time spent":"484.236723ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:58.731914Z","caller":"traceutil/trace.go:171","msg":"trace[1310965940] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"485.494895ms","start":"2026-02-04T01:56:58.246412Z","end":"2026-02-04T01:56:58.731907Z","steps":["trace[1310965940] 'process raft request' (duration: 484.912894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.731948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.246388Z","time spent":"485.540787ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:58.732176Z","caller":"traceutil/trace.go:171","msg":"trace[1817797693] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1544; }","duration":"295.94622ms","start":"2026-02-04T01:56:58.436222Z","end":"2026-02-04T01:56:58.732168Z","steps":["trace[1817797693] 'read index received' (duration: 295.94263ms)","trace[1817797693] 'applied index is now lower than readState.Index' (duration: 2.96µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:58.732255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.025671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:58.732278Z","caller":"traceutil/trace.go:171","msg":"trace[624165680] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1478; }","duration":"296.104073ms","start":"2026-02-04T01:56:58.436167Z","end":"2026-02-04T01:56:58.732271Z","steps":["trace[624165680] 'agreement among raft nodes before linearized reading' (duration: 296.057802ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:58.951181Z","caller":"traceutil/trace.go:171","msg":"trace[755480431] linearizableReadLoop","detail":"{readStateIndex:1546; appliedIndex:1544; }","duration":"218.943208ms","start":"2026-02-04T01:56:58.732192Z","end":"2026-02-04T01:56:58.951135Z","steps":["trace[755480431] 'read index received' (duration: 138.886903ms)","trace[755480431] 'applied index is now lower than readState.Index' (duration: 80.030885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:58.951432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.926657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-04T01:56:58.951466Z","caller":"traceutil/trace.go:171","msg":"trace[134937513] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1479; }","duration":"475.03324ms","start":"2026-02-04T01:56:58.476422Z","end":"2026-02-04T01:56:58.951456Z","steps":["trace[134937513] 'agreement among raft nodes before linearized reading' (duration: 474.828655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.9515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.476411Z","time spent":"475.08057ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-04T01:56:58.951905Z","caller":"traceutil/trace.go:171","msg":"trace[204832695] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"310.713463ms","start":"2026-02-04T01:56:58.64118Z","end":"2026-02-04T01:56:58.951894Z","steps":["trace[204832695] 'process raft request' (duration: 309.790527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.951993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.641155Z","time spent":"310.776864ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:58.952134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.668128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:58.952161Z","caller":"traceutil/trace.go:171","msg":"trace[613348328] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1479; }","duration":"389.697279ms","start":"2026-02-04T01:56:58.562456Z","end":"2026-02-04T01:56:58.952153Z","steps":["trace[613348328] 'agreement among raft nodes before linearized reading' (duration: 389.651108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.952188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.56242Z","time spent":"389.760859ms","remote":"127.0.0.1:58080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:56:58.952321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.419819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:58.952345Z","caller":"traceutil/trace.go:171","msg":"trace[362984809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1479; }","duration":"414.46092ms","start":"2026-02-04T01:56:58.537877Z","end":"2026-02-04T01:56:58.952337Z","steps":["trace[362984809] 'agreement among raft nodes before linearized reading' (duration: 414.422089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.95237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.537867Z","time spent":"414.49632ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:58.952491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.512818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:58.952517Z","caller":"traceutil/trace.go:171","msg":"trace[15615600] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1479; }","duration":"465.56716ms","start":"2026-02-04T01:56:58.48694Z","end":"2026-02-04T01:56:58.952507Z","steps":["trace[15615600] 'agreement among raft nodes before linearized reading' (duration: 465.530739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.952548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.48693Z","time spent":"465.60943ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:56:59.583571Z","caller":"traceutil/trace.go:171","msg":"trace[1406659488] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"124.493537ms","start":"2026-02-04T01:56:59.459045Z","end":"2026-02-04T01:56:59.583538Z","steps":["trace[1406659488] 'process raft request' (duration: 124.341574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:02.656629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.188387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:02.656723Z","caller":"traceutil/trace.go:171","msg":"trace[1929261641] linearizableReadLoop","detail":"{readStateIndex:1560; appliedIndex:1559; }","duration":"117.700945ms","start":"2026-02-04T01:57:02.539009Z","end":"2026-02-04T01:57:02.65671Z","steps":["trace[1929261641] 'read index received' (duration: 54.141µs)","trace[1929261641] 'applied index is now lower than readState.Index' (duration: 117.646134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:02.656818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.820177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:02.656836Z","caller":"traceutil/trace.go:171","msg":"trace[1396631975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1493; }","duration":"117.882439ms","start":"2026-02-04T01:57:02.538948Z","end":"2026-02-04T01:57:02.65683Z","steps":["trace[1396631975] 'agreement among raft nodes before linearized reading' (duration: 117.794177ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:02.657005Z","caller":"traceutil/trace.go:171","msg":"trace[657450078] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"329.315823ms","start":"2026-02-04T01:57:02.327682Z","end":"2026-02-04T01:57:02.656998Z","steps":["trace[657450078] 'process raft request' (duration: 79.669668ms)","trace[657450078] 'compare' (duration: 248.923223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:02.657041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:02.32767Z","time spent":"329.352415ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:03.050183Z","caller":"traceutil/trace.go:171","msg":"trace[864013526] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"308.343141ms","start":"2026-02-04T01:57:02.741821Z","end":"2026-02-04T01:57:03.050165Z","steps":["trace[864013526] 'process raft request' (duration: 214.331487ms)","trace[864013526] 'compare' (duration: 93.758049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:03.05138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:02.741807Z","time spent":"309.494651ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:03.052475Z","caller":"traceutil/trace.go:171","msg":"trace[1527179852] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1560; }","duration":"304.006403ms","start":"2026-02-04T01:57:02.746024Z","end":"2026-02-04T01:57:03.050031Z","steps":["trace[1527179852] 'read index received' (duration: 210.04551ms)","trace[1527179852] 'applied index is now lower than readState.Index' (duration: 93.959903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:03.052741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.709871ms","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-04T01:57:03.053632Z","caller":"traceutil/trace.go:171","msg":"trace[922757654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1494; }","duration":"307.508615ms","start":"2026-02-04T01:57:02.74602Z","end":"2026-02-04T01:57:03.053528Z","steps":["trace[922757654] 'agreement among raft nodes before linearized reading' (duration: 306.592509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.053816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:02.745997Z","time spent":"307.74827ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:57:03.053076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.507078ms","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-04T01:57:03.05415Z","caller":"traceutil/trace.go:171","msg":"trace[61321483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1494; }","duration":"307.601077ms","start":"2026-02-04T01:57:02.746539Z","end":"2026-02-04T01:57:03.05414Z","steps":["trace[61321483] 'agreement among raft nodes before linearized reading' (duration: 306.487657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.054353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:02.746534Z","time spent":"307.808441ms","remote":"127.0.0.1:59412","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-04T01:57:03.053283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.226513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-04T01:57:03.053312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.887958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:03.055275Z","caller":"traceutil/trace.go:171","msg":"trace[178944830] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1494; }","duration":"233.873315ms","start":"2026-02-04T01:57:02.821396Z","end":"2026-02-04T01:57:03.055269Z","steps":["trace[178944830] 'agreement among raft nodes before linearized reading' (duration: 231.902019ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:03.055203Z","caller":"traceutil/trace.go:171","msg":"trace[414537870] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:1494; }","duration":"112.170468ms","start":"2026-02-04T01:57:02.943022Z","end":"2026-02-04T01:57:03.055193Z","steps":["trace[414537870] 'agreement among raft nodes before linearized reading' (duration: 110.219503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.683048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.92987ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-04T01:57:03.683277Z","caller":"traceutil/trace.go:171","msg":"trace[1380203927] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1561; }","duration":"479.687901ms","start":"2026-02-04T01:57:03.203574Z","end":"2026-02-04T01:57:03.683262Z","steps":["trace[1380203927] 'read index received' (duration: 92.611469ms)","trace[1380203927] 'applied index is now lower than readState.Index' (duration: 387.075782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:03.683352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.058278Z","time spent":"625.071831ms","remote":"127.0.0.1:59178","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T01:57:03.683629Z","caller":"traceutil/trace.go:171","msg":"trace[1411567312] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"624.924478ms","start":"2026-02-04T01:57:03.058696Z","end":"2026-02-04T01:57:03.68362Z","steps":["trace[1411567312] 'process raft request' (duration: 624.43784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.683707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.058677Z","time spent":"624.976989ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:03.683806Z","caller":"traceutil/trace.go:171","msg":"trace[1769485853] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"624.954019ms","start":"2026-02-04T01:57:03.058843Z","end":"2026-02-04T01:57:03.683797Z","steps":["trace[1769485853] 'process raft request' (duration: 624.376489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.683842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.058835Z","time spent":"624.98692ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:03.683923Z","caller":"traceutil/trace.go:171","msg":"trace[1159861751] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"623.152456ms","start":"2026-02-04T01:57:03.060764Z","end":"2026-02-04T01:57:03.683917Z","steps":["trace[1159861751] 'process raft request' (duration: 622.477444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.683951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.060758Z","time spent":"623.177227ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:03.684247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.668989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:57:03.684564Z","caller":"traceutil/trace.go:171","msg":"trace[1702018322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1497; }","duration":"481.025575ms","start":"2026-02-04T01:57:03.203527Z","end":"2026-02-04T01:57:03.684553Z","steps":["trace[1702018322] 'agreement among raft nodes before linearized reading' (duration: 480.601108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.684602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.203506Z","time spent":"481.087216ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:03.684771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.174713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:03.684796Z","caller":"traceutil/trace.go:171","msg":"trace[1914862739] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1497; }","duration":"333.251654ms","start":"2026-02-04T01:57:03.351538Z","end":"2026-02-04T01:57:03.68479Z","steps":["trace[1914862739] 'agreement among raft nodes before linearized reading' (duration: 333.157043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.684828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.351514Z","time spent":"333.309415ms","remote":"127.0.0.1:53588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:03.990897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.078534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:03.99176Z","caller":"traceutil/trace.go:171","msg":"trace[392042960] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"299.982511ms","start":"2026-02-04T01:57:03.69172Z","end":"2026-02-04T01:57:03.991702Z","steps":["trace[392042960] 'process raft request' (duration: 299.30745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.991923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.691708Z","time spent":"300.110504ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:03.992163Z","caller":"traceutil/trace.go:171","msg":"trace[514319935] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"302.768041ms","start":"2026-02-04T01:57:03.689353Z","end":"2026-02-04T01:57:03.992121Z","steps":["trace[514319935] 'process raft request' (duration: 183.372504ms)","trace[514319935] 'compare' (duration: 117.668566ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:03.992416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.689334Z","time spent":"302.951604ms","remote":"127.0.0.1:59178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:10.275586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.174244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-04T01:58:10.275684Z","caller":"traceutil/trace.go:171","msg":"trace[400021017] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1788; }","duration":"111.289397ms","start":"2026-02-04T01:58:10.164372Z","end":"2026-02-04T01:58:10.275661Z","steps":["trace[400021017] 'range keys from in-memory index tree' (duration: 111.039442ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:10.422132Z","caller":"traceutil/trace.go:171","msg":"trace[1236093306] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"141.158662ms","start":"2026-02-04T01:58:10.280946Z","end":"2026-02-04T01:58:10.422104Z","steps":["trace[1236093306] 'process raft request' (duration: 140.960139ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:10.730234Z","caller":"traceutil/trace.go:171","msg":"trace[772610155] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"299.514006ms","start":"2026-02-04T01:58:10.430702Z","end":"2026-02-04T01:58:10.730216Z","steps":["trace[772610155] 'read index received' (duration: 40.892579ms)","trace[772610155] 'applied index is now lower than readState.Index' (duration: 258.620797ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:10.730333Z","caller":"traceutil/trace.go:171","msg":"trace[334555522] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"301.491984ms","start":"2026-02-04T01:58:10.428833Z","end":"2026-02-04T01:58:10.730325Z","steps":["trace[334555522] 'process raft request' (duration: 301.240699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:10.730403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.428797Z","time spent":"301.552884ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:10.730663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.955915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-04T01:58:10.730688Z","caller":"traceutil/trace.go:171","msg":"trace[1678979286] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1791; }","duration":"299.990335ms","start":"2026-02-04T01:58:10.430689Z","end":"2026-02-04T01:58:10.730679Z","steps":["trace[1678979286] 'agreement among raft nodes before linearized reading' (duration: 299.904664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:10.730707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.430683Z","time spent":"300.019806ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-04T01:58:12.273526Z","caller":"traceutil/trace.go:171","msg":"trace[570257876] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"121.3129ms","start":"2026-02-04T01:58:12.152185Z","end":"2026-02-04T01:58:12.273497Z","steps":["trace[570257876] 'process raft request' (duration: 37.294879ms)","trace[570257876] 'compare' (duration: 83.849196ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:12.950561Z","caller":"traceutil/trace.go:171","msg":"trace[1329050688] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"116.182491ms","start":"2026-02-04T01:58:12.834357Z","end":"2026-02-04T01:58:12.95054Z","steps":["trace[1329050688] 'read index received' (duration: 61.101469ms)","trace[1329050688] 'applied index is now lower than readState.Index' (duration: 55.079732ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:12.951056Z","caller":"traceutil/trace.go:171","msg":"trace[1464828000] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"163.186556ms","start":"2026-02-04T01:58:12.787853Z","end":"2026-02-04T01:58:12.95104Z","steps":["trace[1464828000] 'process raft request' (duration: 107.661036ms)","trace[1464828000] 'compare' (duration: 54.935009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:12.952798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.420224ms","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-04T01:58:12.95285Z","caller":"traceutil/trace.go:171","msg":"trace[28251830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1800; }","duration":"118.501575ms","start":"2026-02-04T01:58:12.834335Z","end":"2026-02-04T01:58:12.952836Z","steps":["trace[28251830] 'agreement among raft nodes before linearized reading' (duration: 117.71535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:14.998771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.965147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:14.998924Z","caller":"traceutil/trace.go:171","msg":"trace[860221566] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"160.306431ms","start":"2026-02-04T01:58:14.8386Z","end":"2026-02-04T01:58:14.998906Z","steps":["trace[860221566] 'compare' (duration: 156.838745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:15.402226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.598908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:15.402381Z","caller":"traceutil/trace.go:171","msg":"trace[815770637] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1893; }","duration":"312.834922ms","start":"2026-02-04T01:58:15.08953Z","end":"2026-02-04T01:58:15.402365Z","steps":["trace[815770637] 'read index received' (duration: 154.0385ms)","trace[815770637] 'applied index is now lower than readState.Index' (duration: 158.795352ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:15.402394Z","caller":"traceutil/trace.go:171","msg":"trace[686871130] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"399.468763ms","start":"2026-02-04T01:58:15.002901Z","end":"2026-02-04T01:58:15.40237Z","steps":["trace[686871130] 'process raft request' (duration: 240.657501ms)","trace[686871130] 'compare' (duration: 158.490876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:15.4025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:15.002891Z","time spent":"399.551824ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:15.402564Z","caller":"traceutil/trace.go:171","msg":"trace[1907905659] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"394.076048ms","start":"2026-02-04T01:58:15.008476Z","end":"2026-02-04T01:58:15.402552Z","steps":["trace[1907905659] 'process raft request' (duration: 393.838664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:15.40262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:15.008456Z","time spent":"394.13647ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1860,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:58:15.402769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.532846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:58:15.402796Z","caller":"traceutil/trace.go:171","msg":"trace[18487680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1812; }","duration":"230.599837ms","start":"2026-02-04T01:58:15.17219Z","end":"2026-02-04T01:58:15.402789Z","steps":["trace[18487680] 'agreement among raft nodes before linearized reading' (duration: 230.492394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:15.402918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.390973ms","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-04T01:58:15.402997Z","caller":"traceutil/trace.go:171","msg":"trace[890923835] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1812; }","duration":"313.438214ms","start":"2026-02-04T01:58:15.089495Z","end":"2026-02-04T01:58:15.402933Z","steps":["trace[890923835] 'agreement among raft nodes before linearized reading' (duration: 313.372643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:15.403016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:15.089478Z","time spent":"313.532976ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-04T01:58:15.603562Z","caller":"traceutil/trace.go:171","msg":"trace[1790718685] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"193.214885ms","start":"2026-02-04T01:58:15.410324Z","end":"2026-02-04T01:58:15.603539Z","steps":["trace[1790718685] 'process raft request' (duration: 100.958116ms)","trace[1790718685] 'compare' (duration: 91.839702ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:18.3025Z","caller":"traceutil/trace.go:171","msg":"trace[2031497131] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"121.307199ms","start":"2026-02-04T01:58:18.181077Z","end":"2026-02-04T01:58:18.302384Z","steps":["trace[2031497131] 'process raft request' (duration: 121.171076ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:19.186964Z","caller":"traceutil/trace.go:171","msg":"trace[1774177445] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"315.664167ms","start":"2026-02-04T01:58:18.871278Z","end":"2026-02-04T01:58:19.186942Z","steps":["trace[1774177445] 'read index received' (duration: 315.480003ms)","trace[1774177445] 'applied index is now lower than readState.Index' (duration: 183.324µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:19.187071Z","caller":"traceutil/trace.go:171","msg":"trace[791659625] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"641.412318ms","start":"2026-02-04T01:58:18.545648Z","end":"2026-02-04T01:58:19.18706Z","steps":["trace[791659625] 'process raft request' (duration: 641.161583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:19.187275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.545623Z","time spent":"641.50094ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:19.187512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.359094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:58:19.187601Z","caller":"traceutil/trace.go:171","msg":"trace[1103900253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1826; }","duration":"144.498436ms","start":"2026-02-04T01:58:19.043085Z","end":"2026-02-04T01:58:19.187584Z","steps":["trace[1103900253] 'agreement among raft nodes before linearized reading' (duration: 144.307732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:19.18764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.355181ms","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-04T01:58:19.18769Z","caller":"traceutil/trace.go:171","msg":"trace[550439096] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1826; }","duration":"316.443812ms","start":"2026-02-04T01:58:18.871234Z","end":"2026-02-04T01:58:19.187678Z","steps":["trace[550439096] 'agreement among raft nodes before linearized reading' (duration: 316.36084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:19.187728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.871217Z","time spent":"316.502843ms","remote":"127.0.0.1:53550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:58:19.504938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.28023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:19.505514Z","caller":"traceutil/trace.go:171","msg":"trace[2112272487] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"313.351763ms","start":"2026-02-04T01:58:19.191957Z","end":"2026-02-04T01:58:19.505309Z","steps":["trace[2112272487] 'process raft request' (duration: 208.628643ms)","trace[2112272487] 'compare' (duration: 104.186299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:19.505691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:19.191941Z","time spent":"313.673808ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:26.244346Z","caller":"traceutil/trace.go:171","msg":"trace[897957392] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"364.953268ms","start":"2026-02-04T01:58:25.879372Z","end":"2026-02-04T01:58:26.244325Z","steps":["trace[897957392] 'process raft request' (duration: 364.827485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:26.244528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:25.879359Z","time spent":"365.082981ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:30.083277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.75346ms","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-04T01:58:30.083348Z","caller":"traceutil/trace.go:171","msg":"trace[545102112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1873; }","duration":"439.884313ms","start":"2026-02-04T01:58:29.643442Z","end":"2026-02-04T01:58:30.083326Z","steps":["trace[545102112] 'range keys from in-memory index tree' (duration: 439.582437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:30.083375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:29.643423Z","time spent":"439.946464ms","remote":"127.0.0.1:59412","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-04T01:58:30.083522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.548366ms","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-04T01:58:30.083571Z","caller":"traceutil/trace.go:171","msg":"trace[1416503693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1873; }","duration":"496.633138ms","start":"2026-02-04T01:58:29.586925Z","end":"2026-02-04T01:58:30.083558Z","steps":["trace[1416503693] 'range keys from in-memory index tree' (duration: 496.396212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:30.083594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:29.586907Z","time spent":"496.681438ms","remote":"127.0.0.1:59412","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-04T01:58:30.083537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.599736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-04T01:58:30.083748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.950591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-04T01:58:30.083762Z","caller":"traceutil/trace.go:171","msg":"trace[228067468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1873; }","duration":"412.846321ms","start":"2026-02-04T01:58:29.670909Z","end":"2026-02-04T01:58:30.083756Z","steps":["trace[228067468] 'range keys from in-memory index tree' (duration: 412.537516ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:30.083773Z","caller":"traceutil/trace.go:171","msg":"trace[255297482] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1873; }","duration":"220.999252ms","start":"2026-02-04T01:58:29.862767Z","end":"2026-02-04T01:58:30.083766Z","steps":["trace[255297482] 'range keys from in-memory index tree' (duration: 220.88155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:30.08378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:29.670897Z","time spent":"412.877902ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-04T01:58:30.610899Z","caller":"traceutil/trace.go:171","msg":"trace[913189991] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"210.772124ms","start":"2026-02-04T01:58:30.400113Z","end":"2026-02-04T01:58:30.610885Z","steps":["trace[913189991] 'process raft request' (duration: 210.682552ms)"],"step_count":1}