{"level":"warn","ts":"2026-03-14T07:03:16.999219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T07:03:16.999326Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-03-14T07:03:16.999396Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T07:03:16.999406Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-03-14T07:03:16.999436Z","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-03-14T07:03:17.000294Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-03-14T07:03:17.000379Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-03-14T07:03:17.009573Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.972989ms"} {"level":"info","ts":"2026-03-14T07:03:17.024181Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-03-14T07:03:17.024291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T07:03:17.024318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-03-14T07:03:17.024331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T07:03:17.02436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-03-14T07:03:17.024412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-03-14T07:03:17.041722Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T07:03:17.043889Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T07:03:17.045681Z","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-03-14T07:03:17.048079Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T07:03:17.04883Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T07:03:17.048704Z","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-03-14T07:03:17.049116Z","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-03-14T07:03:17.049131Z","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-03-14T07:03:17.050281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-03-14T07:03:17.050543Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-03-14T07:03:17.058474Z","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-03-14T07:03:17.058674Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T07:03:17.058708Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T07:03:17.059628Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-03-14T07:03:17.059744Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-03-14T07:03:17.625762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T07:03:17.625827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T07:03:17.625874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-03-14T07:03:17.625898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-03-14T07:03:17.625914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-03-14T07:03:17.625935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-03-14T07:03:17.625953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-03-14T07:03:17.62699Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:03:17.628065Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T07:03:17.628184Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:03:17.628234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:03:17.628458Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:03:17.628545Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:03:17.628574Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:03:17.628954Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T07:03:17.629005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T07:03:17.63197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T07:03:17.63197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"warn","ts":"2026-03-14T07:03:26.352032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722676156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:03:26.853039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722676156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:03:27.902875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722676156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:03:27.940425Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.140749826s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:03:27.9439Z","caller":"traceutil/trace.go:171","msg":"trace[1014694424] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"2.144234584s","start":"2026-03-14T07:03:25.799603Z","end":"2026-03-14T07:03:27.943838Z","steps":["trace[1014694424] 'process raft request' (duration: 2.141052144s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:27.944132Z","caller":"traceutil/trace.go:171","msg":"trace[784738936] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"2.132895492s","start":"2026-03-14T07:03:25.811211Z","end":"2026-03-14T07:03:27.944106Z","steps":["trace[784738936] 'process raft request' (duration: 2.132584845s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:27.944334Z","caller":"traceutil/trace.go:171","msg":"trace[945967327] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:301; }","duration":"2.103083089s","start":"2026-03-14T07:03:25.841237Z","end":"2026-03-14T07:03:27.94432Z","steps":["trace[945967327] 'read index received' (duration: 2.09954164s)","trace[945967327] 'applied index is now lower than readState.Index' (duration: 3.540559ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:27.944523Z","caller":"traceutil/trace.go:171","msg":"trace[602648789] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"2.132927002s","start":"2026-03-14T07:03:25.811585Z","end":"2026-03-14T07:03:27.944512Z","steps":["trace[602648789] 'process raft request' (duration: 2.132428001s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:27.944648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:25.799593Z","time spent":"2.144402078s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:27.944745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.103502678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-14T07:03:27.944809Z","caller":"traceutil/trace.go:171","msg":"trace[1279870193] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:297; }","duration":"2.10359359s","start":"2026-03-14T07:03:25.841205Z","end":"2026-03-14T07:03:27.944798Z","steps":["trace[1279870193] 'agreement among raft nodes before linearized reading' (duration: 2.103500238s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:27.94484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:25.841189Z","time spent":"2.103641671s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-03-14T07:03:27.944997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:25.811157Z","time spent":"2.133039895s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:27.945061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.10357928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-14T07:03:27.945101Z","caller":"traceutil/trace.go:171","msg":"trace[965848763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:297; }","duration":"2.103721463s","start":"2026-03-14T07:03:25.841371Z","end":"2026-03-14T07:03:27.945092Z","steps":["trace[965848763] 'agreement among raft nodes before linearized reading' (duration: 2.103657201s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:27.94513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:25.841365Z","time spent":"2.103757794s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:03:27.944671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:25.811572Z","time spent":"2.132982334s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:30.539586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001141391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-14T07:03:30.540245Z","caller":"traceutil/trace.go:171","msg":"trace[2026192554] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.343918723s","start":"2026-03-14T07:03:28.196289Z","end":"2026-03-14T07:03:30.540208Z","steps":["trace[2026192554] 'agreement among raft nodes before linearized reading' (duration: 2.001157711s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:30.54032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.196277Z","time spent":"2.344026506s","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:03:30.980728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.936392555s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:30.98113Z","caller":"traceutil/trace.go:171","msg":"trace[988112838] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"2.936320165s","start":"2026-03-14T07:03:28.044789Z","end":"2026-03-14T07:03:30.981109Z","steps":["trace[988112838] 'process raft request' (duration: 2.936206242s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:30.98115Z","caller":"traceutil/trace.go:171","msg":"trace[1188034699] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"2.939144757s","start":"2026-03-14T07:03:28.041963Z","end":"2026-03-14T07:03:30.981108Z","steps":["trace[1188034699] 'compare' (duration: 2.935174218s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:30.981242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.044777Z","time spent":"2.936421177s","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:30.981341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.041953Z","time spent":"2.9393082s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:03:30.981681Z","caller":"traceutil/trace.go:171","msg":"trace[942685193] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"2.937694425s","start":"2026-03-14T07:03:28.043975Z","end":"2026-03-14T07:03:30.98167Z","steps":["trace[942685193] 'read index received' (duration: 35.051µs)","trace[942685193] 'applied index is now lower than readState.Index' (duration: 2.937657914s)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:30.982079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.938070553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-14T07:03:30.982179Z","caller":"traceutil/trace.go:171","msg":"trace[1421508600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:310; }","duration":"2.938209266s","start":"2026-03-14T07:03:28.043948Z","end":"2026-03-14T07:03:30.982157Z","steps":["trace[1421508600] 'agreement among raft nodes before linearized reading' (duration: 2.938030982s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:30.982231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.043941Z","time spent":"2.938279927s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-03-14T07:03:31.084211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.038337273s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-14T07:03:31.423442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.231933ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:31.423642Z","caller":"traceutil/trace.go:171","msg":"trace[1858289802] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:310; }","duration":"326.472169ms","start":"2026-03-14T07:03:31.09714Z","end":"2026-03-14T07:03:31.423613Z","steps":["trace[1858289802] 'range keys from in-memory index tree' (duration: 326.205733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.423777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.063179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:31.424177Z","caller":"traceutil/trace.go:171","msg":"trace[1215812917] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:317; }","duration":"442.409927ms","start":"2026-03-14T07:03:30.981744Z","end":"2026-03-14T07:03:31.424154Z","steps":["trace[1215812917] 'read index received' (duration: 102.789546ms)","trace[1215812917] 'applied index is now lower than readState.Index' (duration: 339.619291ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:31.424249Z","caller":"traceutil/trace.go:171","msg":"trace[1119106283] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"3.365340214s","start":"2026-03-14T07:03:28.058866Z","end":"2026-03-14T07:03:31.424206Z","steps":["trace[1119106283] 'process raft request' (duration: 3.365116329s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.424329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.235923ms","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-03-14T07:03:31.424363Z","caller":"traceutil/trace.go:171","msg":"trace[450589881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:316; }","duration":"881.330855ms","start":"2026-03-14T07:03:30.543022Z","end":"2026-03-14T07:03:31.424353Z","steps":["trace[450589881] 'agreement among raft nodes before linearized reading' (duration: 881.247703ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:31.424309Z","caller":"traceutil/trace.go:171","msg":"trace[318181000] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"3.378896265s","start":"2026-03-14T07:03:28.045127Z","end":"2026-03-14T07:03:31.424024Z","steps":["trace[318181000] 'process raft request' (duration: 3.039396356s)","trace[318181000] 'compare' (duration: 338.865885ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:31.424413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.542991Z","time spent":"881.412877ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-14T07:03:31.424508Z","caller":"traceutil/trace.go:171","msg":"trace[90912106] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"879.923173ms","start":"2026-03-14T07:03:30.544567Z","end":"2026-03-14T07:03:31.42449Z","steps":["trace[90912106] 'process raft request' (duration: 879.460473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.424547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.045117Z","time spent":"3.379318664s","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:31.424616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.544547Z","time spent":"880.004156ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:31.424638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.343558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:03:31.424735Z","caller":"traceutil/trace.go:171","msg":"trace[1358173691] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:316; }","duration":"770.48697ms","start":"2026-03-14T07:03:30.654228Z","end":"2026-03-14T07:03:31.424715Z","steps":["trace[1358173691] 'agreement among raft nodes before linearized reading' (duration: 770.283726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.424766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.722955ms","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-03-14T07:03:31.424793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.654209Z","time spent":"770.574323ms","remote":"127.0.0.1:54242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-03-14T07:03:31.424865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.36291858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-14T07:03:31.424908Z","caller":"traceutil/trace.go:171","msg":"trace[672175518] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:316; }","duration":"3.363004922s","start":"2026-03-14T07:03:28.061886Z","end":"2026-03-14T07:03:31.424891Z","steps":["trace[672175518] 'agreement among raft nodes before linearized reading' (duration: 3.362952581s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.424945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.061854Z","time spent":"3.363084314s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-03-14T07:03:31.424944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.371273906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-14T07:03:31.424987Z","caller":"traceutil/trace.go:171","msg":"trace[1759457835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:316; }","duration":"3.371343678s","start":"2026-03-14T07:03:28.053631Z","end":"2026-03-14T07:03:31.424975Z","steps":["trace[1759457835] 'agreement among raft nodes before linearized reading' (duration: 3.371232005s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.053619Z","time spent":"3.371401089s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-03-14T07:03:31.425059Z","caller":"traceutil/trace.go:171","msg":"trace[248463144] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"438.268044ms","start":"2026-03-14T07:03:30.98677Z","end":"2026-03-14T07:03:31.425038Z","steps":["trace[248463144] 'process raft request' (duration: 437.346714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.352866878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-14T07:03:31.424797Z","caller":"traceutil/trace.go:171","msg":"trace[466443831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:316; }","duration":"877.782476ms","start":"2026-03-14T07:03:30.547005Z","end":"2026-03-14T07:03:31.424787Z","steps":["trace[466443831] 'agreement among raft nodes before linearized reading' (duration: 877.731055ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:31.425147Z","caller":"traceutil/trace.go:171","msg":"trace[1494313284] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:316; }","duration":"3.352921609s","start":"2026-03-14T07:03:28.072216Z","end":"2026-03-14T07:03:31.425138Z","steps":["trace[1494313284] 'agreement among raft nodes before linearized reading' (duration: 3.352870187s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.986743Z","time spent":"438.370757ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:31.425175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.416697104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"warn","ts":"2026-03-14T07:03:31.425174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.546993Z","time spent":"878.166385ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:03:31.425177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.072199Z","time spent":"3.35297062s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-03-14T07:03:31.425209Z","caller":"traceutil/trace.go:171","msg":"trace[768825439] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:316; }","duration":"2.416757796s","start":"2026-03-14T07:03:29.00844Z","end":"2026-03-14T07:03:31.425198Z","steps":["trace[768825439] 'agreement among raft nodes before linearized reading' (duration: 2.416711615s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:29.008416Z","time spent":"2.416815437s","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5359,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-14T07:03:31.42529Z","caller":"traceutil/trace.go:171","msg":"trace[1320940653] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"876.60378ms","start":"2026-03-14T07:03:30.548672Z","end":"2026-03-14T07:03:31.425276Z","steps":["trace[1320940653] 'process raft request' (duration: 875.413033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.548663Z","time spent":"876.652332ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:31.425356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.356463707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-03-14T07:03:31.425302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.05021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-14T07:03:31.42539Z","caller":"traceutil/trace.go:171","msg":"trace[1478721373] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:316; }","duration":"3.356514299s","start":"2026-03-14T07:03:28.068867Z","end":"2026-03-14T07:03:31.425381Z","steps":["trace[1478721373] 'agreement among raft nodes before linearized reading' (duration: 3.356453488s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.424487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.058851Z","time spent":"3.365506938s","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:31.425448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.068857Z","time spent":"3.35655443s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-03-14T07:03:31.42545Z","caller":"traceutil/trace.go:171","msg":"trace[664115358] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:316; }","duration":"438.228283ms","start":"2026-03-14T07:03:30.98717Z","end":"2026-03-14T07:03:31.425399Z","steps":["trace[664115358] 'agreement among raft nodes before linearized reading' (duration: 437.975388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.352289225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-14T07:03:31.425509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.987161Z","time spent":"438.337186ms","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-03-14T07:03:31.425523Z","caller":"traceutil/trace.go:171","msg":"trace[977614601] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:316; }","duration":"3.352769466s","start":"2026-03-14T07:03:28.072739Z","end":"2026-03-14T07:03:31.425509Z","steps":["trace[977614601] 'agreement among raft nodes before linearized reading' (duration: 3.352273645s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.072728Z","time spent":"3.352825437s","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-03-14T07:03:31.425655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.839614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-14T07:03:31.424792Z","caller":"traceutil/trace.go:171","msg":"trace[459810568] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"3.379496929s","start":"2026-03-14T07:03:28.045283Z","end":"2026-03-14T07:03:31.42478Z","steps":["trace[459810568] 'process raft request' (duration: 3.378610149s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:31.425693Z","caller":"traceutil/trace.go:171","msg":"trace[332042745] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:316; }","duration":"271.916426ms","start":"2026-03-14T07:03:31.15376Z","end":"2026-03-14T07:03:31.425676Z","steps":["trace[332042745] 'agreement among raft nodes before linearized reading' (duration: 271.859374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:28.045276Z","time spent":"3.380436069s","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:31.424296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.548236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-14T07:03:31.425897Z","caller":"traceutil/trace.go:171","msg":"trace[1548901171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:316; }","duration":"881.12162ms","start":"2026-03-14T07:03:30.544714Z","end":"2026-03-14T07:03:31.425836Z","steps":["trace[1548901171] 'agreement among raft nodes before linearized reading' (duration: 879.468403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.425939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.544707Z","time spent":"881.216382ms","remote":"127.0.0.1:54514","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-03-14T07:03:31.425125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.109912ms","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-03-14T07:03:31.426014Z","caller":"traceutil/trace.go:171","msg":"trace[76757200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:316; }","duration":"883.024973ms","start":"2026-03-14T07:03:30.542976Z","end":"2026-03-14T07:03:31.426001Z","steps":["trace[76757200] 'agreement among raft nodes before linearized reading' (duration: 882.117343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:31.42605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:30.542943Z","time spent":"883.096895ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T07:04:00.871674Z","caller":"traceutil/trace.go:171","msg":"trace[414679805] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"216.422991ms","start":"2026-03-14T07:04:00.655226Z","end":"2026-03-14T07:04:00.871649Z","steps":["trace[414679805] 'read index received' (duration: 216.278688ms)","trace[414679805] 'applied index is now lower than readState.Index' (duration: 143.413µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:04:00.871739Z","caller":"traceutil/trace.go:171","msg":"trace[2143922019] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"224.416241ms","start":"2026-03-14T07:04:00.647295Z","end":"2026-03-14T07:04:00.871711Z","steps":["trace[2143922019] 'process raft request' (duration: 224.150986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:00.871816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.569844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:04:00.871853Z","caller":"traceutil/trace.go:171","msg":"trace[1651843598] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:500; }","duration":"216.654646ms","start":"2026-03-14T07:04:00.655185Z","end":"2026-03-14T07:04:00.87184Z","steps":["trace[1651843598] 'agreement among raft nodes before linearized reading' (duration: 216.566334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:01.489646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.333987ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:04:01.489754Z","caller":"traceutil/trace.go:171","msg":"trace[279887514] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:501; }","duration":"392.461369ms","start":"2026-03-14T07:04:01.097277Z","end":"2026-03-14T07:04:01.489739Z","steps":["trace[279887514] 'range keys from in-memory index tree' (duration: 392.315996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:02.193096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722676552,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:04:02.465876Z","caller":"traceutil/trace.go:171","msg":"trace[286695146] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"774.205841ms","start":"2026-03-14T07:04:01.691647Z","end":"2026-03-14T07:04:02.465852Z","steps":["trace[286695146] 'process raft request' (duration: 774.046647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:02.466067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:01.691617Z","time spent":"774.348324ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:04:02.469084Z","caller":"traceutil/trace.go:171","msg":"trace[733773251] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"777.305627ms","start":"2026-03-14T07:04:01.691745Z","end":"2026-03-14T07:04:02.46905Z","steps":["trace[733773251] 'process raft request' (duration: 777.060562ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:02.469246Z","caller":"traceutil/trace.go:171","msg":"trace[236034449] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:517; }","duration":"777.085692ms","start":"2026-03-14T07:04:01.692126Z","end":"2026-03-14T07:04:02.469212Z","steps":["trace[236034449] 'read index received' (duration: 773.618818ms)","trace[236034449] 'applied index is now lower than readState.Index' (duration: 3.464714ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:04:02.469304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:01.691717Z","time spent":"777.475841ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:04:02.469545Z","caller":"traceutil/trace.go:171","msg":"trace[364103083] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"776.089451ms","start":"2026-03-14T07:04:01.693439Z","end":"2026-03-14T07:04:02.469528Z","steps":["trace[364103083] 'process raft request' (duration: 775.55324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:02.469904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.884406ms","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-03-14T07:04:02.469928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.765137ms","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-03-14T07:04:02.469974Z","caller":"traceutil/trace.go:171","msg":"trace[1700842823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"774.970547ms","start":"2026-03-14T07:04:01.694986Z","end":"2026-03-14T07:04:02.469957Z","steps":["trace[1700842823] 'agreement among raft nodes before linearized reading' (duration: 774.765893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:02.470024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:01.694977Z","time spent":"775.030248ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-14T07:04:02.469976Z","caller":"traceutil/trace.go:171","msg":"trace[1258821691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"777.860339ms","start":"2026-03-14T07:04:01.692102Z","end":"2026-03-14T07:04:02.469963Z","steps":["trace[1258821691] 'agreement among raft nodes before linearized reading' (duration: 777.665045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:02.47035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:01.692091Z","time spent":"778.240377ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:04:02.470771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:01.693401Z","time spent":"776.232944ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:04:04.154736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.574647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:04:04.154828Z","caller":"traceutil/trace.go:171","msg":"trace[2092494265] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"375.100789ms","start":"2026-03-14T07:04:03.779712Z","end":"2026-03-14T07:04:04.154812Z","steps":["trace[2092494265] 'process raft request' (duration: 104.282247ms)","trace[2092494265] 'compare' (duration: 270.462995ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:04:04.154881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:03.77969Z","time spent":"375.16865ms","remote":"127.0.0.1:54262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:04:04.168809Z","caller":"traceutil/trace.go:171","msg":"trace[1836490239] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"231.061323ms","start":"2026-03-14T07:04:03.937728Z","end":"2026-03-14T07:04:04.168789Z","steps":["trace[1836490239] 'process raft request' (duration: 230.93154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.036923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722676905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:04:23.53792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722676905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:04:23.581866Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.056223992s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:04:23.582453Z","caller":"traceutil/trace.go:171","msg":"trace[1216135705] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.056848525s","start":"2026-03-14T07:04:22.525558Z","end":"2026-03-14T07:04:23.582406Z","steps":["trace[1216135705] 'process raft request' (duration: 1.056569649s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.582749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:22.525537Z","time spent":"1.0570576s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:04:23.930755Z","caller":"traceutil/trace.go:171","msg":"trace[68172729] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:711; }","duration":"1.394582867s","start":"2026-03-14T07:04:22.536151Z","end":"2026-03-14T07:04:23.930734Z","steps":["trace[68172729] 'read index received' (duration: 1.045911152s)","trace[68172729] 'applied index is now lower than readState.Index' (duration: 348.670825ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:04:23.930764Z","caller":"traceutil/trace.go:171","msg":"trace[916558572] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"926.173926ms","start":"2026-03-14T07:04:23.004562Z","end":"2026-03-14T07:04:23.930736Z","steps":["trace[916558572] 'process raft request' (duration: 925.979292ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:23.930852Z","caller":"traceutil/trace.go:171","msg":"trace[847899900] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"772.247249ms","start":"2026-03-14T07:04:23.158598Z","end":"2026-03-14T07:04:23.930846Z","steps":["trace[847899900] 'process raft request' (duration: 772.067276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.930903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:23.004523Z","time spent":"926.306209ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:04:23.930928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:23.158571Z","time spent":"772.30001ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:04:23.931342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377492662s","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-03-14T07:04:23.931371Z","caller":"traceutil/trace.go:171","msg":"trace[1936559381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"1.377538283s","start":"2026-03-14T07:04:22.553824Z","end":"2026-03-14T07:04:23.931362Z","steps":["trace[1936559381] 'agreement among raft nodes before linearized reading' (duration: 1.37739464s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.931391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:22.553816Z","time spent":"1.377571244s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:04:23.931396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395191901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:04:23.931497Z","caller":"traceutil/trace.go:171","msg":"trace[1757276589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"1.395349034s","start":"2026-03-14T07:04:22.536121Z","end":"2026-03-14T07:04:23.93147Z","steps":["trace[1757276589] 'agreement among raft nodes before linearized reading' (duration: 1.395083808s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.931531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322388896s","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-03-14T07:04:23.931548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344476717s","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-03-14T07:04:23.931548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:22.536108Z","time spent":"1.395432615s","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T07:04:23.931565Z","caller":"traceutil/trace.go:171","msg":"trace[1695303257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"1.322422316s","start":"2026-03-14T07:04:22.609133Z","end":"2026-03-14T07:04:23.931555Z","steps":["trace[1695303257] 'agreement among raft nodes before linearized reading' (duration: 1.322309774s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:23.931571Z","caller":"traceutil/trace.go:171","msg":"trace[1906789421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"1.344529589s","start":"2026-03-14T07:04:22.587036Z","end":"2026-03-14T07:04:23.931565Z","steps":["trace[1906789421] 'agreement among raft nodes before linearized reading' (duration: 1.344466147s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.931589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:22.609006Z","time spent":"1.32257836s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T07:04:23.931591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:22.587026Z","time spent":"1.344561639s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:04:23.931665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.410608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-14T07:04:23.931674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369232416s","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-03-14T07:04:23.931682Z","caller":"traceutil/trace.go:171","msg":"trace[936218480] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:694; }","duration":"731.485189ms","start":"2026-03-14T07:04:23.200191Z","end":"2026-03-14T07:04:23.931676Z","steps":["trace[936218480] 'agreement among raft nodes before linearized reading' (duration: 731.450538ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:23.93169Z","caller":"traceutil/trace.go:171","msg":"trace[221240793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"1.369260136s","start":"2026-03-14T07:04:22.562425Z","end":"2026-03-14T07:04:23.931685Z","steps":["trace[221240793] 'agreement among raft nodes before linearized reading' (duration: 1.369210915s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.931703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:23.200164Z","time spent":"731.53436ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:04:23.931707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:22.562418Z","time spent":"1.369285617s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-14T07:04:23.931814Z","caller":"traceutil/trace.go:171","msg":"trace[1186821787] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"759.17651ms","start":"2026-03-14T07:04:23.17263Z","end":"2026-03-14T07:04:23.931807Z","steps":["trace[1186821787] 'process raft request' (duration: 758.070076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:23.931853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:23.172611Z","time spent":"759.219461ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5170,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:04:38.959034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677046,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:04:39.459943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677046,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:04:39.961087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677046,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:04:40.037519Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.981532427s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-14T07:04:40.431602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.696188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:04:40.431683Z","caller":"traceutil/trace.go:171","msg":"trace[891499711] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"1.973665965s","start":"2026-03-14T07:04:38.458001Z","end":"2026-03-14T07:04:40.431667Z","steps":["trace[891499711] 'read index received' (duration: 1.579757631s)","trace[891499711] 'applied index is now lower than readState.Index' (duration: 393.907364ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:04:40.431856Z","caller":"traceutil/trace.go:171","msg":"trace[453255287] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"2.376515129s","start":"2026-03-14T07:04:38.055329Z","end":"2026-03-14T07:04:40.431844Z","steps":["trace[453255287] 'process raft request' (duration: 1.982476372s)","trace[453255287] 'compare' (duration: 393.594565ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:04:40.431895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:38.055314Z","time spent":"2.376560769s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:04:40.432068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.974048715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:04:40.432084Z","caller":"traceutil/trace.go:171","msg":"trace[323319399] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:740; }","duration":"1.974115817s","start":"2026-03-14T07:04:38.457961Z","end":"2026-03-14T07:04:40.432077Z","steps":["trace[323319399] 'agreement among raft nodes before linearized reading' (duration: 1.974054345s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:40.432101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:38.45794Z","time spent":"1.974157268s","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":10,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:04:40.432252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.974108936s","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-03-14T07:04:40.432265Z","caller":"traceutil/trace.go:171","msg":"trace[2084419784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"1.974131298s","start":"2026-03-14T07:04:38.45813Z","end":"2026-03-14T07:04:40.432261Z","steps":["trace[2084419784] 'agreement among raft nodes before linearized reading' (duration: 1.974047195s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:40.432284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:38.458126Z","time spent":"1.974154708s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T07:04:40.432411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.106008ms","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-03-14T07:04:40.432423Z","caller":"traceutil/trace.go:171","msg":"trace[1900747189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"373.141859ms","start":"2026-03-14T07:04:40.059277Z","end":"2026-03-14T07:04:40.432419Z","steps":["trace[1900747189] 'agreement among raft nodes before linearized reading' (duration: 373.099618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:40.432436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:40.059267Z","time spent":"373.165009ms","remote":"127.0.0.1:54514","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-03-14T07:04:40.432548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.080113ms","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-03-14T07:04:40.432562Z","caller":"traceutil/trace.go:171","msg":"trace[379012632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"377.157756ms","start":"2026-03-14T07:04:40.055399Z","end":"2026-03-14T07:04:40.432557Z","steps":["trace[379012632] 'agreement among raft nodes before linearized reading' (duration: 377.087184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:40.432575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:40.055368Z","time spent":"377.203497ms","remote":"127.0.0.1:54514","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-03-14T07:04:40.432664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.656096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-14T07:04:40.432676Z","caller":"traceutil/trace.go:171","msg":"trace[1904241306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"384.709838ms","start":"2026-03-14T07:04:40.047962Z","end":"2026-03-14T07:04:40.432671Z","steps":["trace[1904241306] 'agreement among raft nodes before linearized reading' (duration: 384.666926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:40.432689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:40.047937Z","time spent":"384.747738ms","remote":"127.0.0.1:54514","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-03-14T07:04:40.43276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.965150318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:04:40.432772Z","caller":"traceutil/trace.go:171","msg":"trace[1528908356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"1.965171258s","start":"2026-03-14T07:04:38.467596Z","end":"2026-03-14T07:04:40.432767Z","steps":["trace[1528908356] 'agreement among raft nodes before linearized reading' (duration: 1.965148578s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:40.432784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:04:38.46759Z","time spent":"1.965190839s","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:05:01.194982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.936241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:05:01.195197Z","caller":"traceutil/trace.go:171","msg":"trace[1641477650] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:825; }","duration":"437.791375ms","start":"2026-03-14T07:05:00.757388Z","end":"2026-03-14T07:05:01.19518Z","steps":["trace[1641477650] 'read index received' (duration: 222.515014ms)","trace[1641477650] 'applied index is now lower than readState.Index' (duration: 215.275301ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:05:01.19529Z","caller":"traceutil/trace.go:171","msg":"trace[950208639] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"476.012007ms","start":"2026-03-14T07:05:00.719267Z","end":"2026-03-14T07:05:01.195279Z","steps":["trace[950208639] 'process raft request' (duration: 475.839002ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:01.19542Z","caller":"traceutil/trace.go:171","msg":"trace[2034363855] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"479.676875ms","start":"2026-03-14T07:05:00.715681Z","end":"2026-03-14T07:05:01.195358Z","steps":["trace[2034363855] 'process raft request' (duration: 264.207219ms)","trace[2034363855] 'compare' (duration: 214.791078ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:01.195496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:00.719248Z","time spent":"476.072678ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:05:01.195669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:00.71565Z","time spent":"479.87835ms","remote":"127.0.0.1:54242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:05:01.195969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.577127ms","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-03-14T07:05:01.196018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.813783ms","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-03-14T07:05:01.196093Z","caller":"traceutil/trace.go:171","msg":"trace[1308193038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"438.740151ms","start":"2026-03-14T07:05:00.757329Z","end":"2026-03-14T07:05:01.196069Z","steps":["trace[1308193038] 'agreement among raft nodes before linearized reading' (duration: 438.33849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:01.196231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:00.757307Z","time spent":"438.908595ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:05:01.196228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.463814ms","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-03-14T07:05:01.19632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.682987ms","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-03-14T07:05:01.196149Z","caller":"traceutil/trace.go:171","msg":"trace[778684866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"386.990708ms","start":"2026-03-14T07:05:00.809123Z","end":"2026-03-14T07:05:01.196113Z","steps":["trace[778684866] 'agreement among raft nodes before linearized reading' (duration: 386.536156ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:01.196408Z","caller":"traceutil/trace.go:171","msg":"trace[1161705741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"380.701911ms","start":"2026-03-14T07:05:00.815656Z","end":"2026-03-14T07:05:01.196358Z","steps":["trace[1161705741] 'agreement among raft nodes before linearized reading' (duration: 380.328791ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:01.196405Z","caller":"traceutil/trace.go:171","msg":"trace[586579539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"381.798199ms","start":"2026-03-14T07:05:00.814592Z","end":"2026-03-14T07:05:01.19639Z","steps":["trace[586579539] 'agreement among raft nodes before linearized reading' (duration: 381.565453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:01.196486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:00.814578Z","time spent":"381.895473ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T07:05:01.196525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:00.8091Z","time spent":"387.4078ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:05:01.196699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:00.815624Z","time spent":"381.06076ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-14T07:05:03.360204Z","caller":"traceutil/trace.go:171","msg":"trace[1233980424] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"111.91182ms","start":"2026-03-14T07:05:03.248266Z","end":"2026-03-14T07:05:03.360177Z","steps":["trace[1233980424] 'read index received' (duration: 111.686524ms)","trace[1233980424] 'applied index is now lower than readState.Index' (duration: 224.386µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:03.360596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.30176ms","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-03-14T07:05:03.360691Z","caller":"traceutil/trace.go:171","msg":"trace[1515951564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"112.469394ms","start":"2026-03-14T07:05:03.248201Z","end":"2026-03-14T07:05:03.36067Z","steps":["trace[1515951564] 'agreement among raft nodes before linearized reading' (duration: 112.155886ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:03.360884Z","caller":"traceutil/trace.go:171","msg":"trace[2034635974] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"149.982497ms","start":"2026-03-14T07:05:03.210887Z","end":"2026-03-14T07:05:03.360869Z","steps":["trace[2034635974] 'process raft request' (duration: 149.144904ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:03.719333Z","caller":"traceutil/trace.go:171","msg":"trace[179866461] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"351.724207ms","start":"2026-03-14T07:05:03.367583Z","end":"2026-03-14T07:05:03.719307Z","steps":["trace[179866461] 'process raft request' (duration: 351.548942ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:03.719401Z","caller":"traceutil/trace.go:171","msg":"trace[1460656127] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:833; }","duration":"272.002827ms","start":"2026-03-14T07:05:03.447364Z","end":"2026-03-14T07:05:03.719367Z","steps":["trace[1460656127] 'read index received' (duration: 271.427912ms)","trace[1460656127] 'applied index is now lower than readState.Index' (duration: 573.665µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:03.719513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:03.367574Z","time spent":"351.833059ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:05:03.719557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.180812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:05:03.719595Z","caller":"traceutil/trace.go:171","msg":"trace[915919797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"272.282915ms","start":"2026-03-14T07:05:03.4473Z","end":"2026-03-14T07:05:03.719583Z","steps":["trace[915919797] 'agreement among raft nodes before linearized reading' (duration: 272.169212ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:03.719812Z","caller":"traceutil/trace.go:171","msg":"trace[1564764108] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"353.194356ms","start":"2026-03-14T07:05:03.366604Z","end":"2026-03-14T07:05:03.719798Z","steps":["trace[1564764108] 'process raft request' (duration: 352.295842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:03.719881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:03.366585Z","time spent":"353.261458ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:03.720008Z","caller":"traceutil/trace.go:171","msg":"trace[1821650080] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"352.022775ms","start":"2026-03-14T07:05:03.367967Z","end":"2026-03-14T07:05:03.719989Z","steps":["trace[1821650080] 'process raft request' (duration: 351.276875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:03.720162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:03.367958Z","time spent":"352.091546ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:05.829205Z","caller":"traceutil/trace.go:171","msg":"trace[699330357] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"100.403902ms","start":"2026-03-14T07:05:05.728781Z","end":"2026-03-14T07:05:05.829185Z","steps":["trace[699330357] 'process raft request' (duration: 100.264829ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:05.845187Z","caller":"traceutil/trace.go:171","msg":"trace[2070497891] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"114.915821ms","start":"2026-03-14T07:05:05.730255Z","end":"2026-03-14T07:05:05.845171Z","steps":["trace[2070497891] 'process raft request' (duration: 114.785897ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:05.845457Z","caller":"traceutil/trace.go:171","msg":"trace[1642851376] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"114.642403ms","start":"2026-03-14T07:05:05.730763Z","end":"2026-03-14T07:05:05.845405Z","steps":["trace[1642851376] 'process raft request' (duration: 114.365416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:16.947415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:05:17.448486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:05:17.750737Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.761736015s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-14T07:05:17.781818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.893985369s","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-03-14T07:05:17.781909Z","caller":"traceutil/trace.go:171","msg":"trace[1488430415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"1.894110521s","start":"2026-03-14T07:05:15.887774Z","end":"2026-03-14T07:05:17.781885Z","steps":["trace[1488430415] 'range keys from in-memory index tree' (duration: 1.893819364s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:17.781967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:15.887764Z","time spent":"1.894194984s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T07:05:17.78205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911157647s","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-03-14T07:05:17.782135Z","caller":"traceutil/trace.go:171","msg":"trace[1141121083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"1.911294001s","start":"2026-03-14T07:05:15.870818Z","end":"2026-03-14T07:05:17.782112Z","steps":["trace[1141121083] 'range keys from in-memory index tree' (duration: 1.910945052s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:17.782184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:15.870799Z","time spent":"1.911375553s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:05:17.782912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.884713211s","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-03-14T07:05:17.783032Z","caller":"traceutil/trace.go:171","msg":"trace[1745809001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"1.885758448s","start":"2026-03-14T07:05:15.897248Z","end":"2026-03-14T07:05:17.783007Z","steps":["trace[1745809001] 'range keys from in-memory index tree' (duration: 1.884542666s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:17.783105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:15.897243Z","time spent":"1.885846171s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-14T07:05:17.784006Z","caller":"traceutil/trace.go:171","msg":"trace[673281769] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"1.337110102s","start":"2026-03-14T07:05:16.446881Z","end":"2026-03-14T07:05:17.783991Z","steps":["trace[673281769] 'read index received' (duration: 1.303999427s)","trace[673281769] 'applied index is now lower than readState.Index' (duration: 33.109295ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:17.785768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338876098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:05:17.785913Z","caller":"traceutil/trace.go:171","msg":"trace[1164276581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"1.339001462s","start":"2026-03-14T07:05:16.446866Z","end":"2026-03-14T07:05:17.785867Z","steps":["trace[1164276581] 'agreement among raft nodes before linearized reading' (duration: 1.337263055s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:17.785952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:16.446805Z","time spent":"1.339137496s","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:05:17.786193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203243585s","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":"info","ts":"2026-03-14T07:05:17.786261Z","caller":"traceutil/trace.go:171","msg":"trace[621463488] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:889; }","duration":"1.205352801s","start":"2026-03-14T07:05:16.580876Z","end":"2026-03-14T07:05:17.786229Z","steps":["trace[621463488] 'agreement among raft nodes before linearized reading' (duration: 1.203249135s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:17.786307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:16.580858Z","time spent":"1.205429153s","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:05:17.786902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.400054ms","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-03-14T07:05:17.786978Z","caller":"traceutil/trace.go:171","msg":"trace[769269708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"343.534278ms","start":"2026-03-14T07:05:17.44341Z","end":"2026-03-14T07:05:17.786944Z","steps":["trace[769269708] 'agreement among raft nodes before linearized reading' (duration: 340.771994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:17.787097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:17.44338Z","time spent":"343.62191ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T07:05:17.787098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.211406ms","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-03-14T07:05:17.78715Z","caller":"traceutil/trace.go:171","msg":"trace[662961041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"225.300159ms","start":"2026-03-14T07:05:17.561824Z","end":"2026-03-14T07:05:17.787124Z","steps":["trace[662961041] 'agreement among raft nodes before linearized reading' (duration: 222.34922ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:19.95818Z","caller":"traceutil/trace.go:171","msg":"trace[415620492] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"151.844327ms","start":"2026-03-14T07:05:19.806288Z","end":"2026-03-14T07:05:19.958133Z","steps":["trace[415620492] 'process raft request' (duration: 148.59495ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:19.958287Z","caller":"traceutil/trace.go:171","msg":"trace[1939187326] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"150.07744ms","start":"2026-03-14T07:05:19.80819Z","end":"2026-03-14T07:05:19.958267Z","steps":["trace[1939187326] 'process raft request' (duration: 150.028638ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:19.958502Z","caller":"traceutil/trace.go:171","msg":"trace[975179601] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"151.94179ms","start":"2026-03-14T07:05:19.806553Z","end":"2026-03-14T07:05:19.958494Z","steps":["trace[975179601] 'process raft request' (duration: 151.505078ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:19.958588Z","caller":"traceutil/trace.go:171","msg":"trace[210486428] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"151.782676ms","start":"2026-03-14T07:05:19.806375Z","end":"2026-03-14T07:05:19.958157Z","steps":["trace[210486428] 'process raft request' (duration: 151.650392ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:30.987871Z","caller":"traceutil/trace.go:171","msg":"trace[1418769778] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"296.419049ms","start":"2026-03-14T07:05:30.691332Z","end":"2026-03-14T07:05:30.987751Z","steps":["trace[1418769778] 'process raft request' (duration: 290.234174ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:32.056411Z","caller":"traceutil/trace.go:171","msg":"trace[709754161] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"194.885307ms","start":"2026-03-14T07:05:31.8615Z","end":"2026-03-14T07:05:32.056385Z","steps":["trace[709754161] 'process raft request' (duration: 194.394854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:34.354459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.794328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:05:34.354685Z","caller":"traceutil/trace.go:171","msg":"trace[1958350282] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"204.447042ms","start":"2026-03-14T07:05:34.150209Z","end":"2026-03-14T07:05:34.354656Z","steps":["trace[1958350282] 'process raft request' (duration: 80.335406ms)","trace[1958350282] 'compare' (duration: 123.653823ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:05:34.354719Z","caller":"traceutil/trace.go:171","msg":"trace[1642376976] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"204.114893ms","start":"2026-03-14T07:05:34.150584Z","end":"2026-03-14T07:05:34.354698Z","steps":["trace[1642376976] 'process raft request' (duration: 204.047621ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:34.354895Z","caller":"traceutil/trace.go:171","msg":"trace[1137700335] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"204.440492ms","start":"2026-03-14T07:05:34.150441Z","end":"2026-03-14T07:05:34.354882Z","steps":["trace[1137700335] 'process raft request' (duration: 204.138074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:35.947084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677588,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:05:36.07423Z","caller":"traceutil/trace.go:171","msg":"trace[937709596] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"748.423985ms","start":"2026-03-14T07:05:35.325779Z","end":"2026-03-14T07:05:36.074203Z","steps":["trace[937709596] 'process raft request' (duration: 748.2417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:36.074402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:35.325747Z","time spent":"748.562718ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:36.093886Z","caller":"traceutil/trace.go:171","msg":"trace[814180463] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"662.14299ms","start":"2026-03-14T07:05:35.43171Z","end":"2026-03-14T07:05:36.093853Z","steps":["trace[814180463] 'process raft request' (duration: 661.864942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:36.094201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:35.431682Z","time spent":"662.285744ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:36.165869Z","caller":"traceutil/trace.go:171","msg":"trace[664423997] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"719.395878ms","start":"2026-03-14T07:05:35.446433Z","end":"2026-03-14T07:05:36.165828Z","steps":["trace[664423997] 'read index received' (duration: 627.809362ms)","trace[664423997] 'applied index is now lower than readState.Index' (duration: 91.584436ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:36.165986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.525152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:05:36.166064Z","caller":"traceutil/trace.go:171","msg":"trace[1576406901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"719.647674ms","start":"2026-03-14T07:05:35.446397Z","end":"2026-03-14T07:05:36.166045Z","steps":["trace[1576406901] 'agreement among raft nodes before linearized reading' (duration: 719.514131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:36.166111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:35.446386Z","time spent":"719.717547ms","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T07:05:36.36434Z","caller":"traceutil/trace.go:171","msg":"trace[1250447847] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"192.600135ms","start":"2026-03-14T07:05:36.171691Z","end":"2026-03-14T07:05:36.364292Z","steps":["trace[1250447847] 'process raft request' (duration: 105.259132ms)","trace[1250447847] 'compare' (duration: 87.078136ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:05:36.528506Z","caller":"traceutil/trace.go:171","msg":"trace[2029823441] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"158.881705ms","start":"2026-03-14T07:05:36.369594Z","end":"2026-03-14T07:05:36.528476Z","steps":["trace[2029823441] 'process raft request' (duration: 104.178593ms)","trace[2029823441] 'compare' (duration: 54.508587ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:05:36.528677Z","caller":"traceutil/trace.go:171","msg":"trace[1159163914] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"158.930166ms","start":"2026-03-14T07:05:36.36972Z","end":"2026-03-14T07:05:36.52865Z","steps":["trace[1159163914] 'process raft request' (duration: 158.716581ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:38.59804Z","caller":"traceutil/trace.go:171","msg":"trace[1897184845] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"229.305696ms","start":"2026-03-14T07:05:38.368707Z","end":"2026-03-14T07:05:38.598012Z","steps":["trace[1897184845] 'read index received' (duration: 229.034999ms)","trace[1897184845] 'applied index is now lower than readState.Index' (duration: 269.147µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:05:38.598161Z","caller":"traceutil/trace.go:171","msg":"trace[928351696] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"401.743643ms","start":"2026-03-14T07:05:38.196373Z","end":"2026-03-14T07:05:38.598117Z","steps":["trace[928351696] 'process raft request' (duration: 401.414204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:38.598334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.593944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-14T07:05:38.598388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:38.19634Z","time spent":"401.953849ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:38.598397Z","caller":"traceutil/trace.go:171","msg":"trace[1086330588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"229.738487ms","start":"2026-03-14T07:05:38.368644Z","end":"2026-03-14T07:05:38.598383Z","steps":["trace[1086330588] 'agreement among raft nodes before linearized reading' (duration: 229.48212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:38.598444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.509591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:05:38.59849Z","caller":"traceutil/trace.go:171","msg":"trace[105578529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"150.570113ms","start":"2026-03-14T07:05:38.447907Z","end":"2026-03-14T07:05:38.598477Z","steps":["trace[105578529] 'agreement among raft nodes before linearized reading' (duration: 150.512041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:38.598384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.649637ms","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-03-14T07:05:38.598558Z","caller":"traceutil/trace.go:171","msg":"trace[1518457890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"195.880982ms","start":"2026-03-14T07:05:38.402664Z","end":"2026-03-14T07:05:38.598545Z","steps":["trace[1518457890] 'agreement among raft nodes before linearized reading' (duration: 195.536763ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:38.914785Z","caller":"traceutil/trace.go:171","msg":"trace[178849615] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1007; }","duration":"138.557492ms","start":"2026-03-14T07:05:38.776196Z","end":"2026-03-14T07:05:38.914753Z","steps":["trace[178849615] 'read index received' (duration: 138.10108ms)","trace[178849615] 'applied index is now lower than readState.Index' (duration: 455.052µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:38.91502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.791828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:05:38.915061Z","caller":"traceutil/trace.go:171","msg":"trace[1670975303] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:972; }","duration":"138.903331ms","start":"2026-03-14T07:05:38.776146Z","end":"2026-03-14T07:05:38.915049Z","steps":["trace[1670975303] 'agreement among raft nodes before linearized reading' (duration: 138.767097ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:38.915481Z","caller":"traceutil/trace.go:171","msg":"trace[1667995478] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"310.101255ms","start":"2026-03-14T07:05:38.605365Z","end":"2026-03-14T07:05:38.915467Z","steps":["trace[1667995478] 'process raft request' (duration: 309.043857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:38.9156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:38.6053Z","time spent":"310.223768ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:38.915766Z","caller":"traceutil/trace.go:171","msg":"trace[1559114745] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"310.379652ms","start":"2026-03-14T07:05:38.605376Z","end":"2026-03-14T07:05:38.915756Z","steps":["trace[1559114745] 'process raft request' (duration: 309.217301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:38.915827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:38.60536Z","time spent":"310.436834ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:38.915908Z","caller":"traceutil/trace.go:171","msg":"trace[229052550] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"308.8065ms","start":"2026-03-14T07:05:38.607093Z","end":"2026-03-14T07:05:38.915899Z","steps":["trace[229052550] 'process raft request' (duration: 307.549796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:38.915963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:38.607082Z","time spent":"308.852702ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:38.916102Z","caller":"traceutil/trace.go:171","msg":"trace[75966603] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"300.340724ms","start":"2026-03-14T07:05:38.615752Z","end":"2026-03-14T07:05:38.916093Z","steps":["trace[75966603] 'process raft request' (duration: 298.932886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:38.916148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:38.615733Z","time spent":"300.389685ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:05:39.253225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.848019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:05:39.253768Z","caller":"traceutil/trace.go:171","msg":"trace[697443322] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"292.641318ms","start":"2026-03-14T07:05:38.96111Z","end":"2026-03-14T07:05:39.253751Z","steps":["trace[697443322] 'process raft request' (duration: 159.192374ms)","trace[697443322] 'compare' (duration: 132.654623ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:05:39.253963Z","caller":"traceutil/trace.go:171","msg":"trace[1024098611] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"260.122199ms","start":"2026-03-14T07:05:38.993798Z","end":"2026-03-14T07:05:39.25392Z","steps":["trace[1024098611] 'process raft request' (duration: 259.595625ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:39.254078Z","caller":"traceutil/trace.go:171","msg":"trace[1713877985] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"261.64618ms","start":"2026-03-14T07:05:38.992385Z","end":"2026-03-14T07:05:39.254031Z","steps":["trace[1713877985] 'process raft request' (duration: 260.975502ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:40.73217Z","caller":"traceutil/trace.go:171","msg":"trace[553434146] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"121.237658ms","start":"2026-03-14T07:05:40.610873Z","end":"2026-03-14T07:05:40.732111Z","steps":["trace[553434146] 'process raft request' (duration: 120.949441ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:40.947869Z","caller":"traceutil/trace.go:171","msg":"trace[1250112704] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"177.147053ms","start":"2026-03-14T07:05:40.770692Z","end":"2026-03-14T07:05:40.947839Z","steps":["trace[1250112704] 'process raft request' (duration: 104.81156ms)","trace[1250112704] 'compare' (duration: 72.120577ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:41.290893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.661895ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:05:41.291317Z","caller":"traceutil/trace.go:171","msg":"trace[582194326] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:980; }","duration":"193.86627ms","start":"2026-03-14T07:05:41.097182Z","end":"2026-03-14T07:05:41.291048Z","steps":["trace[582194326] 'range keys from in-memory index tree' (duration: 193.635553ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:41.305608Z","caller":"traceutil/trace.go:171","msg":"trace[1604488793] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"352.267281ms","start":"2026-03-14T07:05:40.953301Z","end":"2026-03-14T07:05:41.305569Z","steps":["trace[1604488793] 'process raft request' (duration: 351.953302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:41.305858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:40.953287Z","time spent":"352.437736ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:41.306631Z","caller":"traceutil/trace.go:171","msg":"trace[1374193679] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1020; }","duration":"352.034395ms","start":"2026-03-14T07:05:40.95458Z","end":"2026-03-14T07:05:41.306614Z","steps":["trace[1374193679] 'read index received' (duration: 350.909405ms)","trace[1374193679] 'applied index is now lower than readState.Index' (duration: 1.12387ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:05:41.306677Z","caller":"traceutil/trace.go:171","msg":"trace[2309276] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"353.181996ms","start":"2026-03-14T07:05:40.953469Z","end":"2026-03-14T07:05:41.306651Z","steps":["trace[2309276] 'process raft request' (duration: 352.939099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:41.306819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:40.953463Z","time spent":"353.277338ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:05:41.307175Z","caller":"traceutil/trace.go:171","msg":"trace[929665729] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"353.071952ms","start":"2026-03-14T07:05:40.954096Z","end":"2026-03-14T07:05:41.307168Z","steps":["trace[929665729] 'process raft request' (duration: 352.471486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:41.307228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:40.954088Z","time spent":"353.116203ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:05:41.307594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.99938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-14T07:05:41.307963Z","caller":"traceutil/trace.go:171","msg":"trace[1589984624] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:983; }","duration":"353.073753ms","start":"2026-03-14T07:05:40.954563Z","end":"2026-03-14T07:05:41.307637Z","steps":["trace[1589984624] 'agreement among raft nodes before linearized reading' (duration: 352.123518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:41.308018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:40.954555Z","time spent":"353.449213ms","remote":"127.0.0.1:54516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-14T07:05:49.4072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:05:49.907492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722677734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:05:50.370512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.542973235s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:05:50.3714Z","caller":"traceutil/trace.go:171","msg":"trace[1734130643] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"1.464463137s","start":"2026-03-14T07:05:48.906912Z","end":"2026-03-14T07:05:50.371375Z","steps":["trace[1734130643] 'read index received' (duration: 1.46419734s)","trace[1734130643] 'applied index is now lower than readState.Index' (duration: 264.317µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:05:50.371641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464720374s","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-03-14T07:05:50.371575Z","caller":"traceutil/trace.go:171","msg":"trace[908572018] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.544076993s","start":"2026-03-14T07:05:48.827432Z","end":"2026-03-14T07:05:50.371509Z","steps":["trace[908572018] 'process raft request' (duration: 1.543778316s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:50.371687Z","caller":"traceutil/trace.go:171","msg":"trace[605638251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"1.464831856s","start":"2026-03-14T07:05:48.906844Z","end":"2026-03-14T07:05:50.371676Z","steps":["trace[605638251] 'agreement among raft nodes before linearized reading' (duration: 1.464682513s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.371754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15616699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:05:50.37185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:48.827401Z","time spent":"1.544306619s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:05:50.371865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.676491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-14T07:05:50.371912Z","caller":"traceutil/trace.go:171","msg":"trace[967552502] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1036; }","duration":"749.750433ms","start":"2026-03-14T07:05:49.622153Z","end":"2026-03-14T07:05:50.371903Z","steps":["trace[967552502] 'agreement among raft nodes before linearized reading' (duration: 749.64168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.371952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:49.622137Z","time spent":"749.809214ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-14T07:05:50.371952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.653374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-14T07:05:50.371994Z","caller":"traceutil/trace.go:171","msg":"trace[807063460] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1036; }","duration":"580.715386ms","start":"2026-03-14T07:05:49.791268Z","end":"2026-03-14T07:05:50.371983Z","steps":["trace[807063460] 'agreement among raft nodes before linearized reading' (duration: 580.588593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.372114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:49.791255Z","time spent":"580.843749ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T07:05:50.371981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.167791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-14T07:05:50.372228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.075032ms","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-03-14T07:05:50.372304Z","caller":"traceutil/trace.go:171","msg":"trace[2102019874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"993.135523ms","start":"2026-03-14T07:05:49.379143Z","end":"2026-03-14T07:05:50.372278Z","steps":["trace[2102019874] 'agreement among raft nodes before linearized reading' (duration: 992.926678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.372344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.767183ms","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-03-14T07:05:50.372374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062735168s","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-03-14T07:05:50.372233Z","caller":"traceutil/trace.go:171","msg":"trace[1585465827] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1036; }","duration":"335.551881ms","start":"2026-03-14T07:05:50.036669Z","end":"2026-03-14T07:05:50.372221Z","steps":["trace[1585465827] 'agreement among raft nodes before linearized reading' (duration: 335.133801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.372251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.282331ms","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-03-14T07:05:50.37242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:50.036642Z","time spent":"335.768586ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:05:50.371832Z","caller":"traceutil/trace.go:171","msg":"trace[2127693177] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1036; }","duration":"1.156294293s","start":"2026-03-14T07:05:49.215518Z","end":"2026-03-14T07:05:50.371813Z","steps":["trace[2127693177] 'agreement among raft nodes before linearized reading' (duration: 1.15618253s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.37237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:49.379132Z","time spent":"993.227855ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T07:05:50.371733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:48.906825Z","time spent":"1.464898698s","remote":"127.0.0.1:54514","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-03-14T07:05:50.372398Z","caller":"traceutil/trace.go:171","msg":"trace[1664646572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"964.818794ms","start":"2026-03-14T07:05:49.407565Z","end":"2026-03-14T07:05:50.372384Z","steps":["trace[1664646572] 'agreement among raft nodes before linearized reading' (duration: 964.590618ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:50.372498Z","caller":"traceutil/trace.go:171","msg":"trace[1491087390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"970.550868ms","start":"2026-03-14T07:05:49.401896Z","end":"2026-03-14T07:05:50.372447Z","steps":["trace[1491087390] 'agreement among raft nodes before linearized reading' (duration: 969.967603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.372644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:49.407477Z","time spent":"965.128152ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-14T07:05:50.372416Z","caller":"traceutil/trace.go:171","msg":"trace[2118128611] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1036; }","duration":"1.06280006s","start":"2026-03-14T07:05:49.309605Z","end":"2026-03-14T07:05:50.372405Z","steps":["trace[2118128611] 'agreement among raft nodes before linearized reading' (duration: 1.062702917s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:05:50.372797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:49.30958Z","time spent":"1.063203759s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T07:05:50.372677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:49.401839Z","time spent":"970.822354ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:05:50.372796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:05:49.215495Z","time spent":"1.157285758s","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:06:17.550055Z","caller":"traceutil/trace.go:171","msg":"trace[504220474] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"103.409012ms","start":"2026-03-14T07:06:17.44662Z","end":"2026-03-14T07:06:17.550029Z","steps":["trace[504220474] 'read index received' (duration: 103.240517ms)","trace[504220474] 'applied index is now lower than readState.Index' (duration: 167.355µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:17.550092Z","caller":"traceutil/trace.go:171","msg":"trace[1111521364] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"120.786388ms","start":"2026-03-14T07:06:17.429281Z","end":"2026-03-14T07:06:17.550067Z","steps":["trace[1111521364] 'process raft request' (duration: 120.588573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:17.550232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.604337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:17.550284Z","caller":"traceutil/trace.go:171","msg":"trace[2079262870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1152; }","duration":"103.732971ms","start":"2026-03-14T07:06:17.446529Z","end":"2026-03-14T07:06:17.550262Z","steps":["trace[2079262870] 'agreement among raft nodes before linearized reading' (duration: 103.650978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:57.203776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.805984ms","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-03-14T07:06:57.203877Z","caller":"traceutil/trace.go:171","msg":"trace[616042843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"455.961298ms","start":"2026-03-14T07:06:56.747896Z","end":"2026-03-14T07:06:57.203858Z","steps":["trace[616042843] 'range keys from bolt db' (duration: 454.192296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:57.203914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:56.747881Z","time spent":"456.023759ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T07:06:57.206867Z","caller":"traceutil/trace.go:171","msg":"trace[1681839366] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"458.161831ms","start":"2026-03-14T07:06:56.748688Z","end":"2026-03-14T07:06:57.20685Z","steps":["trace[1681839366] 'process raft request' (duration: 457.905465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:57.206966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:56.748644Z","time spent":"458.258114ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:07.759861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722678532,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:07:08.260503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722678532,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:07:08.760896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722678532,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:07:09.064847Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.277616754s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:07:09.065406Z","caller":"traceutil/trace.go:171","msg":"trace[699013491] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"2.27827387s","start":"2026-03-14T07:07:06.787093Z","end":"2026-03-14T07:07:09.065367Z","steps":["trace[699013491] 'process raft request' (duration: 2.278048765s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.065713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:06.787062Z","time spent":"2.278467445s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:07:09.074272Z","caller":"traceutil/trace.go:171","msg":"trace[889595845] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.402979668s","start":"2026-03-14T07:07:07.671262Z","end":"2026-03-14T07:07:09.074242Z","steps":["trace[889595845] 'process raft request' (duration: 1.402770093s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.07447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.671219Z","time spent":"1.403141412s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:07:09.074523Z","caller":"traceutil/trace.go:171","msg":"trace[905950122] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1405; }","duration":"1.81553531s","start":"2026-03-14T07:07:07.258948Z","end":"2026-03-14T07:07:09.074483Z","steps":["trace[905950122] 'read index received' (duration: 1.806311228s)","trace[905950122] 'applied index is now lower than readState.Index' (duration: 9.220832ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:09.074646Z","caller":"traceutil/trace.go:171","msg":"trace[1014594968] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"481.275607ms","start":"2026-03-14T07:07:08.593357Z","end":"2026-03-14T07:07:09.074633Z","steps":["trace[1014594968] 'process raft request' (duration: 480.840047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.074717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:08.593323Z","time spent":"481.359109ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:09.074941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.814106335s","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-03-14T07:07:09.074981Z","caller":"traceutil/trace.go:171","msg":"trace[1638540215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"1.814179216s","start":"2026-03-14T07:07:07.260792Z","end":"2026-03-14T07:07:09.074971Z","steps":["trace[1638540215] 'agreement among raft nodes before linearized reading' (duration: 1.814013722s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.075011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.26078Z","time spent":"1.814224628s","remote":"127.0.0.1:54514","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-03-14T07:07:09.075062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627754079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:09.075162Z","caller":"traceutil/trace.go:171","msg":"trace[1561303511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"1.627880001s","start":"2026-03-14T07:07:07.447255Z","end":"2026-03-14T07:07:09.075135Z","steps":["trace[1561303511] 'agreement among raft nodes before linearized reading' (duration: 1.627729068s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.075207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.447251Z","time spent":"1.627947224s","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:07:09.075279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.628688562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:09.075318Z","caller":"traceutil/trace.go:171","msg":"trace[50314994] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1351; }","duration":"1.628730152s","start":"2026-03-14T07:07:07.446576Z","end":"2026-03-14T07:07:09.075306Z","steps":["trace[50314994] 'agreement among raft nodes before linearized reading' (duration: 1.628666091s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.075349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.446518Z","time spent":"1.628823194s","remote":"127.0.0.1:48934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:07:09.075407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.424676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:09.075445Z","caller":"traceutil/trace.go:171","msg":"trace[1416754095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"605.500588ms","start":"2026-03-14T07:07:08.469933Z","end":"2026-03-14T07:07:09.075433Z","steps":["trace[1416754095] 'agreement among raft nodes before linearized reading' (duration: 605.445376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.075489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:08.469908Z","time spent":"605.57297ms","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:07:09.075567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811061732s","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-03-14T07:07:09.075602Z","caller":"traceutil/trace.go:171","msg":"trace[448803564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"1.811094152s","start":"2026-03-14T07:07:07.264498Z","end":"2026-03-14T07:07:09.075592Z","steps":["trace[448803564] 'agreement among raft nodes before linearized reading' (duration: 1.81100424s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.075633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.264463Z","time spent":"1.811163494s","remote":"127.0.0.1:54514","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-03-14T07:07:09.075689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329910118s","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":"info","ts":"2026-03-14T07:07:09.07573Z","caller":"traceutil/trace.go:171","msg":"trace[1229027823] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1351; }","duration":"1.32997546s","start":"2026-03-14T07:07:07.745739Z","end":"2026-03-14T07:07:09.075715Z","steps":["trace[1229027823] 'agreement among raft nodes before linearized reading' (duration: 1.329899509s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.075761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.745723Z","time spent":"1.330030001s","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:07:09.075798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812740632s","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:531"} {"level":"info","ts":"2026-03-14T07:07:09.075827Z","caller":"traceutil/trace.go:171","msg":"trace[865582890] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1351; }","duration":"1.812806224s","start":"2026-03-14T07:07:07.263012Z","end":"2026-03-14T07:07:09.075818Z","steps":["trace[865582890] 'agreement among raft nodes before linearized reading' (duration: 1.812727832s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.07586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.263005Z","time spent":"1.812848805s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-14T07:07:09.076018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385324702s","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":"warn","ts":"2026-03-14T07:07:09.076033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.813169853s","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-03-14T07:07:09.076052Z","caller":"traceutil/trace.go:171","msg":"trace[525779188] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1351; }","duration":"1.385382194s","start":"2026-03-14T07:07:07.690658Z","end":"2026-03-14T07:07:09.07604Z","steps":["trace[525779188] 'agreement among raft nodes before linearized reading' (duration: 1.385238811s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:09.076066Z","caller":"traceutil/trace.go:171","msg":"trace[1971280264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"1.813228924s","start":"2026-03-14T07:07:07.262827Z","end":"2026-03-14T07:07:09.076056Z","steps":["trace[1971280264] 'agreement among raft nodes before linearized reading' (duration: 1.813144992s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.076087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.690646Z","time spent":"1.385433705s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T07:07:09.076097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.262815Z","time spent":"1.813276365s","remote":"127.0.0.1:54514","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-03-14T07:07:09.076118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.817140758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-14T07:07:09.07621Z","caller":"traceutil/trace.go:171","msg":"trace[1000075287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"1.817325143s","start":"2026-03-14T07:07:07.258867Z","end":"2026-03-14T07:07:09.076192Z","steps":["trace[1000075287] 'agreement among raft nodes before linearized reading' (duration: 1.815810796s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.076271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.258841Z","time spent":"1.817415454s","remote":"127.0.0.1:54514","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-03-14T07:07:09.076323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412577719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-14T07:07:09.076354Z","caller":"traceutil/trace.go:171","msg":"trace[1998281232] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1351; }","duration":"1.41263737s","start":"2026-03-14T07:07:07.663706Z","end":"2026-03-14T07:07:09.076344Z","steps":["trace[1998281232] 'agreement among raft nodes before linearized reading' (duration: 1.412526048s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:09.076429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.663692Z","time spent":"1.412727882s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-14T07:07:11.053361Z","caller":"traceutil/trace.go:171","msg":"trace[205491075] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"482.552249ms","start":"2026-03-14T07:07:10.570784Z","end":"2026-03-14T07:07:11.053336Z","steps":["trace[205491075] 'process raft request' (duration: 482.402825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:11.053523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:10.570766Z","time spent":"482.680512ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:07:11.05441Z","caller":"traceutil/trace.go:171","msg":"trace[1785373671] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1415; }","duration":"388.075193ms","start":"2026-03-14T07:07:10.666313Z","end":"2026-03-14T07:07:11.054388Z","steps":["trace[1785373671] 'read index received' (duration: 386.930816ms)","trace[1785373671] 'applied index is now lower than readState.Index' (duration: 1.143437ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:11.054692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.35104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:07:11.054767Z","caller":"traceutil/trace.go:171","msg":"trace[1927918564] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1360; }","duration":"388.444483ms","start":"2026-03-14T07:07:10.666304Z","end":"2026-03-14T07:07:11.054749Z","steps":["trace[1927918564] 'agreement among raft nodes before linearized reading' (duration: 388.183057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:11.054829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:10.666226Z","time spent":"388.586986ms","remote":"127.0.0.1:54242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-03-14T07:07:11.055032Z","caller":"traceutil/trace.go:171","msg":"trace[1996432670] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"483.813679ms","start":"2026-03-14T07:07:10.571196Z","end":"2026-03-14T07:07:11.05501Z","steps":["trace[1996432670] 'process raft request' (duration: 483.02672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:11.055216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:10.571184Z","time spent":"483.929542ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:15.611065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740729722678599,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:07:15.899317Z","caller":"traceutil/trace.go:171","msg":"trace[296563611] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"789.18045ms","start":"2026-03-14T07:07:15.110096Z","end":"2026-03-14T07:07:15.899276Z","steps":["trace[296563611] 'process raft request' (duration: 787.473459ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:15.899319Z","caller":"traceutil/trace.go:171","msg":"trace[1915524801] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"788.931274ms","start":"2026-03-14T07:07:15.110357Z","end":"2026-03-14T07:07:15.899288Z","steps":["trace[1915524801] 'read index received' (duration: 787.225043ms)","trace[1915524801] 'applied index is now lower than readState.Index' (duration: 1.703621ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:15.899509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:15.110079Z","time spent":"789.334144ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:16.555308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.068974ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-14T07:07:16.555461Z","caller":"traceutil/trace.go:171","msg":"trace[851756761] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"656.024123ms","start":"2026-03-14T07:07:15.899414Z","end":"2026-03-14T07:07:16.555438Z","steps":["trace[851756761] 'read index received' (duration: 31.371µs)","trace[851756761] 'applied index is now lower than readState.Index' (duration: 655.990612ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:16.555898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111512279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:16.555961Z","caller":"traceutil/trace.go:171","msg":"trace[1224710594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"1.111609551s","start":"2026-03-14T07:07:15.444334Z","end":"2026-03-14T07:07:16.555943Z","steps":["trace[1224710594] 'agreement among raft nodes before linearized reading' (duration: 1.111505789s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:16.555988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:15.444319Z","time spent":"1.111664422s","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:07:16.555981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444889316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-14T07:07:16.555945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445536961s","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:531"} {"level":"warn","ts":"2026-03-14T07:07:16.555982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.174061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-14T07:07:16.556048Z","caller":"traceutil/trace.go:171","msg":"trace[1322365166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.444969997s","start":"2026-03-14T07:07:15.111059Z","end":"2026-03-14T07:07:16.556029Z","steps":["trace[1322365166] 'agreement among raft nodes before linearized reading' (duration: 1.444753952s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:16.556074Z","caller":"traceutil/trace.go:171","msg":"trace[1515815757] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"495.313844ms","start":"2026-03-14T07:07:16.060741Z","end":"2026-03-14T07:07:16.556055Z","steps":["trace[1515815757] 'agreement among raft nodes before linearized reading' (duration: 495.076388ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:16.556084Z","caller":"traceutil/trace.go:171","msg":"trace[106963609] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1377; }","duration":"1.445708195s","start":"2026-03-14T07:07:15.110335Z","end":"2026-03-14T07:07:16.556043Z","steps":["trace[106963609] 'agreement among raft nodes before linearized reading' (duration: 789.052096ms)","trace[106963609] 'range keys from in-memory index tree' (duration: 656.29296ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:16.556102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.681841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-03-14T07:07:16.556119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:15.111054Z","time spent":"1.445051179s","remote":"127.0.0.1:54514","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-03-14T07:07:16.556119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:16.060698Z","time spent":"495.409406ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T07:07:16.556145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:15.110329Z","time spent":"1.445803238s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-14T07:07:16.556154Z","caller":"traceutil/trace.go:171","msg":"trace[1675288832] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"497.763253ms","start":"2026-03-14T07:07:16.058371Z","end":"2026-03-14T07:07:16.556135Z","steps":["trace[1675288832] 'agreement among raft nodes before linearized reading' (duration: 497.413505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:16.556201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:16.058352Z","time spent":"497.836654ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T07:07:16.555988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439482766s","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-03-14T07:07:16.556265Z","caller":"traceutil/trace.go:171","msg":"trace[340623776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.439788953s","start":"2026-03-14T07:07:15.116461Z","end":"2026-03-14T07:07:16.556249Z","steps":["trace[340623776] 'agreement among raft nodes before linearized reading' (duration: 1.439301791s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:16.556286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:15.116451Z","time spent":"1.439827943s","remote":"127.0.0.1:54514","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-03-14T07:07:16.556336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445180182s","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-03-14T07:07:16.556392Z","caller":"traceutil/trace.go:171","msg":"trace[1450561096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.445244913s","start":"2026-03-14T07:07:15.111121Z","end":"2026-03-14T07:07:16.556366Z","steps":["trace[1450561096] 'agreement among raft nodes before linearized reading' (duration: 1.44511151s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:16.556433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:15.111109Z","time spent":"1.445312976s","remote":"127.0.0.1:54514","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-03-14T07:07:24.614772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.743759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:24.614858Z","caller":"traceutil/trace.go:171","msg":"trace[923627294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1412; }","duration":"167.886362ms","start":"2026-03-14T07:07:24.44695Z","end":"2026-03-14T07:07:24.614836Z","steps":["trace[923627294] 'range keys from in-memory index tree' (duration: 167.643996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:24.615078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.500824ms","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-03-14T07:07:24.615106Z","caller":"traceutil/trace.go:171","msg":"trace[905572236] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1412; }","duration":"241.578326ms","start":"2026-03-14T07:07:24.373519Z","end":"2026-03-14T07:07:24.615098Z","steps":["trace[905572236] 'count revisions from in-memory index tree' (duration: 241.373331ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:09:01.285788Z","caller":"traceutil/trace.go:171","msg":"trace[1540201296] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"123.957836ms","start":"2026-03-14T07:09:01.161808Z","end":"2026-03-14T07:09:01.285766Z","steps":["trace[1540201296] 'process raft request' (duration: 123.759981ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:09:09.44665Z","caller":"traceutil/trace.go:171","msg":"trace[800279096] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"110.735107ms","start":"2026-03-14T07:09:09.335886Z","end":"2026-03-14T07:09:09.446621Z","steps":["trace[800279096] 'process raft request' (duration: 42.211607ms)","trace[800279096] 'compare' (duration: 68.054459ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:09:09.446783Z","caller":"traceutil/trace.go:171","msg":"trace[1193466578] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1950; }","duration":"110.689657ms","start":"2026-03-14T07:09:09.336066Z","end":"2026-03-14T07:09:09.446756Z","steps":["trace[1193466578] 'read index received' (duration: 10.206256ms)","trace[1193466578] 'applied index is now lower than readState.Index' (duration: 100.482021ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:09:09.446887Z","caller":"traceutil/trace.go:171","msg":"trace[263070977] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"108.487183ms","start":"2026-03-14T07:09:09.33836Z","end":"2026-03-14T07:09:09.446847Z","steps":["trace[263070977] 'process raft request' (duration: 108.194466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:09:09.447064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.984644ms","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-03-14T07:09:09.447097Z","caller":"traceutil/trace.go:171","msg":"trace[935492242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"111.083476ms","start":"2026-03-14T07:09:09.336004Z","end":"2026-03-14T07:09:09.447088Z","steps":["trace[935492242] 'agreement among raft nodes before linearized reading' (duration: 110.875841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:09:09.447209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.856093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-14T07:09:09.447235Z","caller":"traceutil/trace.go:171","msg":"trace[175914059] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1871; }","duration":"108.898413ms","start":"2026-03-14T07:09:09.338327Z","end":"2026-03-14T07:09:09.447225Z","steps":["trace[175914059] 'agreement among raft nodes before linearized reading' (duration: 108.843552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:09:09.447358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.53044ms","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-03-14T07:09:09.447399Z","caller":"traceutil/trace.go:171","msg":"trace[1724814310] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1871; }","duration":"107.601472ms","start":"2026-03-14T07:09:09.339783Z","end":"2026-03-14T07:09:09.447384Z","steps":["trace[1724814310] 'agreement among raft nodes before linearized reading' (duration: 107.5335ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:09:09.570374Z","caller":"traceutil/trace.go:171","msg":"trace[1334692436] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"115.487382ms","start":"2026-03-14T07:09:09.454851Z","end":"2026-03-14T07:09:09.570338Z","steps":["trace[1334692436] 'process raft request' (duration: 111.333962ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:09:09.572427Z","caller":"traceutil/trace.go:171","msg":"trace[709715924] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"111.616729ms","start":"2026-03-14T07:09:09.460777Z","end":"2026-03-14T07:09:09.572394Z","steps":["trace[709715924] 'process raft request' (duration: 111.426295ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:09:09.57273Z","caller":"traceutil/trace.go:171","msg":"trace[106128760] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"103.140154ms","start":"2026-03-14T07:09:09.469577Z","end":"2026-03-14T07:09:09.572717Z","steps":["trace[106128760] 'process raft request' (duration: 102.771425ms)"],"step_count":1}