{"level":"warn","ts":"2026-02-25T19:29:54.129653Z","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-25T19:29:54.129747Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.37:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.37:2380","--initial-cluster=instance=https://199.19.213.37:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.37:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.37: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-25T19:29:54.129824Z","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-25T19:29:54.12984Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-02-25T19:29:54.129859Z","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-25T19:29:54.130493Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"]} {"level":"info","ts":"2026-02-25T19:29:54.130606Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.37: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-25T19:29:54.136434Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.617963ms"} {"level":"info","ts":"2026-02-25T19:29:54.14321Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7252794153f4f3cd","cluster-id":"2552855285ac01f"} {"level":"info","ts":"2026-02-25T19:29:54.143295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:29:54.143322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 0"} {"level":"info","ts":"2026-02-25T19:29:54.143335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7252794153f4f3cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:29:54.143352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 1"} {"level":"info","ts":"2026-02-25T19:29:54.143399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"warn","ts":"2026-02-25T19:29:54.149319Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:29:54.150755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:29:54.151995Z","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-25T19:29:54.153883Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7252794153f4f3cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:29:54.156458Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7252794153f4f3cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:29:54.156746Z","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-25T19:29:54.156907Z","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-25T19:29:54.156941Z","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-25T19:29:54.157626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"info","ts":"2026-02-25T19:29:54.158109Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","added-peer-id":"7252794153f4f3cd","added-peer-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-02-25T19:29:54.159838Z","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-25T19:29:54.159894Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-02-25T19:29:54.159996Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-02-25T19:29:54.160942Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7252794153f4f3cd","initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:29:54.160989Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:29:54.543612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:29:54.543673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:29:54.543712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgPreVoteResp from 7252794153f4f3cd at term 1"} {"level":"info","ts":"2026-02-25T19:29:54.543734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.543746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgVoteResp from 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.543771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became leader at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.543794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7252794153f4f3cd elected leader 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-02-25T19:29:54.560683Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:54.63884Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7252794153f4f3cd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.37:2379]}","request-path":"/0/members/7252794153f4f3cd/attributes","cluster-id":"2552855285ac01f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:29:54.638885Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:29:54.63912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:29:54.639508Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:29:54.639647Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:29:54.640648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:29:54.647388Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.37:2379"} {"level":"info","ts":"2026-02-25T19:29:54.691077Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:54.691398Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:54.691488Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T19:29:56.680476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700763089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:29:57.879269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146284798s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:29:57.879401Z","caller":"traceutil/trace.go:171","msg":"trace[2062703193] linearizableReadLoop","detail":"{readStateIndex:73; appliedIndex:72; }","duration":"1.699529073s","start":"2026-02-25T19:29:56.179851Z","end":"2026-02-25T19:29:57.87938Z","steps":["trace[2062703193] 'read index received' (duration: 552.48753ms)","trace[2062703193] 'applied index is now lower than readState.Index' (duration: 1.147040203s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:57.879432Z","caller":"traceutil/trace.go:171","msg":"trace[1215294013] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"1.727829712s","start":"2026-02-25T19:29:56.151564Z","end":"2026-02-25T19:29:57.879394Z","steps":["trace[1215294013] 'process raft request' (duration: 580.79658ms)","trace[1215294013] 'compare' (duration: 1.146100475s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:29:57.879576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:56.151548Z","time spent":"1.727961404s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:29:57.879728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69985173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:29:57.879821Z","caller":"traceutil/trace.go:171","msg":"trace[412274360] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:69; }","duration":"1.700001332s","start":"2026-02-25T19:29:56.179798Z","end":"2026-02-25T19:29:57.879799Z","steps":["trace[412274360] 'agreement among raft nodes before linearized reading' (duration: 1.699766517s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:57.879877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:56.179773Z","time spent":"1.700092114s","remote":"127.0.0.1:40046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-02-25T19:29:57.880047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00944182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:29:57.879912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644597806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:29:57.880073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009676424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:29:57.880092Z","caller":"traceutil/trace.go:171","msg":"trace[895548291] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:69; }","duration":"1.009488421s","start":"2026-02-25T19:29:56.870591Z","end":"2026-02-25T19:29:57.88008Z","steps":["trace[895548291] 'agreement among raft nodes before linearized reading' (duration: 1.009418929s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:57.880121Z","caller":"traceutil/trace.go:171","msg":"trace[724790953] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:69; }","duration":"1.009758955s","start":"2026-02-25T19:29:56.870346Z","end":"2026-02-25T19:29:57.880105Z","steps":["trace[724790953] 'agreement among raft nodes before linearized reading' (duration: 1.009675824s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:57.880124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:56.870501Z","time spent":"1.009614583s","remote":"127.0.0.1:40202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-25T19:29:57.880161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:56.870326Z","time spent":"1.009827508s","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-25T19:29:57.880121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.35526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:29:57.880241Z","caller":"traceutil/trace.go:171","msg":"trace[672794892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:69; }","duration":"905.539915ms","start":"2026-02-25T19:29:56.974672Z","end":"2026-02-25T19:29:57.880212Z","steps":["trace[672794892] 'agreement among raft nodes before linearized reading' (duration: 905.29902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:57.880312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:56.974657Z","time spent":"905.641056ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:29:57.880108Z","caller":"traceutil/trace.go:171","msg":"trace[1298484766] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:69; }","duration":"1.644929092s","start":"2026-02-25T19:29:56.235166Z","end":"2026-02-25T19:29:57.880095Z","steps":["trace[1298484766] 'agreement among raft nodes before linearized reading' (duration: 1.644465544s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:57.880934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:56.235139Z","time spent":"1.645776147s","remote":"127.0.0.1:40252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-25T19:29:58.38543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700763093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:29:58.828062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.283122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:29:58.828156Z","caller":"traceutil/trace.go:171","msg":"trace[1545812445] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"944.193853ms","start":"2026-02-25T19:29:57.883948Z","end":"2026-02-25T19:29:58.828142Z","steps":["trace[1545812445] 'process raft request' (duration: 713.787078ms)","trace[1545812445] 'compare' (duration: 230.125539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:29:58.828198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:57.883903Z","time spent":"944.279675ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:29:58.848056Z","caller":"traceutil/trace.go:171","msg":"trace[728531467] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:73; }","duration":"962.923167ms","start":"2026-02-25T19:29:57.885101Z","end":"2026-02-25T19:29:58.848024Z","steps":["trace[728531467] 'read index received' (duration: 712.572606ms)","trace[728531467] 'applied index is now lower than readState.Index' (duration: 250.349351ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:58.848185Z","caller":"traceutil/trace.go:171","msg":"trace[1864833172] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"962.740313ms","start":"2026-02-25T19:29:57.885431Z","end":"2026-02-25T19:29:58.848172Z","steps":["trace[1864833172] 'process raft request' (duration: 962.450178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:58.848296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:57.885417Z","time spent":"962.805734ms","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:29:58.848489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.14143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:29:58.848536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.484058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:29:58.848561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.444376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-02-25T19:29:58.848598Z","caller":"traceutil/trace.go:171","msg":"trace[1029069540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"872.599449ms","start":"2026-02-25T19:29:57.975982Z","end":"2026-02-25T19:29:58.848582Z","steps":["trace[1029069540] 'agreement among raft nodes before linearized reading' (duration: 872.466937ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:58.848612Z","caller":"traceutil/trace.go:171","msg":"trace[1980911898] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:71; }","duration":"963.518997ms","start":"2026-02-25T19:29:57.885079Z","end":"2026-02-25T19:29:58.848598Z","steps":["trace[1980911898] 'agreement among raft nodes before linearized reading' (duration: 963.403035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:58.848633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:57.975968Z","time spent":"872.659511ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:29:58.848649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:57.885069Z","time spent":"963.573578ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3512,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T19:29:58.848755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.774046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:29:58.848815Z","caller":"traceutil/trace.go:171","msg":"trace[1786615188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:71; }","duration":"155.872847ms","start":"2026-02-25T19:29:58.692923Z","end":"2026-02-25T19:29:58.848796Z","steps":["trace[1786615188] 'agreement among raft nodes before linearized reading' (duration: 155.45523ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:58.848596Z","caller":"traceutil/trace.go:171","msg":"trace[1258266597] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:71; }","duration":"963.232632ms","start":"2026-02-25T19:29:57.885308Z","end":"2026-02-25T19:29:58.84854Z","steps":["trace[1258266597] 'agreement among raft nodes before linearized reading' (duration: 963.044858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:58.84891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.121315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:29:58.848946Z","caller":"traceutil/trace.go:171","msg":"trace[725383968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:71; }","duration":"437.192826ms","start":"2026-02-25T19:29:58.411742Z","end":"2026-02-25T19:29:58.848935Z","steps":["trace[725383968] 'agreement among raft nodes before linearized reading' (duration: 436.657866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:58.848975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:58.41172Z","time spent":"437.248347ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:29:58.849348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:57.8853Z","time spent":"964.029278ms","remote":"127.0.0.1:40210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-02-25T19:30:00.911242Z","caller":"traceutil/trace.go:171","msg":"trace[682029967] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"253.409297ms","start":"2026-02-25T19:30:00.657809Z","end":"2026-02-25T19:30:00.911218Z","steps":["trace[682029967] 'process raft request' (duration: 253.250244ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:01.782088Z","caller":"traceutil/trace.go:171","msg":"trace[657385468] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"157.185092ms","start":"2026-02-25T19:30:01.624864Z","end":"2026-02-25T19:30:01.782049Z","steps":["trace[657385468] 'process raft request' (duration: 110.913243ms)","trace[657385468] 'compare' (duration: 46.103806ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:08.5154Z","caller":"traceutil/trace.go:171","msg":"trace[260783011] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"128.71397ms","start":"2026-02-25T19:30:08.386665Z","end":"2026-02-25T19:30:08.515379Z","steps":["trace[260783011] 'process raft request' (duration: 128.593458ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:30.45963Z","caller":"traceutil/trace.go:171","msg":"trace[1984063935] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"292.81419ms","start":"2026-02-25T19:30:30.166795Z","end":"2026-02-25T19:30:30.45961Z","steps":["trace[1984063935] 'read index received' (duration: 292.742768ms)","trace[1984063935] 'applied index is now lower than readState.Index' (duration: 70.462µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:30.459651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:29.87867Z","time spent":"580.974592ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T19:30:30.459853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.276499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:30:30.459868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.051885ms","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-25T19:30:30.459943Z","caller":"traceutil/trace.go:171","msg":"trace[1817305938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"208.417722ms","start":"2026-02-25T19:30:30.251494Z","end":"2026-02-25T19:30:30.459912Z","steps":["trace[1817305938] 'agreement among raft nodes before linearized reading' (duration: 208.292889ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:30.459969Z","caller":"traceutil/trace.go:171","msg":"trace[1334473804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"293.202038ms","start":"2026-02-25T19:30:30.166744Z","end":"2026-02-25T19:30:30.459946Z","steps":["trace[1334473804] 'agreement among raft nodes before linearized reading' (duration: 292.953742ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:31.325715Z","caller":"traceutil/trace.go:171","msg":"trace[1157753675] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"864.193973ms","start":"2026-02-25T19:30:30.46143Z","end":"2026-02-25T19:30:31.325624Z","steps":["trace[1157753675] 'process raft request' (duration: 856.611461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:31.326077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:30.461415Z","time spent":"864.412536ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:31.329212Z","caller":"traceutil/trace.go:171","msg":"trace[1948562162] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"865.428535ms","start":"2026-02-25T19:30:30.463757Z","end":"2026-02-25T19:30:31.329185Z","steps":["trace[1948562162] 'process raft request' (duration: 864.985277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:31.329499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:30.463742Z","time spent":"865.558309ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:30:35.496183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.989674ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T19:30:35.496309Z","caller":"traceutil/trace.go:171","msg":"trace[1137554274] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"243.237128ms","start":"2026-02-25T19:30:35.253048Z","end":"2026-02-25T19:30:35.496285Z","steps":["trace[1137554274] 'read index received' (duration: 28.159743ms)","trace[1137554274] 'applied index is now lower than readState.Index' (duration: 215.075655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:35.496488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.413101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:35.49658Z","caller":"traceutil/trace.go:171","msg":"trace[1512715845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"243.560794ms","start":"2026-02-25T19:30:35.252997Z","end":"2026-02-25T19:30:35.496557Z","steps":["trace[1512715845] 'agreement among raft nodes before linearized reading' (duration: 243.374221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:35.496615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.766822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:30:35.496592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.649805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:30:35.496681Z","caller":"traceutil/trace.go:171","msg":"trace[462089700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"128.865794ms","start":"2026-02-25T19:30:35.367799Z","end":"2026-02-25T19:30:35.496664Z","steps":["trace[462089700] 'agreement among raft nodes before linearized reading' (duration: 128.731772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:35.496701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.448207ms","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-25T19:30:35.496786Z","caller":"traceutil/trace.go:171","msg":"trace[1824016055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"141.58009ms","start":"2026-02-25T19:30:35.355184Z","end":"2026-02-25T19:30:35.496765Z","steps":["trace[1824016055] 'agreement among raft nodes before linearized reading' (duration: 141.405897ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:35.496732Z","caller":"traceutil/trace.go:171","msg":"trace[226681851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"152.847929ms","start":"2026-02-25T19:30:35.34386Z","end":"2026-02-25T19:30:35.496708Z","steps":["trace[226681851] 'agreement among raft nodes before linearized reading' (duration: 152.585354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:35.496601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.500388ms","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-25T19:30:35.496982Z","caller":"traceutil/trace.go:171","msg":"trace[1476631458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"127.915046ms","start":"2026-02-25T19:30:35.369054Z","end":"2026-02-25T19:30:35.49697Z","steps":["trace[1476631458] 'agreement among raft nodes before linearized reading' (duration: 127.443317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:36.303488Z","caller":"traceutil/trace.go:171","msg":"trace[988849050] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"801.659011ms","start":"2026-02-25T19:30:35.5018Z","end":"2026-02-25T19:30:36.303459Z","steps":["trace[988849050] 'process raft request' (duration: 801.418017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:36.303657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:35.501776Z","time spent":"801.795823ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:36.303787Z","caller":"traceutil/trace.go:171","msg":"trace[266479727] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"801.889175ms","start":"2026-02-25T19:30:35.501859Z","end":"2026-02-25T19:30:36.303748Z","steps":["trace[266479727] 'process raft request' (duration: 801.550439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:36.303991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:35.501842Z","time spent":"802.028147ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:36.304544Z","caller":"traceutil/trace.go:171","msg":"trace[408928614] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"802.616309ms","start":"2026-02-25T19:30:35.501909Z","end":"2026-02-25T19:30:36.304526Z","steps":["trace[408928614] 'process raft request' (duration: 802.167301ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:36.304708Z","caller":"traceutil/trace.go:171","msg":"trace[167535021] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"796.497206ms","start":"2026-02-25T19:30:35.508188Z","end":"2026-02-25T19:30:36.304686Z","steps":["trace[167535021] 'process raft request' (duration: 796.288632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:36.304824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:35.508175Z","time spent":"796.591727ms","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:30:36.304935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:35.501896Z","time spent":"802.716261ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:30:37.499024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.462998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:37.4991Z","caller":"traceutil/trace.go:171","msg":"trace[1025874757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"246.59043ms","start":"2026-02-25T19:30:37.25249Z","end":"2026-02-25T19:30:37.49908Z","steps":["trace[1025874757] 'range keys from in-memory index tree' (duration: 246.344776ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:37.819383Z","caller":"traceutil/trace.go:171","msg":"trace[395811784] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"236.680167ms","start":"2026-02-25T19:30:37.58268Z","end":"2026-02-25T19:30:37.81936Z","steps":["trace[395811784] 'process raft request' (duration: 236.551494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:38.523008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.401861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:30:38.52314Z","caller":"traceutil/trace.go:171","msg":"trace[1662078403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"215.550193ms","start":"2026-02-25T19:30:38.307528Z","end":"2026-02-25T19:30:38.523079Z","steps":["trace[1662078403] 'range keys from in-memory index tree' (duration: 215.308059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:38.523469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.423555ms","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-25T19:30:38.523538Z","caller":"traceutil/trace.go:171","msg":"trace[460790962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"213.524246ms","start":"2026-02-25T19:30:38.309998Z","end":"2026-02-25T19:30:38.523522Z","steps":["trace[460790962] 'range keys from in-memory index tree' (duration: 213.282462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:38.523673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.269981ms","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-25T19:30:38.52373Z","caller":"traceutil/trace.go:171","msg":"trace[947274877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"213.380454ms","start":"2026-02-25T19:30:38.310336Z","end":"2026-02-25T19:30:38.523717Z","steps":["trace[947274877] 'range keys from in-memory index tree' (duration: 213.116079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:40.062169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.231684ms","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-25T19:30:40.062275Z","caller":"traceutil/trace.go:171","msg":"trace[1756407883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"239.397407ms","start":"2026-02-25T19:30:39.822854Z","end":"2026-02-25T19:30:40.062251Z","steps":["trace[1756407883] 'range keys from in-memory index tree' (duration: 239.097771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:40.062275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.376425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:30:40.062356Z","caller":"traceutil/trace.go:171","msg":"trace[1855249974] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:504; }","duration":"184.514478ms","start":"2026-02-25T19:30:39.877822Z","end":"2026-02-25T19:30:40.062337Z","steps":["trace[1855249974] 'range keys from in-memory index tree' (duration: 184.192832ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:41.14906Z","caller":"traceutil/trace.go:171","msg":"trace[603173338] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"117.269519ms","start":"2026-02-25T19:30:41.031765Z","end":"2026-02-25T19:30:41.149034Z","steps":["trace[603173338] 'process raft request' (duration: 117.119766ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:41.266384Z","caller":"traceutil/trace.go:171","msg":"trace[992629153] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"107.355963ms","start":"2026-02-25T19:30:41.158666Z","end":"2026-02-25T19:30:41.266022Z","steps":["trace[992629153] 'process raft request' (duration: 107.137309ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:43.795868Z","caller":"traceutil/trace.go:171","msg":"trace[423468190] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"139.054563ms","start":"2026-02-25T19:30:43.656145Z","end":"2026-02-25T19:30:43.7952Z","steps":["trace[423468190] 'process raft request' (duration: 138.85264ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:44.164924Z","caller":"traceutil/trace.go:171","msg":"trace[1026685476] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"167.127185ms","start":"2026-02-25T19:30:43.997771Z","end":"2026-02-25T19:30:44.164898Z","steps":["trace[1026685476] 'process raft request' (duration: 166.693767ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:44.476716Z","caller":"traceutil/trace.go:171","msg":"trace[1062376726] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"272.126406ms","start":"2026-02-25T19:30:44.204565Z","end":"2026-02-25T19:30:44.476691Z","steps":["trace[1062376726] 'read index received' (duration: 211.280335ms)","trace[1062376726] 'applied index is now lower than readState.Index' (duration: 60.845061ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:44.477004Z","caller":"traceutil/trace.go:171","msg":"trace[416067746] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"413.006642ms","start":"2026-02-25T19:30:44.063964Z","end":"2026-02-25T19:30:44.476971Z","steps":["trace[416067746] 'process raft request' (duration: 352.247902ms)","trace[416067746] 'compare' (duration: 60.119627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:44.477219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:44.063894Z","time spent":"413.186495ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:30:44.477054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.471501ms","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-25T19:30:44.478027Z","caller":"traceutil/trace.go:171","msg":"trace[740392816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"273.471351ms","start":"2026-02-25T19:30:44.204525Z","end":"2026-02-25T19:30:44.477997Z","steps":["trace[740392816] 'agreement among raft nodes before linearized reading' (duration: 272.37106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:44.478732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.888421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-25T19:30:44.478826Z","caller":"traceutil/trace.go:171","msg":"trace[1602675619] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"252.434379ms","start":"2026-02-25T19:30:44.226356Z","end":"2026-02-25T19:30:44.478791Z","steps":["trace[1602675619] 'agreement among raft nodes before linearized reading' (duration: 250.661006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:44.481786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.088799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:44.481911Z","caller":"traceutil/trace.go:171","msg":"trace[1832544569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"230.726515ms","start":"2026-02-25T19:30:44.251156Z","end":"2026-02-25T19:30:44.481883Z","steps":["trace[1832544569] 'agreement among raft nodes before linearized reading' (duration: 226.12797ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:44.621295Z","caller":"traceutil/trace.go:171","msg":"trace[1472947019] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"135.852824ms","start":"2026-02-25T19:30:44.485414Z","end":"2026-02-25T19:30:44.621267Z","steps":["trace[1472947019] 'process raft request' (duration: 121.953345ms)","trace[1472947019] 'compare' (duration: 13.663015ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:44.621684Z","caller":"traceutil/trace.go:171","msg":"trace[595613201] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"136.161509ms","start":"2026-02-25T19:30:44.485493Z","end":"2026-02-25T19:30:44.621655Z","steps":["trace[595613201] 'process raft request' (duration: 135.723281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:44.621899Z","caller":"traceutil/trace.go:171","msg":"trace[1426668399] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"115.591627ms","start":"2026-02-25T19:30:44.506279Z","end":"2026-02-25T19:30:44.62187Z","steps":["trace[1426668399] 'process raft request' (duration: 115.21372ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:44.621907Z","caller":"traceutil/trace.go:171","msg":"trace[250559039] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:590; }","duration":"117.247628ms","start":"2026-02-25T19:30:44.504629Z","end":"2026-02-25T19:30:44.621877Z","steps":["trace[250559039] 'read index received' (duration: 102.80358ms)","trace[250559039] 'applied index is now lower than readState.Index' (duration: 14.442898ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:44.622498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.85757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fvr2r\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-02-25T19:30:44.622551Z","caller":"traceutil/trace.go:171","msg":"trace[1039333374] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fvr2r; range_end:; response_count:1; response_revision:576; }","duration":"117.947191ms","start":"2026-02-25T19:30:44.504591Z","end":"2026-02-25T19:30:44.622538Z","steps":["trace[1039333374] 'agreement among raft nodes before linearized reading' (duration: 117.708126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:48.805914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.159396ms","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-25T19:30:48.805965Z","caller":"traceutil/trace.go:171","msg":"trace[1299381744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:608; }","duration":"167.244307ms","start":"2026-02-25T19:30:48.63871Z","end":"2026-02-25T19:30:48.805954Z","steps":["trace[1299381744] 'range keys from in-memory index tree' (duration: 167.060464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:48.806146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.941066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:30:48.806124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.295838ms","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-25T19:30:48.806164Z","caller":"traceutil/trace.go:171","msg":"trace[1526844280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"128.968396ms","start":"2026-02-25T19:30:48.67719Z","end":"2026-02-25T19:30:48.806158Z","steps":["trace[1526844280] 'range keys from in-memory index tree' (duration: 128.898074ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:48.806185Z","caller":"traceutil/trace.go:171","msg":"trace[652384587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"167.39236ms","start":"2026-02-25T19:30:48.638778Z","end":"2026-02-25T19:30:48.80617Z","steps":["trace[652384587] 'range keys from in-memory index tree' (duration: 167.171436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:48.806198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.239072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:30:48.806252Z","caller":"traceutil/trace.go:171","msg":"trace[612435602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"100.338774ms","start":"2026-02-25T19:30:48.7059Z","end":"2026-02-25T19:30:48.806239Z","steps":["trace[612435602] 'range keys from in-memory index tree' (duration: 100.13745ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:48.925882Z","caller":"traceutil/trace.go:171","msg":"trace[404718264] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"116.733708ms","start":"2026-02-25T19:30:48.809133Z","end":"2026-02-25T19:30:48.925867Z","steps":["trace[404718264] 'process raft request' (duration: 116.687027ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:48.9259Z","caller":"traceutil/trace.go:171","msg":"trace[1123710847] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"117.171496ms","start":"2026-02-25T19:30:48.8087Z","end":"2026-02-25T19:30:48.925871Z","steps":["trace[1123710847] 'process raft request' (duration: 117.047134ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:48.926033Z","caller":"traceutil/trace.go:171","msg":"trace[2125907010] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"115.661188ms","start":"2026-02-25T19:30:48.810364Z","end":"2026-02-25T19:30:48.926025Z","steps":["trace[2125907010] 'process raft request' (duration: 115.480295ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:48.92628Z","caller":"traceutil/trace.go:171","msg":"trace[732039702] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"115.859612ms","start":"2026-02-25T19:30:48.810401Z","end":"2026-02-25T19:30:48.926261Z","steps":["trace[732039702] 'process raft request' (duration: 115.587296ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:48.926276Z","caller":"traceutil/trace.go:171","msg":"trace[545806767] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"115.828482ms","start":"2026-02-25T19:30:48.810426Z","end":"2026-02-25T19:30:48.926254Z","steps":["trace[545806767] 'process raft request' (duration: 115.74323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:49.50932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.818133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fvr2r\" ","response":"range_response_count:1 size:18709"} {"level":"info","ts":"2026-02-25T19:30:49.509397Z","caller":"traceutil/trace.go:171","msg":"trace[1041681355] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fvr2r; range_end:; response_count:1; response_revision:615; }","duration":"311.930024ms","start":"2026-02-25T19:30:49.197452Z","end":"2026-02-25T19:30:49.509382Z","steps":["trace[1041681355] 'range keys from in-memory index tree' (duration: 311.711811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:49.509428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:49.197435Z","time spent":"311.986645ms","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18731,"request content":"key:\"/registry/pods/kube-system/cilium-fvr2r\" "} {"level":"warn","ts":"2026-02-25T19:30:49.752549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700764214,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:30:49.980872Z","caller":"traceutil/trace.go:171","msg":"trace[823379951] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"778.044983ms","start":"2026-02-25T19:30:49.202811Z","end":"2026-02-25T19:30:49.980856Z","steps":["trace[823379951] 'process raft request' (duration: 777.922371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:49.980969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:49.202798Z","time spent":"778.125954ms","remote":"127.0.0.1:54296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:30:50.71217Z","caller":"traceutil/trace.go:171","msg":"trace[123881556] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:635; }","duration":"1.459905258s","start":"2026-02-25T19:30:49.25224Z","end":"2026-02-25T19:30:50.712145Z","steps":["trace[123881556] 'read index received' (duration: 728.601124ms)","trace[123881556] 'applied index is now lower than readState.Index' (duration: 731.303294ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:50.712203Z","caller":"traceutil/trace.go:171","msg":"trace[1479435904] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.189107798s","start":"2026-02-25T19:30:49.523075Z","end":"2026-02-25T19:30:50.712183Z","steps":["trace[1479435904] 'process raft request' (duration: 1.188890024s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:50.712317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.102704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T19:30:50.712328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:49.523049Z","time spent":"1.18920184s","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:50.712352Z","caller":"traceutil/trace.go:171","msg":"trace[358979897] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:618; }","duration":"834.143035ms","start":"2026-02-25T19:30:49.878196Z","end":"2026-02-25T19:30:50.712339Z","steps":["trace[358979897] 'agreement among raft nodes before linearized reading' (duration: 834.074714ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:50.712363Z","caller":"traceutil/trace.go:171","msg":"trace[1568756769] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.183745068s","start":"2026-02-25T19:30:49.528599Z","end":"2026-02-25T19:30:50.712344Z","steps":["trace[1568756769] 'process raft request' (duration: 1.183497753s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:50.712382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:49.878141Z","time spent":"834.233606ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.37\" "} {"level":"warn","ts":"2026-02-25T19:30:50.712396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460121513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:30:50.712449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:49.528578Z","time spent":"1.18382512s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:50.712511Z","caller":"traceutil/trace.go:171","msg":"trace[2041037035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.460266225s","start":"2026-02-25T19:30:49.252201Z","end":"2026-02-25T19:30:50.712467Z","steps":["trace[2041037035] 'agreement among raft nodes before linearized reading' (duration: 1.460080842s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:50.712608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:49.252185Z","time spent":"1.460407947s","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:30:50.833731Z","caller":"traceutil/trace.go:171","msg":"trace[276010598] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"101.383083ms","start":"2026-02-25T19:30:50.732322Z","end":"2026-02-25T19:30:50.833705Z","steps":["trace[276010598] 'process raft request' (duration: 100.791392ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:50.954666Z","caller":"traceutil/trace.go:171","msg":"trace[18351515] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"106.394836ms","start":"2026-02-25T19:30:50.848248Z","end":"2026-02-25T19:30:50.954643Z","steps":["trace[18351515] 'process raft request' (duration: 106.222063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:57.897311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.153564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:30:57.897412Z","caller":"traceutil/trace.go:171","msg":"trace[402341304] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"758.241395ms","start":"2026-02-25T19:30:57.139151Z","end":"2026-02-25T19:30:57.897393Z","steps":["trace[402341304] 'process raft request' (duration: 449.930058ms)","trace[402341304] 'compare' (duration: 308.060223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:57.897465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:57.13913Z","time spent":"758.311227ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:57.898921Z","caller":"traceutil/trace.go:171","msg":"trace[1933152268] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"759.327995ms","start":"2026-02-25T19:30:57.139545Z","end":"2026-02-25T19:30:57.898873Z","steps":["trace[1933152268] 'process raft request' (duration: 759.139911ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:57.899205Z","caller":"traceutil/trace.go:171","msg":"trace[1196134166] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"759.525218ms","start":"2026-02-25T19:30:57.139654Z","end":"2026-02-25T19:30:57.899179Z","steps":["trace[1196134166] 'process raft request' (duration: 759.175292ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:57.899246Z","caller":"traceutil/trace.go:171","msg":"trace[2126698468] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:700; }","duration":"646.268525ms","start":"2026-02-25T19:30:57.252946Z","end":"2026-02-25T19:30:57.899215Z","steps":["trace[2126698468] 'read index received' (duration: 336.109314ms)","trace[2126698468] 'applied index is now lower than readState.Index' (duration: 310.157711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:57.899388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.423437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:57.899381Z","caller":"traceutil/trace.go:171","msg":"trace[335602199] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"757.700045ms","start":"2026-02-25T19:30:57.141657Z","end":"2026-02-25T19:30:57.899357Z","steps":["trace[335602199] 'process raft request' (duration: 757.41555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:57.899611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:57.139531Z","time spent":"759.59704ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:30:57.899717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:57.14163Z","time spent":"757.990231ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:57.899419Z","caller":"traceutil/trace.go:171","msg":"trace[143273177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"646.535829ms","start":"2026-02-25T19:30:57.252871Z","end":"2026-02-25T19:30:57.899407Z","steps":["trace[143273177] 'agreement among raft nodes before linearized reading' (duration: 646.452978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:57.899406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:57.139608Z","time spent":"759.667071ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:30:57.901212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:57.252828Z","time spent":"648.339464ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:01.104439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07935777s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:01.104552Z","caller":"traceutil/trace.go:171","msg":"trace[687457989] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.146773913s","start":"2026-02-25T19:30:59.957758Z","end":"2026-02-25T19:31:01.104532Z","steps":["trace[687457989] 'process raft request' (duration: 65.67631ms)","trace[687457989] 'compare' (duration: 1.079077435s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:01.104641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:59.957735Z","time spent":"1.146872524s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:01.603388Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.453348057s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:31:01.92832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.672931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:01.928647Z","caller":"traceutil/trace.go:171","msg":"trace[354615461] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.969955633s","start":"2026-02-25T19:30:59.958672Z","end":"2026-02-25T19:31:01.928628Z","steps":["trace[354615461] 'process raft request' (duration: 1.644902495s)","trace[354615461] 'compare' (duration: 324.388747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:01.928712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:59.958657Z","time spent":"1.970023004s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:01.928751Z","caller":"traceutil/trace.go:171","msg":"trace[1853852082] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.969909582s","start":"2026-02-25T19:30:59.958817Z","end":"2026-02-25T19:31:01.928727Z","steps":["trace[1853852082] 'process raft request' (duration: 1.969686568s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:01.928807Z","caller":"traceutil/trace.go:171","msg":"trace[1095334192] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:707; }","duration":"1.96876594s","start":"2026-02-25T19:30:59.960034Z","end":"2026-02-25T19:31:01.9288Z","steps":["trace[1095334192] 'read index received' (duration: 63.472738ms)","trace[1095334192] 'applied index is now lower than readState.Index' (duration: 1.905292182s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:01.928889Z","caller":"traceutil/trace.go:171","msg":"trace[886238023] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.970144267s","start":"2026-02-25T19:30:59.958731Z","end":"2026-02-25T19:31:01.928876Z","steps":["trace[886238023] 'process raft request' (duration: 1.969697589s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.928901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830276528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T19:31:01.92889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:59.958804Z","time spent":"1.970006734s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:01.928924Z","caller":"traceutil/trace.go:171","msg":"trace[1959511945] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:689; }","duration":"1.83033315s","start":"2026-02-25T19:31:00.098583Z","end":"2026-02-25T19:31:01.928917Z","steps":["trace[1959511945] 'agreement among raft nodes before linearized reading' (duration: 1.830283079s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.928944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:00.098568Z","time spent":"1.83037166s","remote":"127.0.0.1:40020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:31:01.928944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:59.958726Z","time spent":"1.970190018s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:01.928957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.246065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:31:01.92877Z","caller":"traceutil/trace.go:171","msg":"trace[390334712] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"947.255756ms","start":"2026-02-25T19:31:00.9815Z","end":"2026-02-25T19:31:01.928756Z","steps":["trace[390334712] 'process raft request' (duration: 947.044602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.929004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968950775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:31:01.929031Z","caller":"traceutil/trace.go:171","msg":"trace[1496970875] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:689; }","duration":"274.357896ms","start":"2026-02-25T19:31:01.654654Z","end":"2026-02-25T19:31:01.929012Z","steps":["trace[1496970875] 'agreement among raft nodes before linearized reading' (duration: 274.233574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.929032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.877354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:31:01.92907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:00.981475Z","time spent":"947.563001ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:01.929073Z","caller":"traceutil/trace.go:171","msg":"trace[626789891] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:689; }","duration":"1.969054006s","start":"2026-02-25T19:30:59.960001Z","end":"2026-02-25T19:31:01.929055Z","steps":["trace[626789891] 'agreement among raft nodes before linearized reading' (duration: 1.968840482s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.929107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.221762ms","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-25T19:31:01.929103Z","caller":"traceutil/trace.go:171","msg":"trace[286993675] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:689; }","duration":"269.000307ms","start":"2026-02-25T19:31:01.660086Z","end":"2026-02-25T19:31:01.929086Z","steps":["trace[286993675] 'agreement among raft nodes before linearized reading' (duration: 268.874434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.92912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67747002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:01.929149Z","caller":"traceutil/trace.go:171","msg":"trace[285558518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"1.677521131s","start":"2026-02-25T19:31:00.251618Z","end":"2026-02-25T19:31:01.929139Z","steps":["trace[285558518] 'agreement among raft nodes before linearized reading' (duration: 1.67748441s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.929174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:00.251603Z","time spent":"1.677566282s","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:01.929176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:59.959992Z","time spent":"1.969166188s","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-25T19:31:01.929125Z","caller":"traceutil/trace.go:171","msg":"trace[1623785678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"781.268642ms","start":"2026-02-25T19:31:01.147851Z","end":"2026-02-25T19:31:01.92912Z","steps":["trace[1623785678] 'agreement among raft nodes before linearized reading' (duration: 781.191811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.929226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:01.147837Z","time spent":"781.383936ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:31:01.929082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72436454s","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":"info","ts":"2026-02-25T19:31:01.929292Z","caller":"traceutil/trace.go:171","msg":"trace[1056766736] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:689; }","duration":"1.724610636s","start":"2026-02-25T19:31:00.204672Z","end":"2026-02-25T19:31:01.929283Z","steps":["trace[1056766736] 'agreement among raft nodes before linearized reading' (duration: 1.724381321s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:01.929313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:00.204651Z","time spent":"1.724655827s","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:31:05.451859Z","caller":"traceutil/trace.go:171","msg":"trace[162812168] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"232.122521ms","start":"2026-02-25T19:31:05.219718Z","end":"2026-02-25T19:31:05.45184Z","steps":["trace[162812168] 'read index received' (duration: 231.977648ms)","trace[162812168] 'applied index is now lower than readState.Index' (duration: 143.893µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:05.451987Z","caller":"traceutil/trace.go:171","msg":"trace[1167731772] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"332.931025ms","start":"2026-02-25T19:31:05.119026Z","end":"2026-02-25T19:31:05.451957Z","steps":["trace[1167731772] 'process raft request' (duration: 332.686539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:05.452021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.302625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T19:31:05.452134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:05.118994Z","time spent":"333.059656ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:05.452174Z","caller":"traceutil/trace.go:171","msg":"trace[1096714757] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:703; }","duration":"232.482008ms","start":"2026-02-25T19:31:05.219682Z","end":"2026-02-25T19:31:05.452164Z","steps":["trace[1096714757] 'agreement among raft nodes before linearized reading' (duration: 232.287795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:05.702728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.346102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:05.702815Z","caller":"traceutil/trace.go:171","msg":"trace[388657554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"452.498776ms","start":"2026-02-25T19:31:05.250293Z","end":"2026-02-25T19:31:05.702792Z","steps":["trace[388657554] 'agreement among raft nodes before linearized reading' (duration: 201.750118ms)","trace[388657554] 'range keys from in-memory index tree' (duration: 250.614136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:05.702861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:05.250267Z","time spent":"452.584818ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:06.100921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.782619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T19:31:06.101039Z","caller":"traceutil/trace.go:171","msg":"trace[2072052334] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"157.330422ms","start":"2026-02-25T19:31:05.94369Z","end":"2026-02-25T19:31:06.10102Z","steps":["trace[2072052334] 'read index received' (duration: 35.53µs)","trace[2072052334] 'applied index is now lower than readState.Index' (duration: 157.293262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:06.101252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.57839ms","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-25T19:31:06.101286Z","caller":"traceutil/trace.go:171","msg":"trace[1510757631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"155.621241ms","start":"2026-02-25T19:31:05.945656Z","end":"2026-02-25T19:31:06.101277Z","steps":["trace[1510757631] 'agreement among raft nodes before linearized reading' (duration: 155.472298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:06.101308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.387791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:31:06.101344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.621541ms","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-25T19:31:06.101382Z","caller":"traceutil/trace.go:171","msg":"trace[1927130001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"103.514023ms","start":"2026-02-25T19:31:05.997847Z","end":"2026-02-25T19:31:06.101362Z","steps":["trace[1927130001] 'agreement among raft nodes before linearized reading' (duration: 103.279069ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:06.101424Z","caller":"traceutil/trace.go:171","msg":"trace[1149738944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"155.723733ms","start":"2026-02-25T19:31:05.945681Z","end":"2026-02-25T19:31:06.101404Z","steps":["trace[1149738944] 'agreement among raft nodes before linearized reading' (duration: 155.512769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:06.101726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.025195ms","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-25T19:31:06.101786Z","caller":"traceutil/trace.go:171","msg":"trace[687813543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"158.123418ms","start":"2026-02-25T19:31:05.943647Z","end":"2026-02-25T19:31:06.10177Z","steps":["trace[687813543] 'agreement among raft nodes before linearized reading' (duration: 157.459085ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:06.290604Z","caller":"traceutil/trace.go:171","msg":"trace[318928444] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"185.903443ms","start":"2026-02-25T19:31:06.104676Z","end":"2026-02-25T19:31:06.290579Z","steps":["trace[318928444] 'process raft request' (duration: 185.74748ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:06.309286Z","caller":"traceutil/trace.go:171","msg":"trace[1488109839] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"203.245396ms","start":"2026-02-25T19:31:06.106026Z","end":"2026-02-25T19:31:06.309271Z","steps":["trace[1488109839] 'process raft request' (duration: 203.171204ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:06.309353Z","caller":"traceutil/trace.go:171","msg":"trace[2128954693] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"203.582843ms","start":"2026-02-25T19:31:06.105739Z","end":"2026-02-25T19:31:06.309322Z","steps":["trace[2128954693] 'process raft request' (duration: 198.413026ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:06.3095Z","caller":"traceutil/trace.go:171","msg":"trace[850043995] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"203.196195ms","start":"2026-02-25T19:31:06.106293Z","end":"2026-02-25T19:31:06.309489Z","steps":["trace[850043995] 'process raft request' (duration: 202.94453ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:12.51316Z","caller":"traceutil/trace.go:171","msg":"trace[927222723] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"111.058173ms","start":"2026-02-25T19:31:12.402073Z","end":"2026-02-25T19:31:12.513131Z","steps":["trace[927222723] 'process raft request' (duration: 110.834419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:12.513317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.766561ms","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-25T19:31:12.513461Z","caller":"traceutil/trace.go:171","msg":"trace[1519970200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"115.995755ms","start":"2026-02-25T19:31:12.397443Z","end":"2026-02-25T19:31:12.513438Z","steps":["trace[1519970200] 'agreement among raft nodes before linearized reading' (duration: 115.652799ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:12.638938Z","caller":"traceutil/trace.go:171","msg":"trace[1978478275] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"121.229982ms","start":"2026-02-25T19:31:12.517691Z","end":"2026-02-25T19:31:12.638921Z","steps":["trace[1978478275] 'process raft request' (duration: 121.192201ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:12.639023Z","caller":"traceutil/trace.go:171","msg":"trace[436502639] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"159.545473ms","start":"2026-02-25T19:31:12.479435Z","end":"2026-02-25T19:31:12.638981Z","steps":["trace[436502639] 'process raft request' (duration: 110.197936ms)","trace[436502639] 'compare' (duration: 49.141893ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:22.604619Z","caller":"traceutil/trace.go:171","msg":"trace[472923306] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"220.093938ms","start":"2026-02-25T19:31:22.384491Z","end":"2026-02-25T19:31:22.604585Z","steps":["trace[472923306] 'process raft request' (duration: 219.753822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:23.192968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700764524,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:23.573116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.312561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:23.573182Z","caller":"traceutil/trace.go:171","msg":"trace[1270628155] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"964.591059ms","start":"2026-02-25T19:31:22.60858Z","end":"2026-02-25T19:31:23.573172Z","steps":["trace[1270628155] 'process raft request' (duration: 687.173525ms)","trace[1270628155] 'compare' (duration: 277.24481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:23.573213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:22.608564Z","time spent":"964.635049ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:23.788586Z","caller":"traceutil/trace.go:171","msg":"trace[1950325355] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:776; }","duration":"1.096122722s","start":"2026-02-25T19:31:22.692442Z","end":"2026-02-25T19:31:23.788565Z","steps":["trace[1950325355] 'read index received' (duration: 603.326147ms)","trace[1950325355] 'applied index is now lower than readState.Index' (duration: 492.795185ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:23.788835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091677699s","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":"warn","ts":"2026-02-25T19:31:23.788845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096383666s","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-25T19:31:23.788879Z","caller":"traceutil/trace.go:171","msg":"trace[1368508944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"1.091796281s","start":"2026-02-25T19:31:22.697072Z","end":"2026-02-25T19:31:23.788868Z","steps":["trace[1368508944] 'agreement among raft nodes before linearized reading' (duration: 1.091637748s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:23.788728Z","caller":"traceutil/trace.go:171","msg":"trace[1845609734] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.179712155s","start":"2026-02-25T19:31:22.608959Z","end":"2026-02-25T19:31:23.788671Z","steps":["trace[1845609734] 'process raft request' (duration: 1.098417734s)","trace[1845609734] 'compare' (duration: 81.090307ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:23.7889Z","caller":"traceutil/trace.go:171","msg":"trace[1067233799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"1.096497828s","start":"2026-02-25T19:31:22.692391Z","end":"2026-02-25T19:31:23.788889Z","steps":["trace[1067233799] 'agreement among raft nodes before linearized reading' (duration: 1.096289055s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:23.788911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:22.697049Z","time spent":"1.091854902s","remote":"127.0.0.1:40128","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-25T19:31:23.788938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:22.692369Z","time spent":"1.096559619s","remote":"127.0.0.1:40128","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-25T19:31:23.788954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.71739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T19:31:23.789003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:22.608939Z","time spent":"1.179989859s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:23.789023Z","caller":"traceutil/trace.go:171","msg":"trace[500896868] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:750; }","duration":"929.821692ms","start":"2026-02-25T19:31:22.85918Z","end":"2026-02-25T19:31:23.789002Z","steps":["trace[500896868] 'agreement among raft nodes before linearized reading' (duration: 929.71263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:23.789065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.829169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:31:23.789089Z","caller":"traceutil/trace.go:171","msg":"trace[2108548360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"207.883371ms","start":"2026-02-25T19:31:23.5812Z","end":"2026-02-25T19:31:23.789083Z","steps":["trace[2108548360] 'agreement among raft nodes before linearized reading' (duration: 207.81876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:23.789112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:22.85916Z","time spent":"929.935514ms","remote":"127.0.0.1:40300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:31:23.788913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.881965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:23.789231Z","caller":"traceutil/trace.go:171","msg":"trace[1313521562] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:750; }","duration":"212.199151ms","start":"2026-02-25T19:31:23.576984Z","end":"2026-02-25T19:31:23.789183Z","steps":["trace[1313521562] 'agreement among raft nodes before linearized reading' (duration: 211.810504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:23.789637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.020646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:23.789693Z","caller":"traceutil/trace.go:171","msg":"trace[1971534431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"537.080858ms","start":"2026-02-25T19:31:23.252598Z","end":"2026-02-25T19:31:23.789678Z","steps":["trace[1971534431] 'agreement among raft nodes before linearized reading' (duration: 536.944075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:23.789727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:23.252462Z","time spent":"537.2576ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:31:23.981849Z","caller":"traceutil/trace.go:171","msg":"trace[1512084120] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"188.010082ms","start":"2026-02-25T19:31:23.793816Z","end":"2026-02-25T19:31:23.981826Z","steps":["trace[1512084120] 'process raft request' (duration: 112.797484ms)","trace[1512084120] 'compare' (duration: 75.057545ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:23.982013Z","caller":"traceutil/trace.go:171","msg":"trace[900904973] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"187.94044ms","start":"2026-02-25T19:31:23.794048Z","end":"2026-02-25T19:31:23.981988Z","steps":["trace[900904973] 'process raft request' (duration: 187.743237ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:25.099379Z","caller":"traceutil/trace.go:171","msg":"trace[1179600706] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"388.438576ms","start":"2026-02-25T19:31:24.710867Z","end":"2026-02-25T19:31:25.099306Z","steps":["trace[1179600706] 'process raft request' (duration: 388.199981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:25.099734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:24.710843Z","time spent":"388.650489ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:26.147199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.011946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:26.147323Z","caller":"traceutil/trace.go:171","msg":"trace[1496311083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"894.18916ms","start":"2026-02-25T19:31:25.25311Z","end":"2026-02-25T19:31:26.147299Z","steps":["trace[1496311083] 'range keys from in-memory index tree' (duration: 893.929305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:26.147384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.253086Z","time spent":"894.279792ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:26.594207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049446194s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T19:31:26.594333Z","caller":"traceutil/trace.go:171","msg":"trace[29514445] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"1.017089123s","start":"2026-02-25T19:31:25.577221Z","end":"2026-02-25T19:31:26.59431Z","steps":["trace[29514445] 'read index received' (duration: 34.111µs)","trace[29514445] 'applied index is now lower than readState.Index' (duration: 1.017053022s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:26.594923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017687444s","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-25T19:31:26.59504Z","caller":"traceutil/trace.go:171","msg":"trace[1989556318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"1.017842067s","start":"2026-02-25T19:31:25.577181Z","end":"2026-02-25T19:31:26.595023Z","steps":["trace[1989556318] 'agreement among raft nodes before linearized reading' (duration: 1.017598853s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:26.595125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.577162Z","time spent":"1.017944249s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:31:26.595137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.094884ms","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-25T19:31:26.595194Z","caller":"traceutil/trace.go:171","msg":"trace[289339440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"609.184636ms","start":"2026-02-25T19:31:25.985995Z","end":"2026-02-25T19:31:26.59518Z","steps":["trace[289339440] 'agreement among raft nodes before linearized reading' (duration: 609.056694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:26.595183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.391641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:31:26.595319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.652541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T19:31:26.59534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.406142ms","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-25T19:31:26.595358Z","caller":"traceutil/trace.go:171","msg":"trace[1875539910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"800.682123ms","start":"2026-02-25T19:31:25.79466Z","end":"2026-02-25T19:31:26.595343Z","steps":["trace[1875539910] 'agreement among raft nodes before linearized reading' (duration: 800.614551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:26.595373Z","caller":"traceutil/trace.go:171","msg":"trace[1391343086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"608.435692ms","start":"2026-02-25T19:31:25.986927Z","end":"2026-02-25T19:31:26.595363Z","steps":["trace[1391343086] 'agreement among raft nodes before linearized reading' (duration: 608.34668ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:26.595359Z","caller":"traceutil/trace.go:171","msg":"trace[462475859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"446.607436ms","start":"2026-02-25T19:31:26.148726Z","end":"2026-02-25T19:31:26.595333Z","steps":["trace[462475859] 'agreement among raft nodes before linearized reading' (duration: 446.36702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:26.595395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.794548Z","time spent":"800.840406ms","remote":"127.0.0.1:40128","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-25T19:31:26.595402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.986866Z","time spent":"608.530294ms","remote":"127.0.0.1:40128","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-25T19:31:26.59543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:26.148711Z","time spent":"446.702307ms","remote":"127.0.0.1:39898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:26.595249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.985965Z","time spent":"609.269647ms","remote":"127.0.0.1:40128","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-25T19:31:26.59513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002807407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:31:26.595548Z","caller":"traceutil/trace.go:171","msg":"trace[1913382384] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:754; }","duration":"1.003258026s","start":"2026-02-25T19:31:25.59228Z","end":"2026-02-25T19:31:26.595538Z","steps":["trace[1913382384] 'agreement among raft nodes before linearized reading' (duration: 1.002761397s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:26.595253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.754842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T19:31:26.595584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.592271Z","time spent":"1.003300597s","remote":"127.0.0.1:40210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:31:26.595601Z","caller":"traceutil/trace.go:171","msg":"trace[408420021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"725.116329ms","start":"2026-02-25T19:31:25.870464Z","end":"2026-02-25T19:31:26.59558Z","steps":["trace[408420021] 'agreement among raft nodes before linearized reading' (duration: 724.704441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:26.59566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.87045Z","time spent":"725.194151ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:31:26.754697Z","caller":"traceutil/trace.go:171","msg":"trace[1824409640] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"155.273645ms","start":"2026-02-25T19:31:26.599398Z","end":"2026-02-25T19:31:26.754672Z","steps":["trace[1824409640] 'process raft request' (duration: 155.00534ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:26.755005Z","caller":"traceutil/trace.go:171","msg":"trace[1566595887] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"155.4802ms","start":"2026-02-25T19:31:26.599514Z","end":"2026-02-25T19:31:26.754994Z","steps":["trace[1566595887] 'process raft request' (duration: 155.115832ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:26.755211Z","caller":"traceutil/trace.go:171","msg":"trace[1816648332] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:783; }","duration":"151.957793ms","start":"2026-02-25T19:31:26.603231Z","end":"2026-02-25T19:31:26.755189Z","steps":["trace[1816648332] 'read index received' (duration: 71.287534ms)","trace[1816648332] 'applied index is now lower than readState.Index' (duration: 80.668959ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:26.755297Z","caller":"traceutil/trace.go:171","msg":"trace[872986648] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"154.039272ms","start":"2026-02-25T19:31:26.60124Z","end":"2026-02-25T19:31:26.755279Z","steps":["trace[872986648] 'process raft request' (duration: 153.808468ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:26.755269Z","caller":"traceutil/trace.go:171","msg":"trace[1744239221] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"155.210743ms","start":"2026-02-25T19:31:26.600022Z","end":"2026-02-25T19:31:26.755233Z","steps":["trace[1744239221] 'process raft request' (duration: 154.843385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:26.755451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.194757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:26.755496Z","caller":"traceutil/trace.go:171","msg":"trace[2098328518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"152.303249ms","start":"2026-02-25T19:31:26.603182Z","end":"2026-02-25T19:31:26.755485Z","steps":["trace[2098328518] 'agreement among raft nodes before linearized reading' (duration: 152.096656ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:36.93776Z","caller":"traceutil/trace.go:171","msg":"trace[1530819690] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"142.808183ms","start":"2026-02-25T19:31:36.794928Z","end":"2026-02-25T19:31:36.937736Z","steps":["trace[1530819690] 'read index received' (duration: 142.67812ms)","trace[1530819690] 'applied index is now lower than readState.Index' (duration: 128.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:36.937845Z","caller":"traceutil/trace.go:171","msg":"trace[95332207] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"222.754778ms","start":"2026-02-25T19:31:36.715056Z","end":"2026-02-25T19:31:36.937811Z","steps":["trace[95332207] 'process raft request' (duration: 222.541684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:36.938017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.085845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:31:36.938015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.293032ms","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-25T19:31:36.938044Z","caller":"traceutil/trace.go:171","msg":"trace[1595441927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:835; }","duration":"131.173666ms","start":"2026-02-25T19:31:36.80686Z","end":"2026-02-25T19:31:36.938034Z","steps":["trace[1595441927] 'agreement among raft nodes before linearized reading' (duration: 131.000783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:36.93804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.92302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:31:36.93804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.094947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:31:36.938079Z","caller":"traceutil/trace.go:171","msg":"trace[1466083301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"121.392074ms","start":"2026-02-25T19:31:36.81667Z","end":"2026-02-25T19:31:36.938062Z","steps":["trace[1466083301] 'agreement among raft nodes before linearized reading' (duration: 121.215521ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:36.938118Z","caller":"traceutil/trace.go:171","msg":"trace[926756214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"126.043801ms","start":"2026-02-25T19:31:36.812054Z","end":"2026-02-25T19:31:36.938097Z","steps":["trace[926756214] 'agreement among raft nodes before linearized reading' (duration: 125.870508ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:36.938124Z","caller":"traceutil/trace.go:171","msg":"trace[2113839900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:835; }","duration":"143.23945ms","start":"2026-02-25T19:31:36.794868Z","end":"2026-02-25T19:31:36.938108Z","steps":["trace[2113839900] 'agreement among raft nodes before linearized reading' (duration: 142.988076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:37.323061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.808065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:37.323167Z","caller":"traceutil/trace.go:171","msg":"trace[924458113] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"381.493507ms","start":"2026-02-25T19:31:36.941656Z","end":"2026-02-25T19:31:37.323149Z","steps":["trace[924458113] 'process raft request' (duration: 246.524079ms)","trace[924458113] 'compare' (duration: 134.6209ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:37.323329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:36.941637Z","time spent":"381.61875ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:37.33529Z","caller":"traceutil/trace.go:171","msg":"trace[90335430] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"391.574554ms","start":"2026-02-25T19:31:36.943706Z","end":"2026-02-25T19:31:37.335281Z","steps":["trace[90335430] 'process raft request' (duration: 391.529233ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:37.335313Z","caller":"traceutil/trace.go:171","msg":"trace[1271887843] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"391.721746ms","start":"2026-02-25T19:31:36.943568Z","end":"2026-02-25T19:31:37.33529Z","steps":["trace[1271887843] 'process raft request' (duration: 391.611264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:37.335348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:36.943696Z","time spent":"391.623095ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:37.335537Z","caller":"traceutil/trace.go:171","msg":"trace[1153709557] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"391.020474ms","start":"2026-02-25T19:31:36.944507Z","end":"2026-02-25T19:31:37.335528Z","steps":["trace[1153709557] 'process raft request' (duration: 390.752219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:37.3356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:36.944497Z","time spent":"391.059174ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:37.335461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:36.943546Z","time spent":"391.820209ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:39.750772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700764722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:40.250423Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302667998s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:31:40.250762Z","caller":"traceutil/trace.go:171","msg":"trace[1614219369] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"1.000992553s","start":"2026-02-25T19:31:39.249746Z","end":"2026-02-25T19:31:40.250739Z","steps":["trace[1614219369] 'read index received' (duration: 1.000778309s)","trace[1614219369] 'applied index is now lower than readState.Index' (duration: 213.244µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:40.250842Z","caller":"traceutil/trace.go:171","msg":"trace[2124750351] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"1.303120506s","start":"2026-02-25T19:31:38.947697Z","end":"2026-02-25T19:31:40.250817Z","steps":["trace[2124750351] 'process raft request' (duration: 1.302898372s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:40.250894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001133237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:40.250929Z","caller":"traceutil/trace.go:171","msg":"trace[1712791602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"1.00121969s","start":"2026-02-25T19:31:39.2497Z","end":"2026-02-25T19:31:40.25092Z","steps":["trace[1712791602] 'agreement among raft nodes before linearized reading' (duration: 1.001144508s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:40.250953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:39.249681Z","time spent":"1.00126636s","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:40.250953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:38.947687Z","time spent":"1.303192748s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:40.250976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.642408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:31:40.251002Z","caller":"traceutil/trace.go:171","msg":"trace[641982797] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:840; }","duration":"368.70738ms","start":"2026-02-25T19:31:39.882288Z","end":"2026-02-25T19:31:40.250996Z","steps":["trace[641982797] 'agreement among raft nodes before linearized reading' (duration: 368.633899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:40.251032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:39.882255Z","time spent":"368.77189ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.37\" "} {"level":"warn","ts":"2026-02-25T19:31:40.251058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.936833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T19:31:40.251057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.880271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:31:40.250975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.315461ms","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-25T19:31:40.251123Z","caller":"traceutil/trace.go:171","msg":"trace[1154271170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"909.974253ms","start":"2026-02-25T19:31:39.341134Z","end":"2026-02-25T19:31:40.251109Z","steps":["trace[1154271170] 'agreement among raft nodes before linearized reading' (duration: 909.774959ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:40.251139Z","caller":"traceutil/trace.go:171","msg":"trace[379047910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"923.521095ms","start":"2026-02-25T19:31:39.327611Z","end":"2026-02-25T19:31:40.251132Z","steps":["trace[379047910] 'agreement among raft nodes before linearized reading' (duration: 923.268281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:40.25115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.729505ms","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":"warn","ts":"2026-02-25T19:31:40.251157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:39.341129Z","time spent":"910.021754ms","remote":"127.0.0.1:40128","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-25T19:31:40.251163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:39.327589Z","time spent":"923.567086ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:31:40.251086Z","caller":"traceutil/trace.go:171","msg":"trace[1276200636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"909.983323ms","start":"2026-02-25T19:31:39.341093Z","end":"2026-02-25T19:31:40.251076Z","steps":["trace[1276200636] 'agreement among raft nodes before linearized reading' (duration: 909.900652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:40.251284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:39.341082Z","time spent":"910.183237ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:31:40.251173Z","caller":"traceutil/trace.go:171","msg":"trace[1306185730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"912.751215ms","start":"2026-02-25T19:31:39.338415Z","end":"2026-02-25T19:31:40.251166Z","steps":["trace[1306185730] 'agreement among raft nodes before linearized reading' (duration: 912.690804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:40.252459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:39.33837Z","time spent":"914.06793ms","remote":"127.0.0.1:40128","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":"info","ts":"2026-02-25T19:31:56.717194Z","caller":"traceutil/trace.go:171","msg":"trace[627129630] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:942; }","duration":"247.64025ms","start":"2026-02-25T19:31:56.469533Z","end":"2026-02-25T19:31:56.717174Z","steps":["trace[627129630] 'read index received' (duration: 47.450451ms)","trace[627129630] 'applied index is now lower than readState.Index' (duration: 200.188799ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:56.717424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.64456ms","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-25T19:31:56.717385Z","caller":"traceutil/trace.go:171","msg":"trace[1274512609] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"259.080882ms","start":"2026-02-25T19:31:56.458226Z","end":"2026-02-25T19:31:56.717307Z","steps":["trace[1274512609] 'process raft request' (duration: 182.390888ms)","trace[1274512609] 'compare' (duration: 76.44819ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:56.717465Z","caller":"traceutil/trace.go:171","msg":"trace[1484450850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"247.743762ms","start":"2026-02-25T19:31:56.469711Z","end":"2026-02-25T19:31:56.717454Z","steps":["trace[1484450850] 'agreement among raft nodes before linearized reading' (duration: 247.61514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:56.717455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.901245ms","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-25T19:31:56.717548Z","caller":"traceutil/trace.go:171","msg":"trace[539725864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"248.040287ms","start":"2026-02-25T19:31:56.469481Z","end":"2026-02-25T19:31:56.717522Z","steps":["trace[539725864] 'agreement among raft nodes before linearized reading' (duration: 247.792523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:56.717576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.669211ms","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-25T19:31:56.717682Z","caller":"traceutil/trace.go:171","msg":"trace[1913857289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"247.813104ms","start":"2026-02-25T19:31:56.469841Z","end":"2026-02-25T19:31:56.717654Z","steps":["trace[1913857289] 'agreement among raft nodes before linearized reading' (duration: 247.560379ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:58.876948Z","caller":"traceutil/trace.go:171","msg":"trace[1352575663] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"155.074351ms","start":"2026-02-25T19:31:58.721851Z","end":"2026-02-25T19:31:58.876925Z","steps":["trace[1352575663] 'read index received' (duration: 154.895337ms)","trace[1352575663] 'applied index is now lower than readState.Index' (duration: 178.064µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:58.877268Z","caller":"traceutil/trace.go:171","msg":"trace[731128544] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"352.487428ms","start":"2026-02-25T19:31:58.524769Z","end":"2026-02-25T19:31:58.877257Z","steps":["trace[731128544] 'process raft request' (duration: 352.03593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:58.877381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.524744Z","time spent":"352.557179ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:58.877623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.772004ms","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-25T19:31:58.87766Z","caller":"traceutil/trace.go:171","msg":"trace[1618057425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"155.845145ms","start":"2026-02-25T19:31:58.721805Z","end":"2026-02-25T19:31:58.87765Z","steps":["trace[1618057425] 'agreement among raft nodes before linearized reading' (duration: 155.729793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:58.877861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.63727ms","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-25T19:31:58.877892Z","caller":"traceutil/trace.go:171","msg":"trace[1473092463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"149.6968ms","start":"2026-02-25T19:31:58.728188Z","end":"2026-02-25T19:31:58.877884Z","steps":["trace[1473092463] 'agreement among raft nodes before linearized reading' (duration: 149.614579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:58.878015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.573934ms","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-25T19:31:58.878043Z","caller":"traceutil/trace.go:171","msg":"trace[1714730207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"145.621285ms","start":"2026-02-25T19:31:58.732415Z","end":"2026-02-25T19:31:58.878036Z","steps":["trace[1714730207] 'agreement among raft nodes before linearized reading' (duration: 145.550754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:58.878168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.288176ms","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-25T19:31:58.878196Z","caller":"traceutil/trace.go:171","msg":"trace[1466870521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"147.422438ms","start":"2026-02-25T19:31:58.730766Z","end":"2026-02-25T19:31:58.878188Z","steps":["trace[1466870521] 'agreement among raft nodes before linearized reading' (duration: 147.329666ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:59.54636Z","caller":"traceutil/trace.go:171","msg":"trace[1099079277] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"663.267591ms","start":"2026-02-25T19:31:58.883063Z","end":"2026-02-25T19:31:59.54633Z","steps":["trace[1099079277] 'process raft request' (duration: 621.037526ms)","trace[1099079277] 'compare' (duration: 42.001931ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:59.546528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.883041Z","time spent":"663.402034ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:59.87795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.155301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:59.878202Z","caller":"traceutil/trace.go:171","msg":"trace[1658467545] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"994.81699ms","start":"2026-02-25T19:31:58.883358Z","end":"2026-02-25T19:31:59.878175Z","steps":["trace[1658467545] 'process raft request' (duration: 860.273831ms)","trace[1658467545] 'compare' (duration: 133.961928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:59.878291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.883347Z","time spent":"994.908841ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:59.878459Z","caller":"traceutil/trace.go:171","msg":"trace[641273070] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:948; }","duration":"628.806761ms","start":"2026-02-25T19:31:59.249629Z","end":"2026-02-25T19:31:59.878436Z","steps":["trace[641273070] 'read index received' (duration: 254.481918ms)","trace[641273070] 'applied index is now lower than readState.Index' (duration: 374.324103ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:59.878507Z","caller":"traceutil/trace.go:171","msg":"trace[493265832] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"994.85179ms","start":"2026-02-25T19:31:58.883626Z","end":"2026-02-25T19:31:59.878478Z","steps":["trace[493265832] 'process raft request' (duration: 994.712948ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:59.878576Z","caller":"traceutil/trace.go:171","msg":"trace[356931454] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"994.977363ms","start":"2026-02-25T19:31:58.883587Z","end":"2026-02-25T19:31:59.878564Z","steps":["trace[356931454] 'process raft request' (duration: 994.517525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:59.878597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.950103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:59.878628Z","caller":"traceutil/trace.go:171","msg":"trace[324256144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"629.048395ms","start":"2026-02-25T19:31:59.249572Z","end":"2026-02-25T19:31:59.87862Z","steps":["trace[324256144] 'agreement among raft nodes before linearized reading' (duration: 628.976514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:59.87864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.883578Z","time spent":"995.028504ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:59.878652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:59.249542Z","time spent":"629.104776ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:59.878701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.883613Z","time spent":"994.977463ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:00.152353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.113476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:00.15254Z","caller":"traceutil/trace.go:171","msg":"trace[222555165] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"210.945429ms","start":"2026-02-25T19:31:59.941563Z","end":"2026-02-25T19:32:00.152509Z","steps":["trace[222555165] 'process raft request' (duration: 45.568337ms)","trace[222555165] 'compare' (duration: 164.981194ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:00.15256Z","caller":"traceutil/trace.go:171","msg":"trace[2095755261] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"183.750663ms","start":"2026-02-25T19:31:59.968789Z","end":"2026-02-25T19:32:00.15254Z","steps":["trace[2095755261] 'process raft request' (duration: 183.693912ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:01.050769Z","caller":"traceutil/trace.go:171","msg":"trace[1980813374] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"161.010491ms","start":"2026-02-25T19:32:00.889736Z","end":"2026-02-25T19:32:01.050747Z","steps":["trace[1980813374] 'process raft request' (duration: 160.852738ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:01.658035Z","caller":"traceutil/trace.go:171","msg":"trace[1841038267] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"104.179876ms","start":"2026-02-25T19:32:01.553831Z","end":"2026-02-25T19:32:01.658011Z","steps":["trace[1841038267] 'process raft request' (duration: 104.008123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:02.750065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700764911,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:02.864283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.559833ms","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-25T19:32:02.864358Z","caller":"traceutil/trace.go:171","msg":"trace[1117896669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:919; }","duration":"981.686525ms","start":"2026-02-25T19:32:01.882653Z","end":"2026-02-25T19:32:02.864339Z","steps":["trace[1117896669] 'range keys from in-memory index tree' (duration: 981.38209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:02.864398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:01.882624Z","time spent":"981.764788ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:32:02.864407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.858029ms","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-25T19:32:02.864486Z","caller":"traceutil/trace.go:171","msg":"trace[1487034213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"981.979881ms","start":"2026-02-25T19:32:01.882487Z","end":"2026-02-25T19:32:02.864467Z","steps":["trace[1487034213] 'range keys from in-memory index tree' (duration: 981.678555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:02.864498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.90131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T19:32:02.864546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:01.882465Z","time spent":"982.060502ms","remote":"127.0.0.1:40128","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-25T19:32:02.864585Z","caller":"traceutil/trace.go:171","msg":"trace[1681612937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"982.031982ms","start":"2026-02-25T19:32:01.882521Z","end":"2026-02-25T19:32:02.864553Z","steps":["trace[1681612937] 'range keys from in-memory index tree' (duration: 981.734056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:02.864629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:01.8825Z","time spent":"982.120104ms","remote":"127.0.0.1:40128","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-25T19:32:03.251096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700764911,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:03.59809Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.37648382s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:32:03.599456Z","caller":"traceutil/trace.go:171","msg":"trace[755637790] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"1.377912795s","start":"2026-02-25T19:32:02.221485Z","end":"2026-02-25T19:32:03.599398Z","steps":["trace[755637790] 'process raft request' (duration: 1.377016699s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.599639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:02.221456Z","time spent":"1.37809289s","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:32:03.628233Z","caller":"traceutil/trace.go:171","msg":"trace[1017898877] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"760.360384ms","start":"2026-02-25T19:32:02.867853Z","end":"2026-02-25T19:32:03.628213Z","steps":["trace[1017898877] 'process raft request' (duration: 760.222842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.628357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:02.867832Z","time spent":"760.461296ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:03.628492Z","caller":"traceutil/trace.go:171","msg":"trace[2011520174] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"760.254332ms","start":"2026-02-25T19:32:02.868231Z","end":"2026-02-25T19:32:03.628486Z","steps":["trace[2011520174] 'process raft request' (duration: 759.952087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.628527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:02.86822Z","time spent":"760.286853ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:03.628777Z","caller":"traceutil/trace.go:171","msg":"trace[904175246] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"760.225272ms","start":"2026-02-25T19:32:02.86853Z","end":"2026-02-25T19:32:03.628756Z","steps":["trace[904175246] 'process raft request' (duration: 759.930087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.628903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:02.86852Z","time spent":"760.313803ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:03.628924Z","caller":"traceutil/trace.go:171","msg":"trace[801659579] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:957; }","duration":"1.379487856s","start":"2026-02-25T19:32:02.249427Z","end":"2026-02-25T19:32:03.628914Z","steps":["trace[801659579] 'read index received' (duration: 1.348955978s)","trace[801659579] 'applied index is now lower than readState.Index' (duration: 30.531118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:03.62903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379603438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:03.629066Z","caller":"traceutil/trace.go:171","msg":"trace[451017937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"1.379661669s","start":"2026-02-25T19:32:02.249392Z","end":"2026-02-25T19:32:03.629054Z","steps":["trace[451017937] 'agreement among raft nodes before linearized reading' (duration: 1.379611708s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.629097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:02.249376Z","time spent":"1.37971378s","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:32:03.629131Z","caller":"traceutil/trace.go:171","msg":"trace[1431469747] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"482.90847ms","start":"2026-02-25T19:32:03.146215Z","end":"2026-02-25T19:32:03.629124Z","steps":["trace[1431469747] 'process raft request' (duration: 482.468692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.629176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.14612Z","time spent":"483.031733ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:03.914958Z","caller":"traceutil/trace.go:171","msg":"trace[7445772] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"285.986932ms","start":"2026-02-25T19:32:03.628943Z","end":"2026-02-25T19:32:03.91493Z","steps":["trace[7445772] 'read index received' (duration: 285.757788ms)","trace[7445772] 'applied index is now lower than readState.Index' (duration: 227.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:03.915161Z","caller":"traceutil/trace.go:171","msg":"trace[884819523] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"309.202614ms","start":"2026-02-25T19:32:03.605936Z","end":"2026-02-25T19:32:03.915138Z","steps":["trace[884819523] 'process raft request' (duration: 308.843328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.915292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608174023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:03.915357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.533765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T19:32:03.915402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.605915Z","time spent":"309.406287ms","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:32:03.91542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.627546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:03.915435Z","caller":"traceutil/trace.go:171","msg":"trace[1703208955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"860.655818ms","start":"2026-02-25T19:32:03.054763Z","end":"2026-02-25T19:32:03.915418Z","steps":["trace[1703208955] 'agreement among raft nodes before linearized reading' (duration: 860.406414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.915447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.943264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:32:03.915479Z","caller":"traceutil/trace.go:171","msg":"trace[1022415501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"285.714757ms","start":"2026-02-25T19:32:03.629748Z","end":"2026-02-25T19:32:03.915463Z","steps":["trace[1022415501] 'agreement among raft nodes before linearized reading' (duration: 285.605085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.915482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.054743Z","time spent":"860.730389ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:32:03.915366Z","caller":"traceutil/trace.go:171","msg":"trace[681103559] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:925; }","duration":"1.608292276s","start":"2026-02-25T19:32:02.307055Z","end":"2026-02-25T19:32:03.915347Z","steps":["trace[681103559] 'agreement among raft nodes before linearized reading' (duration: 1.608155323s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:03.915509Z","caller":"traceutil/trace.go:171","msg":"trace[939925671] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:925; }","duration":"102.038805ms","start":"2026-02-25T19:32:03.813443Z","end":"2026-02-25T19:32:03.915482Z","steps":["trace[939925671] 'agreement among raft nodes before linearized reading' (duration: 101.930063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:03.915565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:02.307037Z","time spent":"1.608511199s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T19:32:03.915699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.326734ms","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-25T19:32:03.915775Z","caller":"traceutil/trace.go:171","msg":"trace[1601091259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"254.431836ms","start":"2026-02-25T19:32:03.661306Z","end":"2026-02-25T19:32:03.915738Z","steps":["trace[1601091259] 'agreement among raft nodes before linearized reading' (duration: 254.221102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:04.355401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.941192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:04.35562Z","caller":"traceutil/trace.go:171","msg":"trace[1768617556] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:963; }","duration":"431.565837ms","start":"2026-02-25T19:32:03.924035Z","end":"2026-02-25T19:32:04.355601Z","steps":["trace[1768617556] 'read index received' (duration: 138.429231ms)","trace[1768617556] 'applied index is now lower than readState.Index' (duration: 293.135656ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:04.35565Z","caller":"traceutil/trace.go:171","msg":"trace[1742921159] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"434.240507ms","start":"2026-02-25T19:32:03.921389Z","end":"2026-02-25T19:32:04.355629Z","steps":["trace[1742921159] 'process raft request' (duration: 434.165866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:04.355612Z","caller":"traceutil/trace.go:171","msg":"trace[85036416] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"436.061371ms","start":"2026-02-25T19:32:03.919519Z","end":"2026-02-25T19:32:04.355581Z","steps":["trace[85036416] 'process raft request' (duration: 142.886744ms)","trace[85036416] 'compare' (duration: 292.8331ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:04.355711Z","caller":"traceutil/trace.go:171","msg":"trace[841426850] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"433.708016ms","start":"2026-02-25T19:32:03.921994Z","end":"2026-02-25T19:32:04.355702Z","steps":["trace[841426850] 'process raft request' (duration: 433.583034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:04.355768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.921988Z","time spent":"433.748487ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:32:04.355801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.919498Z","time spent":"436.221584ms","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:32:04.355812Z","caller":"traceutil/trace.go:171","msg":"trace[494177831] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"434.813458ms","start":"2026-02-25T19:32:03.920956Z","end":"2026-02-25T19:32:04.35577Z","steps":["trace[494177831] 'process raft request' (duration: 434.530361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:04.355865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.921376Z","time spent":"434.335328ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:04.355681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.644289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:04.355992Z","caller":"traceutil/trace.go:171","msg":"trace[1550885224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"431.967154ms","start":"2026-02-25T19:32:03.924003Z","end":"2026-02-25T19:32:04.35597Z","steps":["trace[1550885224] 'agreement among raft nodes before linearized reading' (duration: 431.647838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:04.356015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.920939Z","time spent":"434.95778ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:04.356053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:03.923991Z","time spent":"432.040386ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:05.541307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700764926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:06.041509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567869890700764926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:06.52893Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.1662601s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:32:06.529402Z","caller":"traceutil/trace.go:171","msg":"trace[903059751] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"2.16683046s","start":"2026-02-25T19:32:04.362547Z","end":"2026-02-25T19:32:06.529377Z","steps":["trace[903059751] 'process raft request' (duration: 2.166688477s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.529531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:04.362523Z","time spent":"2.166949782s","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:32:07.251602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000022762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:32:07.251692Z","caller":"traceutil/trace.go:171","msg":"trace[1961822358] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000160834s","start":"2026-02-25T19:32:05.251507Z","end":"2026-02-25T19:32:07.251668Z","steps":["trace[1961822358] 'agreement among raft nodes before linearized reading' (duration: 2.000056242s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.25174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.25149Z","time spent":"2.000241146s","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:32:07 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:32:08.175974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.646779049s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:32:09.164692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.537423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:09.164894Z","caller":"traceutil/trace.go:171","msg":"trace[345665635] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"4.799694867s","start":"2026-02-25T19:32:04.36517Z","end":"2026-02-25T19:32:09.164865Z","steps":["trace[345665635] 'process raft request' (duration: 3.810925681s)","trace[345665635] 'compare' (duration: 988.42064ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:09.164957Z","caller":"traceutil/trace.go:171","msg":"trace[1157965138] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"4.674468381s","start":"2026-02-25T19:32:04.49042Z","end":"2026-02-25T19:32:09.164889Z","steps":["trace[1157965138] 'process raft request' (duration: 4.674340428s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.16506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:04.365158Z","time spent":"4.79980758s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:09.16523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:04.490396Z","time spent":"4.674709395s","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5957,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:09.253403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001362217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:32:09.253545Z","caller":"traceutil/trace.go:171","msg":"trace[203469187] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001609402s","start":"2026-02-25T19:32:07.251914Z","end":"2026-02-25T19:32:09.253523Z","steps":["trace[203469187] 'agreement among raft nodes before linearized reading' (duration: 2.001428599s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.2536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:07.251896Z","time spent":"2.001692893s","remote":"127.0.0.1:39898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:32:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:32:09.796449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.619532ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T19:32:09.796984Z","caller":"traceutil/trace.go:171","msg":"trace[655135446] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:967; }","duration":"4.756250469s","start":"2026-02-25T19:32:05.040708Z","end":"2026-02-25T19:32:09.796959Z","steps":["trace[655135446] 'read index received' (duration: 1.48855338s)","trace[655135446] 'applied index is now lower than readState.Index' (duration: 3.267695659s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:09.797095Z","caller":"traceutil/trace.go:171","msg":"trace[2145099017] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"3.890139791s","start":"2026-02-25T19:32:05.906942Z","end":"2026-02-25T19:32:09.797081Z","steps":["trace[2145099017] 'process raft request' (duration: 3.889635172s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.797217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.906913Z","time spent":"3.890215022s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:10.253342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.212600707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.253371Z","caller":"traceutil/trace.go:171","msg":"trace[1361791136] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"456.297586ms","start":"2026-02-25T19:32:09.797043Z","end":"2026-02-25T19:32:10.253341Z","steps":["trace[1361791136] 'read index received' (duration: 366.308545ms)","trace[1361791136] 'applied index is now lower than readState.Index' (duration: 89.987511ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:10.253446Z","caller":"traceutil/trace.go:171","msg":"trace[55653141] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:933; }","duration":"5.212765599s","start":"2026-02-25T19:32:05.040653Z","end":"2026-02-25T19:32:10.253418Z","steps":["trace[55653141] 'agreement among raft nodes before linearized reading' (duration: 4.756893471s)","trace[55653141] 'count revisions from in-memory index tree' (duration: 455.711476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:10.253502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.040628Z","time spent":"5.212858882s","remote":"127.0.0.1:40016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:10.253537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.586348683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.253605Z","caller":"traceutil/trace.go:171","msg":"trace[1467994477] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:934; }","duration":"2.586471104s","start":"2026-02-25T19:32:07.667116Z","end":"2026-02-25T19:32:10.253587Z","steps":["trace[1467994477] 'agreement among raft nodes before linearized reading' (duration: 2.586284011s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.253637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.893089356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T19:32:10.253648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:07.667094Z","time spent":"2.586544536s","remote":"127.0.0.1:53440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:32:10.253677Z","caller":"traceutil/trace.go:171","msg":"trace[482740120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"3.893186819s","start":"2026-02-25T19:32:06.360479Z","end":"2026-02-25T19:32:10.253665Z","steps":["trace[482740120] 'agreement among raft nodes before linearized reading' (duration: 3.893001876s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.253714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.360447Z","time spent":"3.8932589s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:32:10.253845Z","caller":"traceutil/trace.go:171","msg":"trace[1212005754] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"995.465602ms","start":"2026-02-25T19:32:09.258362Z","end":"2026-02-25T19:32:10.253828Z","steps":["trace[1212005754] 'process raft request' (duration: 905.046683ms)","trace[1212005754] 'compare' (duration: 89.606234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:10.253913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:09.258335Z","time spent":"995.536203ms","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:32:10.253947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.621813753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:32:10.253971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.621863214s","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-25T19:32:10.253981Z","caller":"traceutil/trace.go:171","msg":"trace[138461816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"4.621886974s","start":"2026-02-25T19:32:05.632083Z","end":"2026-02-25T19:32:10.25397Z","steps":["trace[138461816] 'agreement among raft nodes before linearized reading' (duration: 4.621749592s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:10.254002Z","caller":"traceutil/trace.go:171","msg":"trace[1734266631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"4.621928745s","start":"2026-02-25T19:32:05.632063Z","end":"2026-02-25T19:32:10.253992Z","steps":["trace[1734266631] 'agreement among raft nodes before linearized reading' (duration: 4.621839983s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.254012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.63206Z","time spent":"4.621944995s","remote":"127.0.0.1:40128","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-25T19:32:10.25403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.632043Z","time spent":"4.621980266s","remote":"127.0.0.1:40128","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-25T19:32:10.254052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.454614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.25408Z","caller":"traceutil/trace.go:171","msg":"trace[2136513700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"993.529745ms","start":"2026-02-25T19:32:09.260541Z","end":"2026-02-25T19:32:10.254071Z","steps":["trace[2136513700] 'agreement among raft nodes before linearized reading' (duration: 993.484984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.25411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:09.260515Z","time spent":"993.590416ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:10.254165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.481242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:32:10.254193Z","caller":"traceutil/trace.go:171","msg":"trace[1162457495] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:934; }","duration":"370.540752ms","start":"2026-02-25T19:32:09.883642Z","end":"2026-02-25T19:32:10.254183Z","steps":["trace[1162457495] 'agreement among raft nodes before linearized reading' (duration: 370.460671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.254219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:09.883624Z","time spent":"370.589093ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.37\" "} {"level":"warn","ts":"2026-02-25T19:32:10.254232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.894547573s","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":"warn","ts":"2026-02-25T19:32:10.254247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1203308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.254263Z","caller":"traceutil/trace.go:171","msg":"trace[760012391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"3.894616694s","start":"2026-02-25T19:32:06.359637Z","end":"2026-02-25T19:32:10.254253Z","steps":["trace[760012391] 'agreement among raft nodes before linearized reading' (duration: 3.894532653s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:10.254277Z","caller":"traceutil/trace.go:171","msg":"trace[721013900] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:934; }","duration":"1.120396632s","start":"2026-02-25T19:32:09.133871Z","end":"2026-02-25T19:32:10.254267Z","steps":["trace[721013900] 'agreement among raft nodes before linearized reading' (duration: 1.120352531s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.254291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.359617Z","time spent":"3.894667346s","remote":"127.0.0.1:40128","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-25T19:32:10.254304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:09.133848Z","time spent":"1.120448774s","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:10.254445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.47688215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.254471Z","caller":"traceutil/trace.go:171","msg":"trace[782793434] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:934; }","duration":"2.47690969s","start":"2026-02-25T19:32:07.777554Z","end":"2026-02-25T19:32:10.254463Z","steps":["trace[782793434] 'agreement among raft nodes before linearized reading' (duration: 2.476869069s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.254496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:07.777516Z","time spent":"2.476973981s","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:10.25453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.068048217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.254566Z","caller":"traceutil/trace.go:171","msg":"trace[346835977] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:934; }","duration":"4.068126119s","start":"2026-02-25T19:32:06.18643Z","end":"2026-02-25T19:32:10.254556Z","steps":["trace[346835977] 'agreement among raft nodes before linearized reading' (duration: 4.068070718s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.2546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.186409Z","time spent":"4.068178549s","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:10.254922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.622483926s","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-25T19:32:10.25497Z","caller":"traceutil/trace.go:171","msg":"trace[565905371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"4.622555737s","start":"2026-02-25T19:32:05.632401Z","end":"2026-02-25T19:32:10.254956Z","steps":["trace[565905371] 'agreement among raft nodes before linearized reading' (duration: 4.622423714s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.255011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.632393Z","time spent":"4.622607868s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:32:10.878031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.22544ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-25T19:32:10.878133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.256623Z","time spent":"621.505796ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T19:32:10.906124Z","caller":"traceutil/trace.go:171","msg":"trace[1545517193] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:973; }","duration":"640.220852ms","start":"2026-02-25T19:32:10.265892Z","end":"2026-02-25T19:32:10.906112Z","steps":["trace[1545517193] 'read index received' (duration: 137.84171ms)","trace[1545517193] 'applied index is now lower than readState.Index' (duration: 502.378412ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:10.906172Z","caller":"traceutil/trace.go:171","msg":"trace[622973998] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"646.144252ms","start":"2026-02-25T19:32:10.259996Z","end":"2026-02-25T19:32:10.90614Z","steps":["trace[622973998] 'process raft request' (duration: 646.0277ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:10.906205Z","caller":"traceutil/trace.go:171","msg":"trace[1143975979] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"646.616242ms","start":"2026-02-25T19:32:10.259564Z","end":"2026-02-25T19:32:10.90618Z","steps":["trace[1143975979] 'process raft request' (duration: 646.344597ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:10.906231Z","caller":"traceutil/trace.go:171","msg":"trace[1862826498] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"645.785955ms","start":"2026-02-25T19:32:10.26044Z","end":"2026-02-25T19:32:10.906226Z","steps":["trace[1862826498] 'process raft request' (duration: 645.650713ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:10.906199Z","caller":"traceutil/trace.go:171","msg":"trace[980885] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"646.111431ms","start":"2026-02-25T19:32:10.260038Z","end":"2026-02-25T19:32:10.906149Z","steps":["trace[980885] 'process raft request' (duration: 646.03619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.906293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.260416Z","time spent":"645.838807ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:10.906319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.259545Z","time spent":"646.712253ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:10.906315Z","caller":"traceutil/trace.go:171","msg":"trace[2105166421] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"646.244223ms","start":"2026-02-25T19:32:10.260042Z","end":"2026-02-25T19:32:10.906286Z","steps":["trace[2105166421] 'process raft request' (duration: 646.014319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.906334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.25999Z","time spent":"646.256604ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:10.906371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.260027Z","time spent":"646.250974ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:10.906443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.260033Z","time spent":"646.340386ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:10.906454Z","caller":"traceutil/trace.go:171","msg":"trace[902011304] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"646.551899ms","start":"2026-02-25T19:32:10.259894Z","end":"2026-02-25T19:32:10.906446Z","steps":["trace[902011304] 'process raft request' (duration: 646.098521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.906488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.259875Z","time spent":"646.59711ms","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:32:10.9062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.303124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.906541Z","caller":"traceutil/trace.go:171","msg":"trace[1927906066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"640.65632ms","start":"2026-02-25T19:32:10.26587Z","end":"2026-02-25T19:32:10.906526Z","steps":["trace[1927906066] 'agreement among raft nodes before linearized reading' (duration: 640.287543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.906584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.265864Z","time spent":"640.709971ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:32:11.418194Z","caller":"traceutil/trace.go:171","msg":"trace[888843038] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"539.041823ms","start":"2026-02-25T19:32:10.879119Z","end":"2026-02-25T19:32:11.418161Z","steps":["trace[888843038] 'process raft request' (duration: 509.061596ms)","trace[888843038] 'compare' (duration: 29.584879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:11.418375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:10.879011Z","time spent":"539.270877ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:15.095732Z","caller":"traceutil/trace.go:171","msg":"trace[1845074145] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"171.335262ms","start":"2026-02-25T19:32:14.924373Z","end":"2026-02-25T19:32:15.095708Z","steps":["trace[1845074145] 'process raft request' (duration: 98.765865ms)","trace[1845074145] 'compare' (duration: 72.397294ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:15.095908Z","caller":"traceutil/trace.go:171","msg":"trace[1061546824] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"170.531647ms","start":"2026-02-25T19:32:14.925342Z","end":"2026-02-25T19:32:15.095874Z","steps":["trace[1061546824] 'process raft request' (duration: 170.327683ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:15.096012Z","caller":"traceutil/trace.go:171","msg":"trace[464320177] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"116.203058ms","start":"2026-02-25T19:32:14.979782Z","end":"2026-02-25T19:32:15.095985Z","steps":["trace[464320177] 'process raft request' (duration: 116.076795ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:15.096105Z","caller":"traceutil/trace.go:171","msg":"trace[1818127782] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"123.524775ms","start":"2026-02-25T19:32:14.972569Z","end":"2026-02-25T19:32:15.096094Z","steps":["trace[1818127782] 'read index received' (duration: 50.616461ms)","trace[1818127782] 'applied index is now lower than readState.Index' (duration: 72.907234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:15.096273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.876096ms","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-25T19:32:15.096312Z","caller":"traceutil/trace.go:171","msg":"trace[419630894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"169.948478ms","start":"2026-02-25T19:32:14.926354Z","end":"2026-02-25T19:32:15.096303Z","steps":["trace[419630894] 'agreement among raft nodes before linearized reading' (duration: 169.786765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:17.845241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.218602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:17.845341Z","caller":"traceutil/trace.go:171","msg":"trace[1062658269] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:980; }","duration":"324.366956ms","start":"2026-02-25T19:32:17.520951Z","end":"2026-02-25T19:32:17.845318Z","steps":["trace[1062658269] 'range keys from in-memory index tree' (duration: 324.101309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:17.845388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:17.52092Z","time spent":"324.455097ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-25T19:32:29.402763Z","caller":"traceutil/trace.go:171","msg":"trace[1051309917] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"184.341005ms","start":"2026-02-25T19:32:29.218394Z","end":"2026-02-25T19:32:29.402735Z","steps":["trace[1051309917] 'read index received' (duration: 184.198083ms)","trace[1051309917] 'applied index is now lower than readState.Index' (duration: 141.492µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:29.403277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.854984ms","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-25T19:32:29.403332Z","caller":"traceutil/trace.go:171","msg":"trace[1580023941] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1035; }","duration":"184.972186ms","start":"2026-02-25T19:32:29.218344Z","end":"2026-02-25T19:32:29.403316Z","steps":["trace[1580023941] 'agreement among raft nodes before linearized reading' (duration: 184.781092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.403356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.344674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T19:32:29.403353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.111559ms","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-25T19:32:29.403453Z","caller":"traceutil/trace.go:171","msg":"trace[963918783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"163.537609ms","start":"2026-02-25T19:32:29.239887Z","end":"2026-02-25T19:32:29.403425Z","steps":["trace[963918783] 'agreement among raft nodes before linearized reading' (duration: 163.207273ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:29.403455Z","caller":"traceutil/trace.go:171","msg":"trace[1580047141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"107.295233ms","start":"2026-02-25T19:32:29.296141Z","end":"2026-02-25T19:32:29.403437Z","steps":["trace[1580047141] 'agreement among raft nodes before linearized reading' (duration: 106.985027ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:29.403535Z","caller":"traceutil/trace.go:171","msg":"trace[205423164] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"199.418604ms","start":"2026-02-25T19:32:29.204103Z","end":"2026-02-25T19:32:29.403522Z","steps":["trace[205423164] 'process raft request' (duration: 198.477268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.403715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.663647ms","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-25T19:32:29.403747Z","caller":"traceutil/trace.go:171","msg":"trace[166162883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"123.738898ms","start":"2026-02-25T19:32:29.279998Z","end":"2026-02-25T19:32:29.403737Z","steps":["trace[166162883] 'agreement among raft nodes before linearized reading' (duration: 123.621796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.659572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.065868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:29.659852Z","caller":"traceutil/trace.go:171","msg":"trace[400594684] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"251.071843ms","start":"2026-02-25T19:32:29.408735Z","end":"2026-02-25T19:32:29.659807Z","steps":["trace[400594684] 'process raft request' (duration: 143.650488ms)","trace[400594684] 'compare' (duration: 106.817194ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:29.660247Z","caller":"traceutil/trace.go:171","msg":"trace[645937018] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"251.340309ms","start":"2026-02-25T19:32:29.408865Z","end":"2026-02-25T19:32:29.660205Z","steps":["trace[645937018] 'process raft request' (duration: 251.147355ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:29.660245Z","caller":"traceutil/trace.go:171","msg":"trace[1358567242] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"251.39459ms","start":"2026-02-25T19:32:29.408824Z","end":"2026-02-25T19:32:29.660219Z","steps":["trace[1358567242] 'process raft request' (duration: 250.87014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:30.102153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.277561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-02-25T19:32:30.102177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.307998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:32:30.102261Z","caller":"traceutil/trace.go:171","msg":"trace[520891830] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1039; }","duration":"157.432484ms","start":"2026-02-25T19:32:29.944804Z","end":"2026-02-25T19:32:30.102236Z","steps":["trace[520891830] 'range keys from in-memory index tree' (duration: 157.038907ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:30.102274Z","caller":"traceutil/trace.go:171","msg":"trace[1485930158] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:1039; }","duration":"216.41666ms","start":"2026-02-25T19:32:29.885834Z","end":"2026-02-25T19:32:30.102251Z","steps":["trace[1485930158] 'range keys from in-memory index tree' (duration: 216.099664ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:30.372916Z","caller":"traceutil/trace.go:171","msg":"trace[1770229193] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"265.475082ms","start":"2026-02-25T19:32:30.107424Z","end":"2026-02-25T19:32:30.372899Z","steps":["trace[1770229193] 'process raft request' (duration: 218.156713ms)","trace[1770229193] 'compare' (duration: 47.095614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:30.373005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.753352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:30.372869Z","caller":"traceutil/trace.go:171","msg":"trace[177612874] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1082; }","duration":"240.59806ms","start":"2026-02-25T19:32:30.132237Z","end":"2026-02-25T19:32:30.372835Z","steps":["trace[177612874] 'read index received' (duration: 21.00341ms)","trace[177612874] 'applied index is now lower than readState.Index' (duration: 219.5934ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:30.373046Z","caller":"traceutil/trace.go:171","msg":"trace[1033226499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"240.848694ms","start":"2026-02-25T19:32:30.132184Z","end":"2026-02-25T19:32:30.373033Z","steps":["trace[1033226499] 'agreement among raft nodes before linearized reading' (duration: 240.765623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:30.422996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.965526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:30.422988Z","caller":"traceutil/trace.go:171","msg":"trace[2065215] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"268.688761ms","start":"2026-02-25T19:32:30.154256Z","end":"2026-02-25T19:32:30.422945Z","steps":["trace[2065215] 'process raft request' (duration: 268.538178ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:30.42306Z","caller":"traceutil/trace.go:171","msg":"trace[1386528489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"171.088458ms","start":"2026-02-25T19:32:30.251957Z","end":"2026-02-25T19:32:30.423045Z","steps":["trace[1386528489] 'agreement among raft nodes before linearized reading' (duration: 170.958435ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:43.89147Z","caller":"traceutil/trace.go:171","msg":"trace[1955779720] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"168.179185ms","start":"2026-02-25T19:32:43.723257Z","end":"2026-02-25T19:32:43.891437Z","steps":["trace[1955779720] 'process raft request' (duration: 168.015993ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:43.961322Z","caller":"traceutil/trace.go:171","msg":"trace[448554292] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"237.007447ms","start":"2026-02-25T19:32:43.724269Z","end":"2026-02-25T19:32:43.961277Z","steps":["trace[448554292] 'process raft request' (duration: 216.649035ms)","trace[448554292] 'compare' (duration: 20.188679ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:44.795526Z","caller":"traceutil/trace.go:171","msg":"trace[1747882434] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"101.495111ms","start":"2026-02-25T19:32:44.693988Z","end":"2026-02-25T19:32:44.795483Z","steps":["trace[1747882434] 'process raft request' (duration: 100.639156ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:34:21.591308Z","caller":"traceutil/trace.go:171","msg":"trace[1040552342] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"162.385635ms","start":"2026-02-25T19:34:21.428889Z","end":"2026-02-25T19:34:21.591275Z","steps":["trace[1040552342] 'process raft request' (duration: 162.191951ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:06.356258Z","caller":"traceutil/trace.go:171","msg":"trace[1683946478] linearizableReadLoop","detail":"{readStateIndex:1752; appliedIndex:1751; }","duration":"104.610985ms","start":"2026-02-25T19:35:06.251617Z","end":"2026-02-25T19:35:06.356228Z","steps":["trace[1683946478] 'read index received' (duration: 38.663934ms)","trace[1683946478] 'applied index is now lower than readState.Index' (duration: 65.945621ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:35:06.356345Z","caller":"traceutil/trace.go:171","msg":"trace[956195507] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"107.905016ms","start":"2026-02-25T19:35:06.248402Z","end":"2026-02-25T19:35:06.356307Z","steps":["trace[956195507] 'process raft request' (duration: 41.907624ms)","trace[956195507] 'compare' (duration: 65.804698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:35:06.356394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.742188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:35:06.356441Z","caller":"traceutil/trace.go:171","msg":"trace[444571595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1677; }","duration":"104.83856ms","start":"2026-02-25T19:35:06.25159Z","end":"2026-02-25T19:35:06.356429Z","steps":["trace[444571595] 'agreement among raft nodes before linearized reading' (duration: 104.738568ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:07.135958Z","caller":"traceutil/trace.go:171","msg":"trace[1776390009] transaction","detail":"{read_only:false; response_revision:1681; number_of_response:1; }","duration":"160.196309ms","start":"2026-02-25T19:35:06.9757Z","end":"2026-02-25T19:35:07.135896Z","steps":["trace[1776390009] 'process raft request' (duration: 131.787291ms)","trace[1776390009] 'compare' (duration: 28.275456ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:35:11.361713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.527967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:35:11.36185Z","caller":"traceutil/trace.go:171","msg":"trace[254675224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1706; }","duration":"108.692721ms","start":"2026-02-25T19:35:11.253141Z","end":"2026-02-25T19:35:11.361833Z","steps":["trace[254675224] 'range keys from in-memory index tree' (duration: 108.485607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:11.826137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.022679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-25T19:35:11.826408Z","caller":"traceutil/trace.go:171","msg":"trace[1669280526] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1707; }","duration":"124.348586ms","start":"2026-02-25T19:35:11.702028Z","end":"2026-02-25T19:35:11.826377Z","steps":["trace[1669280526] 'range keys from in-memory index tree' (duration: 123.808105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:12.054159Z","caller":"traceutil/trace.go:171","msg":"trace[459356011] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"222.625837ms","start":"2026-02-25T19:35:11.831503Z","end":"2026-02-25T19:35:12.054129Z","steps":["trace[459356011] 'process raft request' (duration: 222.433273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:12.654593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.652771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:35:12.654651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.385168ms","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-25T19:35:12.654677Z","caller":"traceutil/trace.go:171","msg":"trace[973640632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1709; }","duration":"225.791504ms","start":"2026-02-25T19:35:12.428865Z","end":"2026-02-25T19:35:12.654657Z","steps":["trace[973640632] 'range keys from in-memory index tree' (duration: 225.404467ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:12.65473Z","caller":"traceutil/trace.go:171","msg":"trace[1923830336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1709; }","duration":"275.509029ms","start":"2026-02-25T19:35:12.3792Z","end":"2026-02-25T19:35:12.654709Z","steps":["trace[1923830336] 'range keys from in-memory index tree' (duration: 275.195733ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:12.863655Z","caller":"traceutil/trace.go:171","msg":"trace[638637296] linearizableReadLoop","detail":"{readStateIndex:1788; appliedIndex:1786; }","duration":"174.927616ms","start":"2026-02-25T19:35:12.688695Z","end":"2026-02-25T19:35:12.863623Z","steps":["trace[638637296] 'read index received' (duration: 56.013041ms)","trace[638637296] 'applied index is now lower than readState.Index' (duration: 118.913395ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:35:12.863695Z","caller":"traceutil/trace.go:171","msg":"trace[1302412303] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"203.785732ms","start":"2026-02-25T19:35:12.659876Z","end":"2026-02-25T19:35:12.863662Z","steps":["trace[1302412303] 'process raft request' (duration: 126.801259ms)","trace[1302412303] 'compare' (duration: 76.81583ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:35:12.863829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.1145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:35:12.863894Z","caller":"traceutil/trace.go:171","msg":"trace[1719916921] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1711; }","duration":"175.223663ms","start":"2026-02-25T19:35:12.688654Z","end":"2026-02-25T19:35:12.863878Z","steps":["trace[1719916921] 'agreement among raft nodes before linearized reading' (duration: 175.083881ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:13.258429Z","caller":"traceutil/trace.go:171","msg":"trace[1437562464] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"139.873958ms","start":"2026-02-25T19:35:13.11853Z","end":"2026-02-25T19:35:13.258404Z","steps":["trace[1437562464] 'process raft request' (duration: 139.700215ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:32.895235Z","caller":"traceutil/trace.go:171","msg":"trace[1126878044] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"487.47968ms","start":"2026-02-25T19:35:32.407723Z","end":"2026-02-25T19:35:32.895203Z","steps":["trace[1126878044] 'process raft request' (duration: 487.284757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:32.895407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:35:32.4077Z","time spent":"487.616223ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:35:47.64052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.771881ms","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":"info","ts":"2026-02-25T19:35:47.640668Z","caller":"traceutil/trace.go:171","msg":"trace[2029301242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1883; }","duration":"165.977855ms","start":"2026-02-25T19:35:47.474674Z","end":"2026-02-25T19:35:47.640652Z","steps":["trace[2029301242] 'range keys from in-memory index tree' (duration: 165.587408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:47.640494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.760544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-02-25T19:35:47.640893Z","caller":"traceutil/trace.go:171","msg":"trace[603450739] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1883; }","duration":"130.228982ms","start":"2026-02-25T19:35:47.510628Z","end":"2026-02-25T19:35:47.640857Z","steps":["trace[603450739] 'range keys from in-memory index tree' (duration: 129.51568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:47.640518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.033874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-dns\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-02-25T19:35:47.641067Z","caller":"traceutil/trace.go:171","msg":"trace[1974600462] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-dns; range_end:; response_count:1; response_revision:1883; }","duration":"111.625324ms","start":"2026-02-25T19:35:47.529392Z","end":"2026-02-25T19:35:47.641018Z","steps":["trace[1974600462] 'range keys from in-memory index tree' (duration: 110.892522ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:53.239677Z","caller":"traceutil/trace.go:171","msg":"trace[2034685123] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"230.645987ms","start":"2026-02-25T19:35:53.009Z","end":"2026-02-25T19:35:53.239646Z","steps":["trace[2034685123] 'process raft request' (duration: 230.355122ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:53.239846Z","caller":"traceutil/trace.go:171","msg":"trace[1722988252] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1990; }","duration":"229.992745ms","start":"2026-02-25T19:35:53.009577Z","end":"2026-02-25T19:35:53.239569Z","steps":["trace[1722988252] 'read index received' (duration: 229.692719ms)","trace[1722988252] 'applied index is now lower than readState.Index' (duration: 298.766µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:35:53.240204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.061325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/\" range_end:\"/registry/services/specs/kube-system0\" limit:500 ","response":"range_response_count:3 size:3579"} {"level":"warn","ts":"2026-02-25T19:35:53.240204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.564632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"warn","ts":"2026-02-25T19:35:53.240257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.994712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T19:35:53.240257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.16383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-02-25T19:35:53.240283Z","caller":"traceutil/trace.go:171","msg":"trace[1289198145] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1905; }","duration":"172.657144ms","start":"2026-02-25T19:35:53.067601Z","end":"2026-02-25T19:35:53.240258Z","steps":["trace[1289198145] 'agreement among raft nodes before linearized reading' (duration: 172.542472ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:53.240312Z","caller":"traceutil/trace.go:171","msg":"trace[822609468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1905; }","duration":"195.091534ms","start":"2026-02-25T19:35:53.045202Z","end":"2026-02-25T19:35:53.240294Z","steps":["trace[822609468] 'agreement among raft nodes before linearized reading' (duration: 194.958942ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:53.24035Z","caller":"traceutil/trace.go:171","msg":"trace[665619062] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1905; }","duration":"217.405794ms","start":"2026-02-25T19:35:53.02291Z","end":"2026-02-25T19:35:53.240315Z","steps":["trace[665619062] 'agreement among raft nodes before linearized reading' (duration: 217.077648ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:53.240262Z","caller":"traceutil/trace.go:171","msg":"trace[1044364748] range","detail":"{range_begin:/registry/services/specs/kube-system/; range_end:/registry/services/specs/kube-system0; response_count:3; response_revision:1905; }","duration":"129.166836ms","start":"2026-02-25T19:35:53.111078Z","end":"2026-02-25T19:35:53.240245Z","steps":["trace[1044364748] 'agreement among raft nodes before linearized reading' (duration: 129.012294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:53.240089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.464714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/\" range_end:\"/registry/serviceaccounts/kube-system0\" limit:500 ","response":"range_response_count:39 size:9533"} {"level":"info","ts":"2026-02-25T19:35:53.240715Z","caller":"traceutil/trace.go:171","msg":"trace[2013014389] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/; range_end:/registry/serviceaccounts/kube-system0; response_count:39; response_revision:1905; }","duration":"231.137085ms","start":"2026-02-25T19:35:53.009565Z","end":"2026-02-25T19:35:53.240702Z","steps":["trace[2013014389] 'agreement among raft nodes before linearized reading' (duration: 230.160978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:53.518304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.100979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:35:53.518441Z","caller":"traceutil/trace.go:171","msg":"trace[2025502287] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"270.335913ms","start":"2026-02-25T19:35:53.248072Z","end":"2026-02-25T19:35:53.518408Z","steps":["trace[2025502287] 'read index received' (duration: 124.935209ms)","trace[2025502287] 'applied index is now lower than readState.Index' (duration: 145.399474ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:35:53.51846Z","caller":"traceutil/trace.go:171","msg":"trace[851622082] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"273.292218ms","start":"2026-02-25T19:35:53.245132Z","end":"2026-02-25T19:35:53.518424Z","steps":["trace[851622082] 'process raft request' (duration: 127.945244ms)","trace[851622082] 'compare' (duration: 144.965297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:35:53.518719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.113212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:35:53.518771Z","caller":"traceutil/trace.go:171","msg":"trace[658613807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1906; }","duration":"268.172054ms","start":"2026-02-25T19:35:53.250585Z","end":"2026-02-25T19:35:53.518757Z","steps":["trace[658613807] 'agreement among raft nodes before linearized reading' (duration: 268.112693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:53.518826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.938182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-system/\" range_end:\"/registry/statefulsets/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:35:53.518868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.857943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-25T19:35:53.51891Z","caller":"traceutil/trace.go:171","msg":"trace[2032464595] range","detail":"{range_begin:/registry/statefulsets/kube-system/; range_end:/registry/statefulsets/kube-system0; response_count:0; response_revision:1906; }","duration":"183.056375ms","start":"2026-02-25T19:35:53.335832Z","end":"2026-02-25T19:35:53.518888Z","steps":["trace[2032464595] 'agreement among raft nodes before linearized reading' (duration: 182.930783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:53.518996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.995494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-02-25T19:35:53.518667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.589238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-02-25T19:35:53.519032Z","caller":"traceutil/trace.go:171","msg":"trace[1108195996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1906; }","duration":"105.076425ms","start":"2026-02-25T19:35:53.413948Z","end":"2026-02-25T19:35:53.519025Z","steps":["trace[1108195996] 'agreement among raft nodes before linearized reading' (duration: 104.991074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:53.519053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.159133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-02-25T19:35:53.518917Z","caller":"traceutil/trace.go:171","msg":"trace[765189292] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1906; }","duration":"180.918625ms","start":"2026-02-25T19:35:53.337985Z","end":"2026-02-25T19:35:53.518903Z","steps":["trace[765189292] 'agreement among raft nodes before linearized reading' (duration: 180.744992ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:53.519094Z","caller":"traceutil/trace.go:171","msg":"trace[1091972695] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1906; }","duration":"203.246523ms","start":"2026-02-25T19:35:53.315837Z","end":"2026-02-25T19:35:53.519084Z","steps":["trace[1091972695] 'agreement among raft nodes before linearized reading' (duration: 203.174652ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:35:53.5191Z","caller":"traceutil/trace.go:171","msg":"trace[150522532] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1906; }","duration":"271.043256ms","start":"2026-02-25T19:35:53.248041Z","end":"2026-02-25T19:35:53.519085Z","steps":["trace[150522532] 'agreement among raft nodes before linearized reading' (duration: 270.491787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:35:58.742796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.780167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-02-25T19:35:58.742913Z","caller":"traceutil/trace.go:171","msg":"trace[1366144317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1927; }","duration":"106.928549ms","start":"2026-02-25T19:35:58.635952Z","end":"2026-02-25T19:35:58.74288Z","steps":["trace[1366144317] 'range keys from in-memory index tree' (duration: 106.698055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:01.219064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.025229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:118 size:91248"} {"level":"info","ts":"2026-02-25T19:36:01.219156Z","caller":"traceutil/trace.go:171","msg":"trace[1547447417] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:118; response_revision:1938; }","duration":"102.154572ms","start":"2026-02-25T19:36:01.116982Z","end":"2026-02-25T19:36:01.219137Z","steps":["trace[1547447417] 'range keys from in-memory index tree' (duration: 101.462448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:01.219151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.321416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:118 size:91248"} {"level":"info","ts":"2026-02-25T19:36:01.219233Z","caller":"traceutil/trace.go:171","msg":"trace[654487555] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:118; response_revision:1938; }","duration":"125.457479ms","start":"2026-02-25T19:36:01.093755Z","end":"2026-02-25T19:36:01.219213Z","steps":["trace[654487555] 'range keys from in-memory index tree' (duration: 124.670774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:01.770236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.249142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:36:01.77042Z","caller":"traceutil/trace.go:171","msg":"trace[1402874227] linearizableReadLoop","detail":"{readStateIndex:2029; appliedIndex:2028; }","duration":"155.373987ms","start":"2026-02-25T19:36:01.61502Z","end":"2026-02-25T19:36:01.770394Z","steps":["trace[1402874227] 'read index received' (duration: 16.716566ms)","trace[1402874227] 'applied index is now lower than readState.Index' (duration: 138.655161ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:36:01.770463Z","caller":"traceutil/trace.go:171","msg":"trace[1459664104] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"181.326863ms","start":"2026-02-25T19:36:01.589104Z","end":"2026-02-25T19:36:01.770431Z","steps":["trace[1459664104] 'process raft request' (duration: 42.718113ms)","trace[1459664104] 'compare' (duration: 138.025409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:36:01.770602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.586931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T19:36:01.770648Z","caller":"traceutil/trace.go:171","msg":"trace[1546322149] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:1941; }","duration":"155.659362ms","start":"2026-02-25T19:36:01.614965Z","end":"2026-02-25T19:36:01.770624Z","steps":["trace[1546322149] 'agreement among raft nodes before linearized reading' (duration: 155.578641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:01.770706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.312441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T19:36:01.770862Z","caller":"traceutil/trace.go:171","msg":"trace[1005287662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:1; response_revision:1941; }","duration":"146.421133ms","start":"2026-02-25T19:36:01.624354Z","end":"2026-02-25T19:36:01.770776Z","steps":["trace[1005287662] 'agreement among raft nodes before linearized reading' (duration: 146.23899ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:36:01.973826Z","caller":"traceutil/trace.go:171","msg":"trace[1530880435] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"119.991679ms","start":"2026-02-25T19:36:01.853805Z","end":"2026-02-25T19:36:01.973797Z","steps":["trace[1530880435] 'process raft request' (duration: 34.750337ms)","trace[1530880435] 'compare' (duration: 85.098929ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:36:01.973889Z","caller":"traceutil/trace.go:171","msg":"trace[1222274495] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"119.942378ms","start":"2026-02-25T19:36:01.853847Z","end":"2026-02-25T19:36:01.973789Z","steps":["trace[1222274495] 'read index received' (duration: 34.596874ms)","trace[1222274495] 'applied index is now lower than readState.Index' (duration: 85.344284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:36:01.974114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.234713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T19:36:01.974224Z","caller":"traceutil/trace.go:171","msg":"trace[1369563626] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:1943; }","duration":"120.373666ms","start":"2026-02-25T19:36:01.853828Z","end":"2026-02-25T19:36:01.974202Z","steps":["trace[1369563626] 'agreement among raft nodes before linearized reading' (duration: 120.191153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:01.974276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.152274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363690"} {"level":"info","ts":"2026-02-25T19:36:01.974332Z","caller":"traceutil/trace.go:171","msg":"trace[1838399350] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1943; }","duration":"119.240815ms","start":"2026-02-25T19:36:01.855075Z","end":"2026-02-25T19:36:01.974316Z","steps":["trace[1838399350] 'agreement among raft nodes before linearized reading' (duration: 118.94005ms)"],"step_count":1}