{"level":"warn","ts":"2026-02-26T16:51:49.010769Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T16:51:49.010874Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T16:51:49.010954Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T16:51:49.010974Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-02-26T16:51:49.010995Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T16:51:49.011824Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-02-26T16:51:49.011912Z","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":16,"max-cpu-available":16,"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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T16:51:49.015791Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.611147ms"} {"level":"info","ts":"2026-02-26T16:51:49.033514Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-02-26T16:51:49.033695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T16:51:49.033729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-02-26T16:51:49.033762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T16:51:49.033818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-02-26T16:51:49.033874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-02-26T16:51:49.040993Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T16:51:49.042773Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T16:51:49.043938Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-26T16:51:49.046523Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T16:51:49.046704Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T16:51:49.046831Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T16:51:49.046908Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T16:51:49.046919Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T16:51:49.048715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-02-26T16:51:49.04898Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-02-26T16:51:49.050955Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T16:51:49.051176Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T16:51:49.051206Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T16:51:49.05124Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-02-26T16:51:49.051252Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-02-26T16:51:49.134583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T16:51:49.134639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T16:51:49.134672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-02-26T16:51:49.134697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-02-26T16:51:49.134709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-02-26T16:51:49.13473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-02-26T16:51:49.134743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-02-26T16:51:49.135598Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T16:51:49.136475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T16:51:49.136476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T16:51:49.136523Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T16:51:49.136719Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T16:51:49.136777Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T16:51:49.137313Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T16:51:49.137419Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T16:51:49.137491Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T16:51:49.138586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-02-26T16:51:49.139663Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-26T16:52:00.633639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.694854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-j95hd\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-02-26T16:52:00.633782Z","caller":"traceutil/trace.go:171","msg":"trace[2100735705] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-j95hd; range_end:; response_count:1; response_revision:391; }","duration":"135.947608ms","start":"2026-02-26T16:52:00.497812Z","end":"2026-02-26T16:52:00.63376Z","steps":["trace[2100735705] 'range keys from in-memory index tree' (duration: 135.51347ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:07.056694Z","caller":"traceutil/trace.go:171","msg":"trace[1535672764] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"360.703054ms","start":"2026-02-26T16:52:06.695973Z","end":"2026-02-26T16:52:07.056676Z","steps":["trace[1535672764] 'process raft request' (duration: 360.363078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:07.057134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:06.695957Z","time spent":"360.800176ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T16:52:07.354135Z","caller":"traceutil/trace.go:171","msg":"trace[139800585] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"293.729154ms","start":"2026-02-26T16:52:07.060383Z","end":"2026-02-26T16:52:07.354113Z","steps":["trace[139800585] 'process raft request' (duration: 292.200696ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:07.35429Z","caller":"traceutil/trace.go:171","msg":"trace[1282525771] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"293.663012ms","start":"2026-02-26T16:52:07.060599Z","end":"2026-02-26T16:52:07.354262Z","steps":["trace[1282525771] 'process raft request' (duration: 293.478179ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:13.231578Z","caller":"traceutil/trace.go:171","msg":"trace[1706666819] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"149.403468ms","start":"2026-02-26T16:52:13.082153Z","end":"2026-02-26T16:52:13.231556Z","steps":["trace[1706666819] 'process raft request' (duration: 149.272316ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:17.122502Z","caller":"traceutil/trace.go:171","msg":"trace[1967907543] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"248.433444ms","start":"2026-02-26T16:52:16.874035Z","end":"2026-02-26T16:52:17.122469Z","steps":["trace[1967907543] 'read index received' (duration: 231.376188ms)","trace[1967907543] 'applied index is now lower than readState.Index' (duration: 17.056236ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:52:17.12257Z","caller":"traceutil/trace.go:171","msg":"trace[65766533] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"299.792745ms","start":"2026-02-26T16:52:16.822758Z","end":"2026-02-26T16:52:17.122551Z","steps":["trace[65766533] 'process raft request' (duration: 282.739899ms)","trace[65766533] 'compare' (duration: 16.657539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T16:52:17.122756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.792113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-26T16:52:17.122805Z","caller":"traceutil/trace.go:171","msg":"trace[39454892] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:500; }","duration":"239.882585ms","start":"2026-02-26T16:52:16.882904Z","end":"2026-02-26T16:52:17.122786Z","steps":["trace[39454892] 'agreement among raft nodes before linearized reading' (duration: 239.724212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:17.122803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.71438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-26T16:52:17.122845Z","caller":"traceutil/trace.go:171","msg":"trace[391439462] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:500; }","duration":"138.802652ms","start":"2026-02-26T16:52:16.984031Z","end":"2026-02-26T16:52:17.122834Z","steps":["trace[391439462] 'agreement among raft nodes before linearized reading' (duration: 138.681869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:17.123211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.161058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-02-26T16:52:17.123291Z","caller":"traceutil/trace.go:171","msg":"trace[2075043503] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:500; }","duration":"249.28685ms","start":"2026-02-26T16:52:16.873984Z","end":"2026-02-26T16:52:17.123271Z","steps":["trace[2075043503] 'agreement among raft nodes before linearized reading' (duration: 248.578157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:19.441038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.829847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T16:52:19.441105Z","caller":"traceutil/trace.go:171","msg":"trace[1756331581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"108.941119ms","start":"2026-02-26T16:52:19.332152Z","end":"2026-02-26T16:52:19.441093Z","steps":["trace[1756331581] 'range keys from in-memory index tree' (duration: 108.742666ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:22.469435Z","caller":"traceutil/trace.go:171","msg":"trace[2138882077] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"138.104319ms","start":"2026-02-26T16:52:22.331308Z","end":"2026-02-26T16:52:22.469412Z","steps":["trace[2138882077] 'process raft request' (duration: 138.026648ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:22.469434Z","caller":"traceutil/trace.go:171","msg":"trace[36963319] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"143.491529ms","start":"2026-02-26T16:52:22.325912Z","end":"2026-02-26T16:52:22.469404Z","steps":["trace[36963319] 'process raft request' (duration: 130.344415ms)","trace[36963319] 'compare' (duration: 12.845248ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:52:26.725928Z","caller":"traceutil/trace.go:171","msg":"trace[1853274348] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"150.335685ms","start":"2026-02-26T16:52:26.575573Z","end":"2026-02-26T16:52:26.725909Z","steps":["trace[1853274348] 'process raft request' (duration: 150.221173ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:27.912096Z","caller":"traceutil/trace.go:171","msg":"trace[965302880] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"439.573626ms","start":"2026-02-26T16:52:27.472498Z","end":"2026-02-26T16:52:27.912071Z","steps":["trace[965302880] 'read index received' (duration: 439.419533ms)","trace[965302880] 'applied index is now lower than readState.Index' (duration: 153.073µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:52:27.912166Z","caller":"traceutil/trace.go:171","msg":"trace[1126784976] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"526.69733ms","start":"2026-02-26T16:52:27.385417Z","end":"2026-02-26T16:52:27.912114Z","steps":["trace[1126784976] 'process raft request' (duration: 526.480766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:27.912262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.741629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T16:52:27.912306Z","caller":"traceutil/trace.go:171","msg":"trace[1967637646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"439.825071ms","start":"2026-02-26T16:52:27.472469Z","end":"2026-02-26T16:52:27.912294Z","steps":["trace[1967637646] 'agreement among raft nodes before linearized reading' (duration: 439.726479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:27.912306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:27.385398Z","time spent":"526.839983ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":365,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T16:52:27.912341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:27.472457Z","time spent":"439.875811ms","remote":"127.0.0.1:59990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T16:52:27.941171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.526102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T16:52:27.941171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.171485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T16:52:27.94124Z","caller":"traceutil/trace.go:171","msg":"trace[2020828810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"400.644004ms","start":"2026-02-26T16:52:27.54058Z","end":"2026-02-26T16:52:27.941224Z","steps":["trace[2020828810] 'agreement among raft nodes before linearized reading' (duration: 400.470221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:27.941217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.452974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T16:52:27.941255Z","caller":"traceutil/trace.go:171","msg":"trace[1305195860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"414.323387ms","start":"2026-02-26T16:52:27.526912Z","end":"2026-02-26T16:52:27.941236Z","steps":["trace[1305195860] 'agreement among raft nodes before linearized reading' (duration: 414.178795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:27.941269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:27.540556Z","time spent":"400.706715ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T16:52:27.941284Z","caller":"traceutil/trace.go:171","msg":"trace[612710164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"425.548756ms","start":"2026-02-26T16:52:27.515719Z","end":"2026-02-26T16:52:27.941267Z","steps":["trace[612710164] 'agreement among raft nodes before linearized reading' (duration: 425.409653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:27.941302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:27.526884Z","time spent":"414.406779ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T16:52:27.941329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:27.515708Z","time spent":"425.611877ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T16:52:29.831957Z","caller":"traceutil/trace.go:171","msg":"trace[678465149] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"144.983155ms","start":"2026-02-26T16:52:29.686944Z","end":"2026-02-26T16:52:29.831927Z","steps":["trace[678465149] 'process raft request' (duration: 144.820852ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:32.729503Z","caller":"traceutil/trace.go:171","msg":"trace[870389256] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"128.460782ms","start":"2026-02-26T16:52:32.601018Z","end":"2026-02-26T16:52:32.729479Z","steps":["trace[870389256] 'process raft request' (duration: 128.409951ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:32.729573Z","caller":"traceutil/trace.go:171","msg":"trace[1017691307] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"141.483388ms","start":"2026-02-26T16:52:32.588052Z","end":"2026-02-26T16:52:32.729535Z","steps":["trace[1017691307] 'process raft request' (duration: 99.987157ms)","trace[1017691307] 'compare' (duration: 40.018109ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:52:32.729618Z","caller":"traceutil/trace.go:171","msg":"trace[450899123] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"128.607405ms","start":"2026-02-26T16:52:32.600973Z","end":"2026-02-26T16:52:32.72958Z","steps":["trace[450899123] 'read index received' (duration: 86.948131ms)","trace[450899123] 'applied index is now lower than readState.Index' (duration: 41.652834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T16:52:32.730013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.013325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wfj7q\" ","response":"range_response_count:1 size:18063"} {"level":"info","ts":"2026-02-26T16:52:32.730062Z","caller":"traceutil/trace.go:171","msg":"trace[1199993684] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wfj7q; range_end:; response_count:1; response_revision:741; }","duration":"129.105637ms","start":"2026-02-26T16:52:32.600944Z","end":"2026-02-26T16:52:32.730049Z","steps":["trace[1199993684] 'agreement among raft nodes before linearized reading' (duration: 128.80566ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:33.104934Z","caller":"traceutil/trace.go:171","msg":"trace[436461783] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"201.975367ms","start":"2026-02-26T16:52:32.90293Z","end":"2026-02-26T16:52:33.104906Z","steps":["trace[436461783] 'process raft request' (duration: 177.47474ms)","trace[436461783] 'compare' (duration: 23.969146ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:52:48.404471Z","caller":"traceutil/trace.go:171","msg":"trace[409022303] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"341.748037ms","start":"2026-02-26T16:52:48.062697Z","end":"2026-02-26T16:52:48.404445Z","steps":["trace[409022303] 'read index received' (duration: 341.522712ms)","trace[409022303] 'applied index is now lower than readState.Index' (duration: 224.465µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T16:52:48.404714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.008303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T16:52:48.40475Z","caller":"traceutil/trace.go:171","msg":"trace[5214915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"342.111926ms","start":"2026-02-26T16:52:48.062627Z","end":"2026-02-26T16:52:48.404739Z","steps":["trace[5214915] 'agreement among raft nodes before linearized reading' (duration: 341.948552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:48.404787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:48.062602Z","time spent":"342.176706ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T16:52:48.404813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.667154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T16:52:48.404901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.377768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T16:52:48.404914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.735436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T16:52:48.405038Z","caller":"traceutil/trace.go:171","msg":"trace[1254075899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"335.90829ms","start":"2026-02-26T16:52:48.069109Z","end":"2026-02-26T16:52:48.405017Z","steps":["trace[1254075899] 'agreement among raft nodes before linearized reading' (duration: 335.623733ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:48.405002Z","caller":"traceutil/trace.go:171","msg":"trace[1803547908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"335.49385ms","start":"2026-02-26T16:52:48.06949Z","end":"2026-02-26T16:52:48.404984Z","steps":["trace[1803547908] 'agreement among raft nodes before linearized reading' (duration: 335.223274ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:52:48.404956Z","caller":"traceutil/trace.go:171","msg":"trace[1839137868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"335.874968ms","start":"2026-02-26T16:52:48.069066Z","end":"2026-02-26T16:52:48.404941Z","steps":["trace[1839137868] 'agreement among raft nodes before linearized reading' (duration: 335.558591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:48.405092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:48.069087Z","time spent":"335.994161ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T16:52:48.405125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:48.069047Z","time spent":"336.066973ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T16:52:48.405173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:48.069483Z","time spent":"335.670534ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T16:52:48.404509Z","caller":"traceutil/trace.go:171","msg":"trace[536188958] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"425.866426ms","start":"2026-02-26T16:52:47.978609Z","end":"2026-02-26T16:52:48.404476Z","steps":["trace[536188958] 'process raft request' (duration: 425.662832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:52:48.405363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:52:47.978585Z","time spent":"426.738634ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T16:53:05.801252Z","caller":"traceutil/trace.go:171","msg":"trace[2096791380] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"130.450956ms","start":"2026-02-26T16:53:05.670773Z","end":"2026-02-26T16:53:05.801224Z","steps":["trace[2096791380] 'process raft request' (duration: 130.378794ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:53:05.801323Z","caller":"traceutil/trace.go:171","msg":"trace[2135816037] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"159.633436ms","start":"2026-02-26T16:53:05.641607Z","end":"2026-02-26T16:53:05.801241Z","steps":["trace[2135816037] 'process raft request' (duration: 159.366231ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:53:06.111437Z","caller":"traceutil/trace.go:171","msg":"trace[1554268013] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"110.238563ms","start":"2026-02-26T16:53:06.001166Z","end":"2026-02-26T16:53:06.111405Z","steps":["trace[1554268013] 'process raft request' (duration: 69.398545ms)","trace[1554268013] 'compare' (duration: 40.665034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T16:53:09.432742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.814935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T16:53:09.432795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.041788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T16:53:09.432845Z","caller":"traceutil/trace.go:171","msg":"trace[284363063] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1079; }","duration":"253.985519ms","start":"2026-02-26T16:53:09.178843Z","end":"2026-02-26T16:53:09.432829Z","steps":["trace[284363063] 'count revisions from in-memory index tree' (duration: 253.708922ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:53:09.43287Z","caller":"traceutil/trace.go:171","msg":"trace[1234943800] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1079; }","duration":"228.171931ms","start":"2026-02-26T16:53:09.204682Z","end":"2026-02-26T16:53:09.432854Z","steps":["trace[1234943800] 'count revisions from in-memory index tree' (duration: 227.946917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:53:37.552124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.966557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T16:53:37.552269Z","caller":"traceutil/trace.go:171","msg":"trace[756022112] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"282.805522ms","start":"2026-02-26T16:53:37.269433Z","end":"2026-02-26T16:53:37.552239Z","steps":["trace[756022112] 'process raft request' (duration: 108.486789ms)","trace[756022112] 'compare' (duration: 173.847784ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:53:37.769293Z","caller":"traceutil/trace.go:171","msg":"trace[626884903] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"138.433418ms","start":"2026-02-26T16:53:37.630837Z","end":"2026-02-26T16:53:37.76927Z","steps":["trace[626884903] 'process raft request' (duration: 138.290105ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:53:37.787666Z","caller":"traceutil/trace.go:171","msg":"trace[315837143] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"156.194607ms","start":"2026-02-26T16:53:37.631445Z","end":"2026-02-26T16:53:37.78764Z","steps":["trace[315837143] 'process raft request' (duration: 156.025994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:53:37.888235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.655009ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-26T16:53:38.544929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.523281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T16:53:38.545025Z","caller":"traceutil/trace.go:171","msg":"trace[769514172] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1581; }","duration":"106.717645ms","start":"2026-02-26T16:53:38.438286Z","end":"2026-02-26T16:53:38.545003Z","steps":["trace[769514172] 'count revisions from in-memory index tree' (duration: 106.418728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:54:24.803907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.597327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T16:54:24.80401Z","caller":"traceutil/trace.go:171","msg":"trace[338360789] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1864; }","duration":"314.746259ms","start":"2026-02-26T16:54:24.489242Z","end":"2026-02-26T16:54:24.803988Z","steps":["trace[338360789] 'count revisions from in-memory index tree' (duration: 314.472894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:54:24.804056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:54:24.489229Z","time spent":"314.816221ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-26T16:54:24.80407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.844037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T16:54:24.804159Z","caller":"traceutil/trace.go:171","msg":"trace[1044988238] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1864; }","duration":"171.00284ms","start":"2026-02-26T16:54:24.633139Z","end":"2026-02-26T16:54:24.804142Z","steps":["trace[1044988238] 'count revisions from in-memory index tree' (duration: 170.728675ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:54:25.218894Z","caller":"traceutil/trace.go:171","msg":"trace[664678565] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"300.213284ms","start":"2026-02-26T16:54:24.918654Z","end":"2026-02-26T16:54:25.218867Z","steps":["trace[664678565] 'read index received' (duration: 300.180893ms)","trace[664678565] 'applied index is now lower than readState.Index' (duration: 31.341µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:54:25.218995Z","caller":"traceutil/trace.go:171","msg":"trace[2000846751] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"302.307922ms","start":"2026-02-26T16:54:24.916651Z","end":"2026-02-26T16:54:25.218959Z","steps":["trace[2000846751] 'process raft request' (duration: 302.048447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:54:25.219158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:54:24.916643Z","time spent":"302.428365ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T16:54:25.219193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.051029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T16:54:25.21917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.494229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T16:54:25.21924Z","caller":"traceutil/trace.go:171","msg":"trace[158057209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"300.13499ms","start":"2026-02-26T16:54:24.919091Z","end":"2026-02-26T16:54:25.219226Z","steps":["trace[158057209] 'agreement among raft nodes before linearized reading' (duration: 299.950057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:54:25.219239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.115985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T16:54:25.219268Z","caller":"traceutil/trace.go:171","msg":"trace[1012409122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1865; }","duration":"300.630461ms","start":"2026-02-26T16:54:24.918615Z","end":"2026-02-26T16:54:25.219245Z","steps":["trace[1012409122] 'agreement among raft nodes before linearized reading' (duration: 300.376376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:54:25.219291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:54:24.919085Z","time spent":"300.195932ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T16:54:25.219307Z","caller":"traceutil/trace.go:171","msg":"trace[355503675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"296.236937ms","start":"2026-02-26T16:54:24.923049Z","end":"2026-02-26T16:54:25.219286Z","steps":["trace[355503675] 'agreement among raft nodes before linearized reading' (duration: 296.054933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:54:25.219317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:54:24.918596Z","time spent":"300.708943ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T16:55:17.118997Z","caller":"traceutil/trace.go:171","msg":"trace[800663290] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"180.278568ms","start":"2026-02-26T16:55:16.938699Z","end":"2026-02-26T16:55:17.118977Z","steps":["trace[800663290] 'process raft request' (duration: 179.816589ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:55:17.637744Z","caller":"traceutil/trace.go:171","msg":"trace[431277688] linearizableReadLoop","detail":"{readStateIndex:2384; appliedIndex:2383; }","duration":"142.503262ms","start":"2026-02-26T16:55:17.495207Z","end":"2026-02-26T16:55:17.63771Z","steps":["trace[431277688] 'read index received' (duration: 142.221376ms)","trace[431277688] 'applied index is now lower than readState.Index' (duration: 279.996µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:55:17.637846Z","caller":"traceutil/trace.go:171","msg":"trace[1298981677] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"161.562929ms","start":"2026-02-26T16:55:17.476248Z","end":"2026-02-26T16:55:17.637811Z","steps":["trace[1298981677] 'process raft request' (duration: 161.200932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:55:17.63803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.801979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T16:55:17.638083Z","caller":"traceutil/trace.go:171","msg":"trace[1525161527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2295; }","duration":"142.881711ms","start":"2026-02-26T16:55:17.495187Z","end":"2026-02-26T16:55:17.638069Z","steps":["trace[1525161527] 'agreement among raft nodes before linearized reading' (duration: 142.692326ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:55:26.525852Z","caller":"traceutil/trace.go:171","msg":"trace[921509326] transaction","detail":"{read_only:false; response_revision:2352; number_of_response:1; }","duration":"124.016127ms","start":"2026-02-26T16:55:26.401801Z","end":"2026-02-26T16:55:26.525817Z","steps":["trace[921509326] 'process raft request' (duration: 123.802173ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:55:51.918918Z","caller":"traceutil/trace.go:171","msg":"trace[744135788] linearizableReadLoop","detail":"{readStateIndex:2580; appliedIndex:2579; }","duration":"132.701156ms","start":"2026-02-26T16:55:51.786195Z","end":"2026-02-26T16:55:51.918896Z","steps":["trace[744135788] 'read index received' (duration: 132.546823ms)","trace[744135788] 'applied index is now lower than readState.Index' (duration: 152.823µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:55:51.919025Z","caller":"traceutil/trace.go:171","msg":"trace[1394063626] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"153.893902ms","start":"2026-02-26T16:55:51.765096Z","end":"2026-02-26T16:55:51.91899Z","steps":["trace[1394063626] 'process raft request' (duration: 153.662027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:55:51.91911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.8975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T16:55:51.919139Z","caller":"traceutil/trace.go:171","msg":"trace[1117115724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2484; }","duration":"132.978232ms","start":"2026-02-26T16:55:51.786151Z","end":"2026-02-26T16:55:51.919129Z","steps":["trace[1117115724] 'agreement among raft nodes before linearized reading' (duration: 132.861729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:55:51.919369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.592973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T16:55:51.919465Z","caller":"traceutil/trace.go:171","msg":"trace[1932279662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2484; }","duration":"132.738336ms","start":"2026-02-26T16:55:51.786702Z","end":"2026-02-26T16:55:51.91944Z","steps":["trace[1932279662] 'agreement among raft nodes before linearized reading' (duration: 132.48053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:55:51.919367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.214238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T16:55:51.919591Z","caller":"traceutil/trace.go:171","msg":"trace[972002877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2484; }","duration":"114.477173ms","start":"2026-02-26T16:55:51.805095Z","end":"2026-02-26T16:55:51.919572Z","steps":["trace[972002877] 'agreement among raft nodes before linearized reading' (duration: 114.090105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:55:51.919372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.26579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T16:55:51.919693Z","caller":"traceutil/trace.go:171","msg":"trace[1207238253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2484; }","duration":"114.635557ms","start":"2026-02-26T16:55:51.805051Z","end":"2026-02-26T16:55:51.919687Z","steps":["trace[1207238253] 'agreement among raft nodes before linearized reading' (duration: 114.159437ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:42.474691Z","caller":"traceutil/trace.go:171","msg":"trace[147149431] transaction","detail":"{read_only:false; response_revision:4242; number_of_response:1; }","duration":"170.348805ms","start":"2026-02-26T16:59:42.304314Z","end":"2026-02-26T16:59:42.474663Z","steps":["trace[147149431] 'process raft request' (duration: 170.202972ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:42.478446Z","caller":"traceutil/trace.go:171","msg":"trace[484074929] transaction","detail":"{read_only:false; response_revision:4243; number_of_response:1; }","duration":"123.950086ms","start":"2026-02-26T16:59:42.354474Z","end":"2026-02-26T16:59:42.478424Z","steps":["trace[484074929] 'process raft request' (duration: 123.701251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:46.014545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.855964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T16:59:46.014635Z","caller":"traceutil/trace.go:171","msg":"trace[791311797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4370; }","duration":"130.993877ms","start":"2026-02-26T16:59:45.883625Z","end":"2026-02-26T16:59:46.014619Z","steps":["trace[791311797] 'range keys from in-memory index tree' (duration: 130.65859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:46.014643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.427255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8a48fe89-924f-4a54-a05a-645dca4f1d3f\" ","response":"range_response_count:1 size:4076"} {"level":"info","ts":"2026-02-26T16:59:46.014741Z","caller":"traceutil/trace.go:171","msg":"trace[1570647201] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8a48fe89-924f-4a54-a05a-645dca4f1d3f; range_end:; response_count:1; response_revision:4370; }","duration":"139.586489ms","start":"2026-02-26T16:59:45.875133Z","end":"2026-02-26T16:59:46.014719Z","steps":["trace[1570647201] 'range keys from in-memory index tree' (duration: 139.210891ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:49.410463Z","caller":"traceutil/trace.go:171","msg":"trace[384633498] linearizableReadLoop","detail":"{readStateIndex:4627; appliedIndex:4626; }","duration":"115.257653ms","start":"2026-02-26T16:59:49.295173Z","end":"2026-02-26T16:59:49.410431Z","steps":["trace[384633498] 'read index received' (duration: 114.938137ms)","trace[384633498] 'applied index is now lower than readState.Index' (duration: 318.416µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:59:49.410519Z","caller":"traceutil/trace.go:171","msg":"trace[1121118056] transaction","detail":"{read_only:false; response_revision:4452; number_of_response:1; }","duration":"116.805265ms","start":"2026-02-26T16:59:49.293696Z","end":"2026-02-26T16:59:49.410501Z","steps":["trace[1121118056] 'process raft request' (duration: 116.499869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:49.411591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.391065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75461"} {"level":"info","ts":"2026-02-26T16:59:49.411688Z","caller":"traceutil/trace.go:171","msg":"trace[455650083] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4452; }","duration":"116.524039ms","start":"2026-02-26T16:59:49.295145Z","end":"2026-02-26T16:59:49.411669Z","steps":["trace[455650083] 'agreement among raft nodes before linearized reading' (duration: 115.451358ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:49.620289Z","caller":"traceutil/trace.go:171","msg":"trace[2023611561] linearizableReadLoop","detail":"{readStateIndex:4628; appliedIndex:4627; }","duration":"190.541928ms","start":"2026-02-26T16:59:49.429721Z","end":"2026-02-26T16:59:49.620263Z","steps":["trace[2023611561] 'read index received' (duration: 190.325444ms)","trace[2023611561] 'applied index is now lower than readState.Index' (duration: 215.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:59:49.620374Z","caller":"traceutil/trace.go:171","msg":"trace[1473814991] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"193.869335ms","start":"2026-02-26T16:59:49.426472Z","end":"2026-02-26T16:59:49.620341Z","steps":["trace[1473814991] 'process raft request' (duration: 193.61179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:49.620479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.229782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T16:59:49.620524Z","caller":"traceutil/trace.go:171","msg":"trace[194734109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4453; }","duration":"148.321555ms","start":"2026-02-26T16:59:49.472191Z","end":"2026-02-26T16:59:49.620513Z","steps":["trace[194734109] 'agreement among raft nodes before linearized reading' (duration: 148.229633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:49.620608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.858304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-02-26T16:59:49.620689Z","caller":"traceutil/trace.go:171","msg":"trace[1585765150] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4453; }","duration":"190.952336ms","start":"2026-02-26T16:59:49.429717Z","end":"2026-02-26T16:59:49.620669Z","steps":["trace[1585765150] 'agreement among raft nodes before linearized reading' (duration: 190.695221ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.26363Z","caller":"traceutil/trace.go:171","msg":"trace[2135383572] transaction","detail":"{read_only:false; response_revision:4460; number_of_response:1; }","duration":"114.347896ms","start":"2026-02-26T16:59:50.149253Z","end":"2026-02-26T16:59:50.2636Z","steps":["trace[2135383572] 'process raft request' (duration: 110.713834ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.70655Z","caller":"traceutil/trace.go:171","msg":"trace[352657688] transaction","detail":"{read_only:false; response_revision:4510; number_of_response:1; }","duration":"154.493397ms","start":"2026-02-26T16:59:50.552026Z","end":"2026-02-26T16:59:50.706519Z","steps":["trace[352657688] 'process raft request' (duration: 124.693492ms)","trace[352657688] 'compare' (duration: 29.627022ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:59:50.706762Z","caller":"traceutil/trace.go:171","msg":"trace[286712883] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"154.66432ms","start":"2026-02-26T16:59:50.552063Z","end":"2026-02-26T16:59:50.706727Z","steps":["trace[286712883] 'process raft request' (duration: 154.400565ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.707017Z","caller":"traceutil/trace.go:171","msg":"trace[896598808] transaction","detail":"{read_only:false; response_revision:4512; number_of_response:1; }","duration":"154.594241ms","start":"2026-02-26T16:59:50.552404Z","end":"2026-02-26T16:59:50.706998Z","steps":["trace[896598808] 'process raft request' (duration: 154.276303ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.707062Z","caller":"traceutil/trace.go:171","msg":"trace[649191530] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"154.695492ms","start":"2026-02-26T16:59:50.55234Z","end":"2026-02-26T16:59:50.707036Z","steps":["trace[649191530] 'process raft request' (duration: 154.455327ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.707258Z","caller":"traceutil/trace.go:171","msg":"trace[1889332830] transaction","detail":"{read_only:false; response_revision:4514; number_of_response:1; }","duration":"154.557329ms","start":"2026-02-26T16:59:50.552681Z","end":"2026-02-26T16:59:50.707239Z","steps":["trace[1889332830] 'process raft request' (duration: 154.180961ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.707963Z","caller":"traceutil/trace.go:171","msg":"trace[1633745533] linearizableReadLoop","detail":"{readStateIndex:4689; appliedIndex:4684; }","duration":"155.289644ms","start":"2026-02-26T16:59:50.552659Z","end":"2026-02-26T16:59:50.707949Z","steps":["trace[1633745533] 'read index received' (duration: 29.793636ms)","trace[1633745533] 'applied index is now lower than readState.Index' (duration: 125.494838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T16:59:50.708142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.462508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/loki-gateway\" ","response":"range_response_count:1 size:4305"} {"level":"info","ts":"2026-02-26T16:59:50.708178Z","caller":"traceutil/trace.go:171","msg":"trace[93329230] range","detail":"{range_begin:/registry/deployments/monitoring/loki-gateway; range_end:; response_count:1; response_revision:4515; }","duration":"155.542349ms","start":"2026-02-26T16:59:50.552627Z","end":"2026-02-26T16:59:50.708169Z","steps":["trace[93329230] 'agreement among raft nodes before linearized reading' (duration: 155.373475ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.708335Z","caller":"traceutil/trace.go:171","msg":"trace[1566521584] transaction","detail":"{read_only:false; response_revision:4515; number_of_response:1; }","duration":"129.447347ms","start":"2026-02-26T16:59:50.578878Z","end":"2026-02-26T16:59:50.708325Z","steps":["trace[1566521584] 'process raft request' (duration: 128.241693ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.709603Z","caller":"traceutil/trace.go:171","msg":"trace[1623726268] transaction","detail":"{read_only:false; response_revision:4516; number_of_response:1; }","duration":"122.605689ms","start":"2026-02-26T16:59:50.586971Z","end":"2026-02-26T16:59:50.709576Z","steps":["trace[1623726268] 'process raft request' (duration: 122.412425ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.709636Z","caller":"traceutil/trace.go:171","msg":"trace[1260901429] transaction","detail":"{read_only:false; response_revision:4517; number_of_response:1; }","duration":"122.080399ms","start":"2026-02-26T16:59:50.587528Z","end":"2026-02-26T16:59:50.709608Z","steps":["trace[1260901429] 'process raft request' (duration: 121.996407ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.71011Z","caller":"traceutil/trace.go:171","msg":"trace[1777614042] transaction","detail":"{read_only:false; response_revision:4518; number_of_response:1; }","duration":"121.293815ms","start":"2026-02-26T16:59:50.588804Z","end":"2026-02-26T16:59:50.710098Z","steps":["trace[1777614042] 'process raft request' (duration: 120.767233ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:50.710349Z","caller":"traceutil/trace.go:171","msg":"trace[319529767] transaction","detail":"{read_only:false; response_revision:4519; number_of_response:1; }","duration":"121.188453ms","start":"2026-02-26T16:59:50.589142Z","end":"2026-02-26T16:59:50.710331Z","steps":["trace[319529767] 'process raft request' (duration: 120.928088ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:51.191602Z","caller":"traceutil/trace.go:171","msg":"trace[43809737] transaction","detail":"{read_only:false; response_revision:4521; number_of_response:1; }","duration":"476.750669ms","start":"2026-02-26T16:59:50.714817Z","end":"2026-02-26T16:59:51.191568Z","steps":["trace[43809737] 'process raft request' (duration: 397.462773ms)","trace[43809737] 'compare' (duration: 78.798655ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:59:51.191765Z","caller":"traceutil/trace.go:171","msg":"trace[1459855503] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"476.809289ms","start":"2026-02-26T16:59:50.714893Z","end":"2026-02-26T16:59:51.191703Z","steps":["trace[1459855503] 'process raft request' (duration: 476.536063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.192029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.71479Z","time spent":"476.980303ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":730,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T16:59:51.192393Z","caller":"traceutil/trace.go:171","msg":"trace[115437395] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"473.535183ms","start":"2026-02-26T16:59:50.718839Z","end":"2026-02-26T16:59:51.192374Z","steps":["trace[115437395] 'process raft request' (duration: 473.289879ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:51.192403Z","caller":"traceutil/trace.go:171","msg":"trace[2135647048] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"475.612025ms","start":"2026-02-26T16:59:50.716779Z","end":"2026-02-26T16:59:51.192391Z","steps":["trace[2135647048] 'process raft request' (duration: 475.297059ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:51.192445Z","caller":"traceutil/trace.go:171","msg":"trace[1886441598] linearizableReadLoop","detail":"{readStateIndex:4702; appliedIndex:4695; }","duration":"477.199277ms","start":"2026-02-26T16:59:50.715229Z","end":"2026-02-26T16:59:51.192428Z","steps":["trace[1886441598] 'read index received' (duration: 59.787996ms)","trace[1886441598] 'applied index is now lower than readState.Index' (duration: 417.408621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T16:59:51.192547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.718813Z","time spent":"473.637736ms","remote":"127.0.0.1:42578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4601,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T16:59:51.19257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.716769Z","time spent":"475.685987ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4189,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T16:59:51.192055Z","caller":"traceutil/trace.go:171","msg":"trace[189827310] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"477.106255ms","start":"2026-02-26T16:59:50.714913Z","end":"2026-02-26T16:59:51.19202Z","steps":["trace[189827310] 'process raft request' (duration: 476.670087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.192119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.714883Z","time spent":"477.050554ms","remote":"127.0.0.1:42586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3763,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T16:59:51.1927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.714904Z","time spent":"477.728777ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":732,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T16:59:51.19218Z","caller":"traceutil/trace.go:171","msg":"trace[76878748] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"476.960443ms","start":"2026-02-26T16:59:50.715169Z","end":"2026-02-26T16:59:51.192129Z","steps":["trace[76878748] 'process raft request' (duration: 476.797319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.193005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.715161Z","time spent":"477.801859ms","remote":"127.0.0.1:42462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1364,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T16:59:51.192177Z","caller":"traceutil/trace.go:171","msg":"trace[6502492] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"477.188957ms","start":"2026-02-26T16:59:50.714965Z","end":"2026-02-26T16:59:51.192154Z","steps":["trace[6502492] 'process raft request' (duration: 476.866811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.193274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.714959Z","time spent":"478.268148ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":739,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T16:59:51.192171Z","caller":"traceutil/trace.go:171","msg":"trace[668125788] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"476.962613ms","start":"2026-02-26T16:59:50.715177Z","end":"2026-02-26T16:59:51.19214Z","steps":["trace[668125788] 'process raft request' (duration: 476.851861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.193491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.71517Z","time spent":"478.282219ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4641,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T16:59:51.192303Z","caller":"traceutil/trace.go:171","msg":"trace[1406341276] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"352.065846ms","start":"2026-02-26T16:59:50.84021Z","end":"2026-02-26T16:59:51.192276Z","steps":["trace[1406341276] 'process raft request' (duration: 351.975605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.193577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.840168Z","time spent":"353.376041ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T16:59:51.193761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.783608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/loki-results-cache\" ","response":"range_response_count:1 size:3789"} {"level":"info","ts":"2026-02-26T16:59:51.193814Z","caller":"traceutil/trace.go:171","msg":"trace[330199811] range","detail":"{range_begin:/registry/statefulsets/monitoring/loki-results-cache; range_end:; response_count:1; response_revision:4530; }","duration":"476.88642ms","start":"2026-02-26T16:59:50.716913Z","end":"2026-02-26T16:59:51.193799Z","steps":["trace[330199811] 'agreement among raft nodes before linearized reading' (duration: 476.670616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.193848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.716884Z","time spent":"476.956931ms","remote":"127.0.0.1:42586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3813,"request content":"key:\"/registry/statefulsets/monitoring/loki-results-cache\" "} {"level":"warn","ts":"2026-02-26T16:59:51.193877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.946894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-zrbph.1897da78939a34a3\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-02-26T16:59:51.193938Z","caller":"traceutil/trace.go:171","msg":"trace[2081243056] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-zrbph.1897da78939a34a3; range_end:; response_count:1; response_revision:4530; }","duration":"378.066996ms","start":"2026-02-26T16:59:50.815856Z","end":"2026-02-26T16:59:51.193923Z","steps":["trace[2081243056] 'agreement among raft nodes before linearized reading' (duration: 377.853782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.193928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.668326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-94trb\" ","response":"range_response_count:1 size:3534"} {"level":"warn","ts":"2026-02-26T16:59:51.193978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.815823Z","time spent":"378.147567ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":986,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-zrbph.1897da78939a34a3\" "} {"level":"warn","ts":"2026-02-26T16:59:51.193905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.584399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"warn","ts":"2026-02-26T16:59:51.194024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.921704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-02-26T16:59:51.194108Z","caller":"traceutil/trace.go:171","msg":"trace[1914042193] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4530; }","duration":"304.067686ms","start":"2026-02-26T16:59:50.890026Z","end":"2026-02-26T16:59:51.194093Z","steps":["trace[1914042193] 'agreement among raft nodes before linearized reading' (duration: 303.672148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.194177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.889993Z","time spent":"304.174179ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":824,"request content":"key:\"/registry/serviceaccounts/monitoring/loki\" "} {"level":"warn","ts":"2026-02-26T16:59:51.194315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.832482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T16:59:51.194343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.826992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-02-26T16:59:51.194374Z","caller":"traceutil/trace.go:171","msg":"trace[1004662982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4530; }","duration":"272.921534ms","start":"2026-02-26T16:59:50.921437Z","end":"2026-02-26T16:59:51.194359Z","steps":["trace[1004662982] 'agreement among raft nodes before linearized reading' (duration: 272.7714ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:51.194397Z","caller":"traceutil/trace.go:171","msg":"trace[229803704] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4530; }","duration":"303.911634ms","start":"2026-02-26T16:59:50.890466Z","end":"2026-02-26T16:59:51.194378Z","steps":["trace[229803704] 'agreement among raft nodes before linearized reading' (duration: 303.74368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.194449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.890448Z","time spent":"303.991516ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":824,"request content":"key:\"/registry/serviceaccounts/monitoring/loki\" "} {"level":"info","ts":"2026-02-26T16:59:51.194314Z","caller":"traceutil/trace.go:171","msg":"trace[1657689890] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"133.585689ms","start":"2026-02-26T16:59:51.060598Z","end":"2026-02-26T16:59:51.194184Z","steps":["trace[1657689890] 'process raft request' (duration: 131.627651ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:51.194067Z","caller":"traceutil/trace.go:171","msg":"trace[1988246116] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4530; }","duration":"202.810272ms","start":"2026-02-26T16:59:50.991243Z","end":"2026-02-26T16:59:51.194054Z","steps":["trace[1988246116] 'agreement among raft nodes before linearized reading' (duration: 202.443175ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:51.194004Z","caller":"traceutil/trace.go:171","msg":"trace[272344118] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-94trb; range_end:; response_count:1; response_revision:4530; }","duration":"478.800179ms","start":"2026-02-26T16:59:50.715185Z","end":"2026-02-26T16:59:51.193985Z","steps":["trace[272344118] 'agreement among raft nodes before linearized reading' (duration: 477.32839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.194782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.715173Z","time spent":"479.595385ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":3558,"request content":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-94trb\" "} {"level":"warn","ts":"2026-02-26T16:59:51.196392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.095825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:718773"} {"level":"info","ts":"2026-02-26T16:59:51.196451Z","caller":"traceutil/trace.go:171","msg":"trace[656532040] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4530; }","duration":"410.223228ms","start":"2026-02-26T16:59:50.786211Z","end":"2026-02-26T16:59:51.196435Z","steps":["trace[656532040] 'agreement among raft nodes before linearized reading' (duration: 407.714468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:51.196508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T16:59:50.786179Z","time spent":"410.310519ms","remote":"127.0.0.1:60050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":718797,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-02-26T16:59:52.612912Z","caller":"traceutil/trace.go:171","msg":"trace[311323805] linearizableReadLoop","detail":"{readStateIndex:4750; appliedIndex:4749; }","duration":"139.972527ms","start":"2026-02-26T16:59:52.472915Z","end":"2026-02-26T16:59:52.612887Z","steps":["trace[311323805] 'read index received' (duration: 139.541949ms)","trace[311323805] 'applied index is now lower than readState.Index' (duration: 429.498µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:59:52.612997Z","caller":"traceutil/trace.go:171","msg":"trace[827911536] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"217.16934ms","start":"2026-02-26T16:59:52.395769Z","end":"2026-02-26T16:59:52.612939Z","steps":["trace[827911536] 'process raft request' (duration: 216.841984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:52.613196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.273254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T16:59:52.613277Z","caller":"traceutil/trace.go:171","msg":"trace[151666679] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:4573; }","duration":"140.423946ms","start":"2026-02-26T16:59:52.472836Z","end":"2026-02-26T16:59:52.613259Z","steps":["trace[151666679] 'agreement among raft nodes before linearized reading' (duration: 140.208992ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:54.208423Z","caller":"traceutil/trace.go:171","msg":"trace[1694782589] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"119.828164ms","start":"2026-02-26T16:59:54.088568Z","end":"2026-02-26T16:59:54.208396Z","steps":["trace[1694782589] 'process raft request' (duration: 119.651041ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:54.212495Z","caller":"traceutil/trace.go:171","msg":"trace[1923010101] transaction","detail":"{read_only:false; response_revision:4604; number_of_response:1; }","duration":"123.318404ms","start":"2026-02-26T16:59:54.089149Z","end":"2026-02-26T16:59:54.212468Z","steps":["trace[1923010101] 'process raft request' (duration: 123.151941ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:57.043998Z","caller":"traceutil/trace.go:171","msg":"trace[191325017] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"164.861875ms","start":"2026-02-26T16:59:56.879106Z","end":"2026-02-26T16:59:57.043968Z","steps":["trace[191325017] 'process raft request' (duration: 164.661091ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:57.148638Z","caller":"traceutil/trace.go:171","msg":"trace[450940121] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"261.592008ms","start":"2026-02-26T16:59:56.887014Z","end":"2026-02-26T16:59:57.148606Z","steps":["trace[450940121] 'process raft request' (duration: 250.258431ms)","trace[450940121] 'compare' (duration: 11.122852ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:59:57.148985Z","caller":"traceutil/trace.go:171","msg":"trace[1664376540] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4843; }","duration":"207.241032ms","start":"2026-02-26T16:59:56.941728Z","end":"2026-02-26T16:59:57.148969Z","steps":["trace[1664376540] 'read index received' (duration: 102.153662ms)","trace[1664376540] 'applied index is now lower than readState.Index' (duration: 105.08589ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T16:59:57.149128Z","caller":"traceutil/trace.go:171","msg":"trace[1748013859] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"233.824932ms","start":"2026-02-26T16:59:56.915268Z","end":"2026-02-26T16:59:57.149093Z","steps":["trace[1748013859] 'process raft request' (duration: 233.271021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T16:59:57.149245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.464636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-02-26T16:59:57.149296Z","caller":"traceutil/trace.go:171","msg":"trace[471640807] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4666; }","duration":"207.568578ms","start":"2026-02-26T16:59:56.941707Z","end":"2026-02-26T16:59:57.149276Z","steps":["trace[471640807] 'agreement among raft nodes before linearized reading' (duration: 207.311013ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:57.273348Z","caller":"traceutil/trace.go:171","msg":"trace[915242037] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"118.462748ms","start":"2026-02-26T16:59:57.154857Z","end":"2026-02-26T16:59:57.27332Z","steps":["trace[915242037] 'process raft request' (duration: 118.181642ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:57.278513Z","caller":"traceutil/trace.go:171","msg":"trace[1238074133] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"122.656411ms","start":"2026-02-26T16:59:57.155827Z","end":"2026-02-26T16:59:57.278483Z","steps":["trace[1238074133] 'process raft request' (duration: 122.268463ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:57.278612Z","caller":"traceutil/trace.go:171","msg":"trace[312503780] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"118.735203ms","start":"2026-02-26T16:59:57.159845Z","end":"2026-02-26T16:59:57.27858Z","steps":["trace[312503780] 'process raft request' (duration: 118.453917ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:57.278585Z","caller":"traceutil/trace.go:171","msg":"trace[1829195877] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"118.701782ms","start":"2026-02-26T16:59:57.159845Z","end":"2026-02-26T16:59:57.278547Z","steps":["trace[1829195877] 'process raft request' (duration: 118.365656ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:58.010219Z","caller":"traceutil/trace.go:171","msg":"trace[1024651587] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"113.006249ms","start":"2026-02-26T16:59:57.897175Z","end":"2026-02-26T16:59:58.010181Z","steps":["trace[1024651587] 'process raft request' (duration: 112.344395ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T16:59:58.04606Z","caller":"traceutil/trace.go:171","msg":"trace[1715830682] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"148.038178ms","start":"2026-02-26T16:59:57.898004Z","end":"2026-02-26T16:59:58.046042Z","steps":["trace[1715830682] 'process raft request' (duration: 147.479037ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:00:36.725984Z","caller":"traceutil/trace.go:171","msg":"trace[1486812595] linearizableReadLoop","detail":"{readStateIndex:5353; appliedIndex:5352; }","duration":"418.433287ms","start":"2026-02-26T17:00:36.307519Z","end":"2026-02-26T17:00:36.725952Z","steps":["trace[1486812595] 'read index received' (duration: 418.141801ms)","trace[1486812595] 'applied index is now lower than readState.Index' (duration: 289.906µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:00:36.72609Z","caller":"traceutil/trace.go:171","msg":"trace[1980238526] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"485.342264ms","start":"2026-02-26T17:00:36.240703Z","end":"2026-02-26T17:00:36.726045Z","steps":["trace[1980238526] 'process raft request' (duration: 485.006847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:00:36.726286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.77173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:00:36.726326Z","caller":"traceutil/trace.go:171","msg":"trace[1617749111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5158; }","duration":"253.929333ms","start":"2026-02-26T17:00:36.472388Z","end":"2026-02-26T17:00:36.726317Z","steps":["trace[1617749111] 'agreement among raft nodes before linearized reading' (duration: 253.76137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:00:36.726372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:00:36.240674Z","time spent":"485.538067ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T17:00:36.726502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.948207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-02-26T17:00:36.726547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.96112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T17:00:36.726617Z","caller":"traceutil/trace.go:171","msg":"trace[313276910] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5158; }","duration":"419.150032ms","start":"2026-02-26T17:00:36.307434Z","end":"2026-02-26T17:00:36.726584Z","steps":["trace[313276910] 'agreement among raft nodes before linearized reading' (duration: 418.712103ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:00:36.726672Z","caller":"traceutil/trace.go:171","msg":"trace[1449115003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5158; }","duration":"148.164894ms","start":"2026-02-26T17:00:36.578479Z","end":"2026-02-26T17:00:36.726644Z","steps":["trace[1449115003] 'agreement among raft nodes before linearized reading' (duration: 147.723125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:00:36.726705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.763931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pz5st\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-02-26T17:00:36.726671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:00:36.307408Z","time spent":"419.252193ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-02-26T17:00:36.726812Z","caller":"traceutil/trace.go:171","msg":"trace[551126816] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pz5st; range_end:; response_count:1; response_revision:5158; }","duration":"120.912095ms","start":"2026-02-26T17:00:36.605874Z","end":"2026-02-26T17:00:36.726786Z","steps":["trace[551126816] 'agreement among raft nodes before linearized reading' (duration: 120.602989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:00:36.727044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.253822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T17:00:36.727084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.701677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T17:00:36.727144Z","caller":"traceutil/trace.go:171","msg":"trace[611248716] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5158; }","duration":"190.438675ms","start":"2026-02-26T17:00:36.536685Z","end":"2026-02-26T17:00:36.727124Z","steps":["trace[611248716] 'agreement among raft nodes before linearized reading' (duration: 189.578378ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:00:36.727401Z","caller":"traceutil/trace.go:171","msg":"trace[1926484661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5158; }","duration":"239.962921ms","start":"2026-02-26T17:00:36.48731Z","end":"2026-02-26T17:00:36.727273Z","steps":["trace[1926484661] 'agreement among raft nodes before linearized reading' (duration: 239.021313ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:01:49.625415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2772} {"level":"info","ts":"2026-02-26T17:01:49.685094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2772,"took":"58.444975ms","hash":2764679612,"current-db-size-bytes":40677376,"current-db-size":"41 MB","current-db-size-in-use-bytes":30457856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-26T17:01:49.685177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2764679612,"revision":2772,"compact-revision":-1} {"level":"warn","ts":"2026-02-26T17:05:42.848755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.413564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:05:42.848889Z","caller":"traceutil/trace.go:171","msg":"trace[133941589] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7244; }","duration":"187.576438ms","start":"2026-02-26T17:05:42.66126Z","end":"2026-02-26T17:05:42.848837Z","steps":["trace[133941589] 'range keys from in-memory index tree' (duration: 187.325853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:05:42.848863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.870697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12713"} {"level":"info","ts":"2026-02-26T17:05:42.848974Z","caller":"traceutil/trace.go:171","msg":"trace[175887732] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7244; }","duration":"178.02574ms","start":"2026-02-26T17:05:42.670928Z","end":"2026-02-26T17:05:42.848954Z","steps":["trace[175887732] 'range keys from in-memory index tree' (duration: 177.698204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:05:42.849326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.981942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T17:05:42.849404Z","caller":"traceutil/trace.go:171","msg":"trace[1130583528] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7244; }","duration":"149.095645ms","start":"2026-02-26T17:05:42.700289Z","end":"2026-02-26T17:05:42.849385Z","steps":["trace[1130583528] 'range keys from in-memory index tree' (duration: 148.643226ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:05:42.849696Z","caller":"traceutil/trace.go:171","msg":"trace[1053581445] transaction","detail":"{read_only:false; response_revision:7245; number_of_response:1; }","duration":"149.257838ms","start":"2026-02-26T17:05:42.700429Z","end":"2026-02-26T17:05:42.849687Z","steps":["trace[1053581445] 'process raft request' (duration: 104.82763ms)","trace[1053581445] 'compare' (duration: 43.984859ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:05:42.921197Z","caller":"traceutil/trace.go:171","msg":"trace[603008159] transaction","detail":"{read_only:false; response_revision:7246; number_of_response:1; }","duration":"174.729046ms","start":"2026-02-26T17:05:42.746439Z","end":"2026-02-26T17:05:42.921168Z","steps":["trace[603008159] 'process raft request' (duration: 174.514632ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:06:35.347672Z","caller":"traceutil/trace.go:171","msg":"trace[218157307] linearizableReadLoop","detail":"{readStateIndex:7978; appliedIndex:7977; }","duration":"134.313576ms","start":"2026-02-26T17:06:35.213334Z","end":"2026-02-26T17:06:35.347647Z","steps":["trace[218157307] 'read index received' (duration: 134.116383ms)","trace[218157307] 'applied index is now lower than readState.Index' (duration: 196.433µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:06:35.347736Z","caller":"traceutil/trace.go:171","msg":"trace[1840144567] transaction","detail":"{read_only:false; response_revision:7700; number_of_response:1; }","duration":"149.390631ms","start":"2026-02-26T17:06:35.198311Z","end":"2026-02-26T17:06:35.347701Z","steps":["trace[1840144567] 'process raft request' (duration: 149.171667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:06:35.347885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.53344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T17:06:35.347927Z","caller":"traceutil/trace.go:171","msg":"trace[58759226] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7700; }","duration":"134.633793ms","start":"2026-02-26T17:06:35.213283Z","end":"2026-02-26T17:06:35.347917Z","steps":["trace[58759226] 'agreement among raft nodes before linearized reading' (duration: 134.470739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:06:37.053922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.784115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pz5st\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T17:06:37.054021Z","caller":"traceutil/trace.go:171","msg":"trace[2062961463] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pz5st; range_end:; response_count:1; response_revision:7706; }","duration":"137.937388ms","start":"2026-02-26T17:06:36.916063Z","end":"2026-02-26T17:06:37.054001Z","steps":["trace[2062961463] 'range keys from in-memory index tree' (duration: 137.554849ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:06:37.718961Z","caller":"traceutil/trace.go:171","msg":"trace[312926298] transaction","detail":"{read_only:false; response_revision:7712; number_of_response:1; }","duration":"104.152516ms","start":"2026-02-26T17:06:37.614783Z","end":"2026-02-26T17:06:37.718935Z","steps":["trace[312926298] 'process raft request' (duration: 104.020473ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:06:37.718981Z","caller":"traceutil/trace.go:171","msg":"trace[936286955] linearizableReadLoop","detail":"{readStateIndex:7990; appliedIndex:7989; }","duration":"108.95517ms","start":"2026-02-26T17:06:37.609981Z","end":"2026-02-26T17:06:37.718936Z","steps":["trace[936286955] 'read index received' (duration: 11.320501ms)","trace[936286955] 'applied index is now lower than readState.Index' (duration: 97.627709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T17:06:37.719269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.752041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T17:06:37.719345Z","caller":"traceutil/trace.go:171","msg":"trace[2043832815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7712; }","duration":"164.874324ms","start":"2026-02-26T17:06:37.554453Z","end":"2026-02-26T17:06:37.719328Z","steps":["trace[2043832815] 'agreement among raft nodes before linearized reading' (duration: 164.602479ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:06:38.299106Z","caller":"traceutil/trace.go:171","msg":"trace[957786986] transaction","detail":"{read_only:false; response_revision:7714; number_of_response:1; }","duration":"230.969926ms","start":"2026-02-26T17:06:38.068107Z","end":"2026-02-26T17:06:38.299077Z","steps":["trace[957786986] 'process raft request' (duration: 230.750422ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:06:49.632231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5687} {"level":"info","ts":"2026-02-26T17:06:49.721079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5687,"took":"86.78718ms","hash":4262234508,"current-db-size-bytes":42573824,"current-db-size":"43 MB","current-db-size-in-use-bytes":23248896,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T17:06:49.721143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4262234508,"revision":5687,"compact-revision":2772} {"level":"info","ts":"2026-02-26T17:07:05.382499Z","caller":"traceutil/trace.go:171","msg":"trace[609986102] transaction","detail":"{read_only:false; response_revision:7898; number_of_response:1; }","duration":"319.168875ms","start":"2026-02-26T17:07:05.063294Z","end":"2026-02-26T17:07:05.382462Z","steps":["trace[609986102] 'process raft request' (duration: 318.861509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:05.382732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:05.06326Z","time spent":"319.348018ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T17:07:05.778267Z","caller":"traceutil/trace.go:171","msg":"trace[1786399526] linearizableReadLoop","detail":"{readStateIndex:8188; appliedIndex:8185; }","duration":"489.784753ms","start":"2026-02-26T17:07:05.288436Z","end":"2026-02-26T17:07:05.77822Z","steps":["trace[1786399526] 'read index received' (duration: 93.721369ms)","trace[1786399526] 'applied index is now lower than readState.Index' (duration: 396.062354ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:07:05.778534Z","caller":"traceutil/trace.go:171","msg":"trace[338267116] transaction","detail":"{read_only:false; response_revision:7899; number_of_response:1; }","duration":"582.410721ms","start":"2026-02-26T17:07:05.196111Z","end":"2026-02-26T17:07:05.778522Z","steps":["trace[338267116] 'process raft request' (duration: 581.934412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:05.778629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:05.196077Z","time spent":"582.483812ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T17:07:05.778797Z","caller":"traceutil/trace.go:171","msg":"trace[1984186782] transaction","detail":"{read_only:false; response_revision:7900; number_of_response:1; }","duration":"497.855407ms","start":"2026-02-26T17:07:05.280932Z","end":"2026-02-26T17:07:05.778787Z","steps":["trace[1984186782] 'process raft request' (duration: 497.248014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:05.778845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:05.280888Z","time spent":"497.929099ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T17:07:05.779002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.564889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pz5st\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T17:07:05.779034Z","caller":"traceutil/trace.go:171","msg":"trace[836954538] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pz5st; range_end:; response_count:1; response_revision:7900; }","duration":"490.637701ms","start":"2026-02-26T17:07:05.288385Z","end":"2026-02-26T17:07:05.779023Z","steps":["trace[836954538] 'agreement among raft nodes before linearized reading' (duration: 490.57516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:05.779059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:05.288362Z","time spent":"490.691672ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-pz5st\" "} {"level":"warn","ts":"2026-02-26T17:07:05.77973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.63173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T17:07:05.779807Z","caller":"traceutil/trace.go:171","msg":"trace[1828598482] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7901; }","duration":"169.770063ms","start":"2026-02-26T17:07:05.610016Z","end":"2026-02-26T17:07:05.779786Z","steps":["trace[1828598482] 'agreement among raft nodes before linearized reading' (duration: 169.528998ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:05.779882Z","caller":"traceutil/trace.go:171","msg":"trace[1279488399] transaction","detail":"{read_only:false; response_revision:7901; number_of_response:1; }","duration":"295.566049ms","start":"2026-02-26T17:07:05.484296Z","end":"2026-02-26T17:07:05.779862Z","steps":["trace[1279488399] 'process raft request' (duration: 295.125921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:05.780229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.777505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T17:07:05.780288Z","caller":"traceutil/trace.go:171","msg":"trace[327726689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7901; }","duration":"123.861267ms","start":"2026-02-26T17:07:05.656418Z","end":"2026-02-26T17:07:05.780279Z","steps":["trace[327726689] 'agreement among raft nodes before linearized reading' (duration: 123.688093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:17.863228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.075889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T17:07:17.863573Z","caller":"traceutil/trace.go:171","msg":"trace[39790204] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7996; }","duration":"199.305858ms","start":"2026-02-26T17:07:17.664249Z","end":"2026-02-26T17:07:17.863554Z","steps":["trace[39790204] 'process raft request' (duration: 25.76919ms)","trace[39790204] 'compare' (duration: 172.539168ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:07:17.868597Z","caller":"traceutil/trace.go:171","msg":"trace[740283180] transaction","detail":"{read_only:false; response_revision:7998; number_of_response:1; }","duration":"202.937131ms","start":"2026-02-26T17:07:17.665591Z","end":"2026-02-26T17:07:17.868528Z","steps":["trace[740283180] 'process raft request' (duration: 202.862119ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:17.869187Z","caller":"traceutil/trace.go:171","msg":"trace[1910703275] transaction","detail":"{read_only:false; response_revision:7997; number_of_response:1; }","duration":"204.716917ms","start":"2026-02-26T17:07:17.664454Z","end":"2026-02-26T17:07:17.86917Z","steps":["trace[1910703275] 'process raft request' (duration: 203.789869ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:17.949601Z","caller":"traceutil/trace.go:171","msg":"trace[2121135701] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"123.368027ms","start":"2026-02-26T17:07:17.826198Z","end":"2026-02-26T17:07:17.949566Z","steps":["trace[2121135701] 'process raft request' (duration: 123.075401ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:17.94989Z","caller":"traceutil/trace.go:171","msg":"trace[1550093726] transaction","detail":"{read_only:false; response_revision:8000; number_of_response:1; }","duration":"122.013109ms","start":"2026-02-26T17:07:17.827858Z","end":"2026-02-26T17:07:17.949871Z","steps":["trace[1550093726] 'process raft request' (duration: 121.517219ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:17.949895Z","caller":"traceutil/trace.go:171","msg":"trace[370327017] linearizableReadLoop","detail":"{readStateIndex:8294; appliedIndex:8292; }","duration":"118.427016ms","start":"2026-02-26T17:07:17.831455Z","end":"2026-02-26T17:07:17.949882Z","steps":["trace[370327017] 'read index received' (duration: 37.956385ms)","trace[370327017] 'applied index is now lower than readState.Index' (duration: 80.436331ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T17:07:17.950074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.57451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T17:07:17.95016Z","caller":"traceutil/trace.go:171","msg":"trace[600429859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8000; }","duration":"118.673402ms","start":"2026-02-26T17:07:17.831428Z","end":"2026-02-26T17:07:17.950102Z","steps":["trace[600429859] 'agreement among raft nodes before linearized reading' (duration: 118.497558ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:18.891485Z","caller":"traceutil/trace.go:171","msg":"trace[1850816300] transaction","detail":"{read_only:false; response_revision:8006; number_of_response:1; }","duration":"311.859907ms","start":"2026-02-26T17:07:18.579586Z","end":"2026-02-26T17:07:18.891446Z","steps":["trace[1850816300] 'process raft request' (duration: 311.614402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:18.891779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:18.579562Z","time spent":"312.028551ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T17:07:19.075986Z","caller":"traceutil/trace.go:171","msg":"trace[1166694978] linearizableReadLoop","detail":"{readStateIndex:8301; appliedIndex:8299; }","duration":"443.884168ms","start":"2026-02-26T17:07:18.632067Z","end":"2026-02-26T17:07:19.075951Z","steps":["trace[1166694978] 'read index received' (duration: 259.190125ms)","trace[1166694978] 'applied index is now lower than readState.Index' (duration: 184.692493ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:07:19.076293Z","caller":"traceutil/trace.go:171","msg":"trace[1509929836] transaction","detail":"{read_only:false; response_revision:8007; number_of_response:1; }","duration":"478.905225ms","start":"2026-02-26T17:07:18.597217Z","end":"2026-02-26T17:07:19.076122Z","steps":["trace[1509929836] 'process raft request' (duration: 384.841609ms)","trace[1509929836] 'compare' (duration: 93.575796ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T17:07:19.076535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:18.59718Z","time spent":"479.204221ms","remote":"127.0.0.1:42362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T17:07:19.076698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.664122ms","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-02-26T17:07:19.076796Z","caller":"traceutil/trace.go:171","msg":"trace[510573995] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8007; }","duration":"373.796416ms","start":"2026-02-26T17:07:18.702971Z","end":"2026-02-26T17:07:19.076768Z","steps":["trace[510573995] 'agreement among raft nodes before linearized reading' (duration: 373.598811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:19.076796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.036677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-02-26T17:07:19.076859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:18.70296Z","time spent":"373.887778ms","remote":"127.0.0.1:42576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-26T17:07:19.076921Z","caller":"traceutil/trace.go:171","msg":"trace[1579934884] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8007; }","duration":"165.225641ms","start":"2026-02-26T17:07:18.911664Z","end":"2026-02-26T17:07:19.07689Z","steps":["trace[1579934884] 'agreement among raft nodes before linearized reading' (duration: 164.850192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:19.077273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.359609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-02-26T17:07:19.077518Z","caller":"traceutil/trace.go:171","msg":"trace[1387799034] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8007; }","duration":"382.617334ms","start":"2026-02-26T17:07:18.69488Z","end":"2026-02-26T17:07:19.077498Z","steps":["trace[1387799034] 'agreement among raft nodes before linearized reading' (duration: 381.605033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:19.07761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.43987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T17:07:19.077074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.051399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:07:19.077728Z","caller":"traceutil/trace.go:171","msg":"trace[1163615045] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8007; }","duration":"445.681045ms","start":"2026-02-26T17:07:18.632017Z","end":"2026-02-26T17:07:19.077698Z","steps":["trace[1163615045] 'agreement among raft nodes before linearized reading' (duration: 444.376498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:19.077807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:18.631997Z","time spent":"445.792877ms","remote":"127.0.0.1:42578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-02-26T17:07:19.077822Z","caller":"traceutil/trace.go:171","msg":"trace[1320217975] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8007; }","duration":"299.870475ms","start":"2026-02-26T17:07:18.777899Z","end":"2026-02-26T17:07:19.077769Z","steps":["trace[1320217975] 'agreement among raft nodes before linearized reading' (duration: 298.66486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:19.077655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:07:18.694865Z","time spent":"382.774898ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5170,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-02-26T17:07:20.198676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.144737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T17:07:20.199053Z","caller":"traceutil/trace.go:171","msg":"trace[1837364231] transaction","detail":"{read_only:false; response_revision:8016; number_of_response:1; }","duration":"241.897726ms","start":"2026-02-26T17:07:19.957107Z","end":"2026-02-26T17:07:20.199005Z","steps":["trace[1837364231] 'process raft request' (duration: 92.331361ms)","trace[1837364231] 'compare' (duration: 149.016744ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:07:20.19916Z","caller":"traceutil/trace.go:171","msg":"trace[1365847361] linearizableReadLoop","detail":"{readStateIndex:8311; appliedIndex:8308; }","duration":"235.139951ms","start":"2026-02-26T17:07:19.963996Z","end":"2026-02-26T17:07:20.199136Z","steps":["trace[1365847361] 'read index received' (duration: 7.255017ms)","trace[1365847361] 'applied index is now lower than readState.Index' (duration: 227.883424ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:07:20.199206Z","caller":"traceutil/trace.go:171","msg":"trace[995146857] transaction","detail":"{read_only:false; response_revision:8017; number_of_response:1; }","duration":"240.680762ms","start":"2026-02-26T17:07:19.958484Z","end":"2026-02-26T17:07:20.199165Z","steps":["trace[995146857] 'process raft request' (duration: 240.338326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:20.199336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.321984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pz5st\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T17:07:20.199406Z","caller":"traceutil/trace.go:171","msg":"trace[29675666] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pz5st; range_end:; response_count:1; response_revision:8017; }","duration":"235.431306ms","start":"2026-02-26T17:07:19.963957Z","end":"2026-02-26T17:07:20.199388Z","steps":["trace[29675666] 'agreement among raft nodes before linearized reading' (duration: 235.251672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:20.199434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.282267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T17:07:20.199487Z","caller":"traceutil/trace.go:171","msg":"trace[2076444811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8017; }","duration":"203.38914ms","start":"2026-02-26T17:07:19.996086Z","end":"2026-02-26T17:07:20.199475Z","steps":["trace[2076444811] 'agreement among raft nodes before linearized reading' (duration: 203.196296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:20.199474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.130945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-02-26T17:07:20.199504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.119205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T17:07:20.199551Z","caller":"traceutil/trace.go:171","msg":"trace[998598824] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8017; }","duration":"202.271128ms","start":"2026-02-26T17:07:19.99726Z","end":"2026-02-26T17:07:20.199531Z","steps":["trace[998598824] 'agreement among raft nodes before linearized reading' (duration: 202.041943ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:20.199605Z","caller":"traceutil/trace.go:171","msg":"trace[1706112242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8017; }","duration":"202.169756ms","start":"2026-02-26T17:07:19.997369Z","end":"2026-02-26T17:07:20.199539Z","steps":["trace[1706112242] 'agreement among raft nodes before linearized reading' (duration: 202.034683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:20.199547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.299388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T17:07:20.199768Z","caller":"traceutil/trace.go:171","msg":"trace[181100302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8017; }","duration":"202.577844ms","start":"2026-02-26T17:07:19.997166Z","end":"2026-02-26T17:07:20.199744Z","steps":["trace[181100302] 'agreement among raft nodes before linearized reading' (duration: 202.186936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:07:20.200025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.679456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T17:07:20.200871Z","caller":"traceutil/trace.go:171","msg":"trace[1303876791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8017; }","duration":"203.594805ms","start":"2026-02-26T17:07:19.997257Z","end":"2026-02-26T17:07:20.200852Z","steps":["trace[1303876791] 'agreement among raft nodes before linearized reading' (duration: 202.091244ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:32.572188Z","caller":"traceutil/trace.go:171","msg":"trace[195177195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8117; }","duration":"100.006657ms","start":"2026-02-26T17:07:32.472142Z","end":"2026-02-26T17:07:32.572149Z","steps":["trace[195177195] 'range keys from in-memory index tree' (duration: 99.908694ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:07:34.156602Z","caller":"traceutil/trace.go:171","msg":"trace[391342958] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"104.923696ms","start":"2026-02-26T17:07:34.051658Z","end":"2026-02-26T17:07:34.156582Z","steps":["trace[391342958] 'process raft request' (duration: 104.777713ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:17.963715Z","caller":"traceutil/trace.go:171","msg":"trace[2118411281] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"120.868417ms","start":"2026-02-26T17:08:17.842822Z","end":"2026-02-26T17:08:17.963691Z","steps":["trace[2118411281] 'process raft request' (duration: 120.582771ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.157198Z","caller":"traceutil/trace.go:171","msg":"trace[782228263] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"128.832307ms","start":"2026-02-26T17:08:18.028341Z","end":"2026-02-26T17:08:18.157174Z","steps":["trace[782228263] 'process raft request' (duration: 122.633962ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.47606Z","caller":"traceutil/trace.go:171","msg":"trace[1540234831] linearizableReadLoop","detail":"{readStateIndex:8919; appliedIndex:8919; }","duration":"177.832505ms","start":"2026-02-26T17:08:18.298165Z","end":"2026-02-26T17:08:18.475997Z","steps":["trace[1540234831] 'read index received' (duration: 177.818984ms)","trace[1540234831] 'applied index is now lower than readState.Index' (duration: 11.061µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:08:18.476031Z","caller":"traceutil/trace.go:171","msg":"trace[1273890486] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"287.23448ms","start":"2026-02-26T17:08:18.188759Z","end":"2026-02-26T17:08:18.475993Z","steps":["trace[1273890486] 'process raft request' (duration: 287.074596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:08:18.476297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.10165ms","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-02-26T17:08:18.476368Z","caller":"traceutil/trace.go:171","msg":"trace[1620962124] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8609; }","duration":"178.220963ms","start":"2026-02-26T17:08:18.298127Z","end":"2026-02-26T17:08:18.476348Z","steps":["trace[1620962124] 'agreement among raft nodes before linearized reading' (duration: 178.06978ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.672193Z","caller":"traceutil/trace.go:171","msg":"trace[705810661] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"163.956665ms","start":"2026-02-26T17:08:18.508178Z","end":"2026-02-26T17:08:18.672135Z","steps":["trace[705810661] 'process raft request' (duration: 163.757601ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.779323Z","caller":"traceutil/trace.go:171","msg":"trace[446984939] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"100.74339ms","start":"2026-02-26T17:08:18.678553Z","end":"2026-02-26T17:08:18.779297Z","steps":["trace[446984939] 'process raft request' (duration: 100.568457ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.779647Z","caller":"traceutil/trace.go:171","msg":"trace[942723575] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"100.768741ms","start":"2026-02-26T17:08:18.678852Z","end":"2026-02-26T17:08:18.779621Z","steps":["trace[942723575] 'process raft request' (duration: 100.392663ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.779971Z","caller":"traceutil/trace.go:171","msg":"trace[541233878] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"100.6682ms","start":"2026-02-26T17:08:18.679278Z","end":"2026-02-26T17:08:18.779946Z","steps":["trace[541233878] 'process raft request' (duration: 100.240451ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.780023Z","caller":"traceutil/trace.go:171","msg":"trace[965298880] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"100.649229ms","start":"2026-02-26T17:08:18.679347Z","end":"2026-02-26T17:08:18.779996Z","steps":["trace[965298880] 'process raft request' (duration: 100.431135ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:08:18.780133Z","caller":"traceutil/trace.go:171","msg":"trace[1740563599] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"100.328053ms","start":"2026-02-26T17:08:18.679782Z","end":"2026-02-26T17:08:18.78011Z","steps":["trace[1740563599] 'process raft request' (duration: 100.129459ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:10:13.190437Z","caller":"traceutil/trace.go:171","msg":"trace[762630429] transaction","detail":"{read_only:false; response_revision:9397; number_of_response:1; }","duration":"113.353892ms","start":"2026-02-26T17:10:13.077053Z","end":"2026-02-26T17:10:13.190407Z","steps":["trace[762630429] 'process raft request' (duration: 113.142028ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:10:42.785924Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T17:10:42.790351Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T17:10:42.79042Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T17:11:49.645752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7774} {"level":"info","ts":"2026-02-26T17:11:49.720424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7774,"took":"72.588275ms","hash":361391487,"current-db-size-bytes":42573824,"current-db-size":"43 MB","current-db-size-in-use-bytes":28229632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T17:11:49.720494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":361391487,"revision":7774,"compact-revision":5687} {"level":"info","ts":"2026-02-26T17:11:55.420897Z","caller":"traceutil/trace.go:171","msg":"trace[1405518375] transaction","detail":"{read_only:false; response_revision:10337; number_of_response:1; }","duration":"126.047541ms","start":"2026-02-26T17:11:55.294821Z","end":"2026-02-26T17:11:55.420868Z","steps":["trace[1405518375] 'process raft request' (duration: 125.936969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:11:55.64255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.715049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:11:55.642819Z","caller":"traceutil/trace.go:171","msg":"trace[750259918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10337; }","duration":"170.004105ms","start":"2026-02-26T17:11:55.472788Z","end":"2026-02-26T17:11:55.642792Z","steps":["trace[750259918] 'range keys from in-memory index tree' (duration: 169.576767ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:16:49.652293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10310} {"level":"info","ts":"2026-02-26T17:16:49.736445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10310,"took":"81.538608ms","hash":3880569121,"current-db-size-bytes":42573824,"current-db-size":"43 MB","current-db-size-in-use-bytes":24760320,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-02-26T17:16:49.73652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3880569121,"revision":10310,"compact-revision":7774} {"level":"info","ts":"2026-02-26T17:17:31.740782Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000325e.wal"} {"level":"info","ts":"2026-02-26T17:17:39.973133Z","caller":"traceutil/trace.go:171","msg":"trace[1131929342] transaction","detail":"{read_only:false; response_revision:12527; number_of_response:1; }","duration":"114.634334ms","start":"2026-02-26T17:17:39.858448Z","end":"2026-02-26T17:17:39.973083Z","steps":["trace[1131929342] 'process raft request' (duration: 114.295677ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:01.348465Z","caller":"traceutil/trace.go:171","msg":"trace[279467228] transaction","detail":"{read_only:false; response_revision:12630; number_of_response:1; }","duration":"549.014606ms","start":"2026-02-26T17:18:00.799423Z","end":"2026-02-26T17:18:01.348437Z","steps":["trace[279467228] 'process raft request' (duration: 548.836022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:01.348676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:00.799397Z","time spent":"549.17795ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T17:18:01.440965Z","caller":"traceutil/trace.go:171","msg":"trace[525044606] linearizableReadLoop","detail":"{readStateIndex:13083; appliedIndex:13081; }","duration":"444.191254ms","start":"2026-02-26T17:18:00.996742Z","end":"2026-02-26T17:18:01.440933Z","steps":["trace[525044606] 'read index received' (duration: 351.514568ms)","trace[525044606] 'applied index is now lower than readState.Index' (duration: 92.675416ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:18:01.441059Z","caller":"traceutil/trace.go:171","msg":"trace[414327207] transaction","detail":"{read_only:false; response_revision:12631; number_of_response:1; }","duration":"620.935118ms","start":"2026-02-26T17:18:00.820076Z","end":"2026-02-26T17:18:01.441011Z","steps":["trace[414327207] 'process raft request' (duration: 619.851966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:01.441194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.450515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pz5st\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-02-26T17:18:01.441242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:00.820031Z","time spent":"621.104571ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T17:18:01.44123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.184337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:18:01.441322Z","caller":"traceutil/trace.go:171","msg":"trace[2105343873] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:12631; }","duration":"128.33327ms","start":"2026-02-26T17:18:01.312972Z","end":"2026-02-26T17:18:01.441305Z","steps":["trace[2105343873] 'agreement among raft nodes before linearized reading' (duration: 128.182647ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:01.441258Z","caller":"traceutil/trace.go:171","msg":"trace[1889094613] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pz5st; range_end:; response_count:1; response_revision:12631; }","duration":"182.565347ms","start":"2026-02-26T17:18:01.258675Z","end":"2026-02-26T17:18:01.441241Z","steps":["trace[1889094613] 'agreement among raft nodes before linearized reading' (duration: 182.433365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:01.44141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.652664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T17:18:01.441653Z","caller":"traceutil/trace.go:171","msg":"trace[790553402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12631; }","duration":"444.787246ms","start":"2026-02-26T17:18:00.996709Z","end":"2026-02-26T17:18:01.441496Z","steps":["trace[790553402] 'agreement among raft nodes before linearized reading' (duration: 444.387518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:01.441754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:00.996694Z","time spent":"445.03995ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T17:18:01.576362Z","caller":"traceutil/trace.go:171","msg":"trace[249042286] linearizableReadLoop","detail":"{readStateIndex:13084; appliedIndex:13083; }","duration":"103.819673ms","start":"2026-02-26T17:18:01.472516Z","end":"2026-02-26T17:18:01.576336Z","steps":["trace[249042286] 'read index received' (duration: 70.775909ms)","trace[249042286] 'applied index is now lower than readState.Index' (duration: 33.042614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T17:18:01.576503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.961825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:18:01.576499Z","caller":"traceutil/trace.go:171","msg":"trace[581308891] transaction","detail":"{read_only:false; response_revision:12632; number_of_response:1; }","duration":"130.192307ms","start":"2026-02-26T17:18:01.446266Z","end":"2026-02-26T17:18:01.576458Z","steps":["trace[581308891] 'process raft request' (duration: 97.099512ms)","trace[581308891] 'compare' (duration: 32.8387ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:18:01.576534Z","caller":"traceutil/trace.go:171","msg":"trace[464217731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12632; }","duration":"104.036557ms","start":"2026-02-26T17:18:01.472488Z","end":"2026-02-26T17:18:01.576524Z","steps":["trace[464217731] 'agreement among raft nodes before linearized reading' (duration: 103.939845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:09.707116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.764669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T17:18:09.707205Z","caller":"traceutil/trace.go:171","msg":"trace[1108915359] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:12685; }","duration":"109.939442ms","start":"2026-02-26T17:18:09.597244Z","end":"2026-02-26T17:18:09.707184Z","steps":["trace[1108915359] 'count revisions from in-memory index tree' (duration: 109.609275ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:12.15444Z","caller":"traceutil/trace.go:171","msg":"trace[1677583190] transaction","detail":"{read_only:false; response_revision:12697; number_of_response:1; }","duration":"249.595663ms","start":"2026-02-26T17:18:11.904813Z","end":"2026-02-26T17:18:12.154408Z","steps":["trace[1677583190] 'process raft request' (duration: 249.364109ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:12.321462Z","caller":"traceutil/trace.go:171","msg":"trace[2090388120] linearizableReadLoop","detail":"{readStateIndex:13156; appliedIndex:13152; }","duration":"114.688455ms","start":"2026-02-26T17:18:12.206704Z","end":"2026-02-26T17:18:12.321393Z","steps":["trace[2090388120] 'read index received' (duration: 18.958939ms)","trace[2090388120] 'applied index is now lower than readState.Index' (duration: 95.728566ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:18:12.321618Z","caller":"traceutil/trace.go:171","msg":"trace[1571455693] transaction","detail":"{read_only:false; response_revision:12699; number_of_response:1; }","duration":"160.370085ms","start":"2026-02-26T17:18:12.16121Z","end":"2026-02-26T17:18:12.32158Z","steps":["trace[1571455693] 'process raft request' (duration: 127.985674ms)","trace[1571455693] 'compare' (duration: 31.989273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T17:18:12.321707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.98996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T17:18:12.321748Z","caller":"traceutil/trace.go:171","msg":"trace[674241912] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12701; }","duration":"115.111203ms","start":"2026-02-26T17:18:12.206627Z","end":"2026-02-26T17:18:12.321738Z","steps":["trace[674241912] 'agreement among raft nodes before linearized reading' (duration: 114.909589ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:12.321975Z","caller":"traceutil/trace.go:171","msg":"trace[2103237560] transaction","detail":"{read_only:false; response_revision:12700; number_of_response:1; }","duration":"160.697701ms","start":"2026-02-26T17:18:12.161264Z","end":"2026-02-26T17:18:12.321962Z","steps":["trace[2103237560] 'process raft request' (duration: 160.043909ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:12.322093Z","caller":"traceutil/trace.go:171","msg":"trace[47267967] transaction","detail":"{read_only:false; response_revision:12701; number_of_response:1; }","duration":"159.983047ms","start":"2026-02-26T17:18:12.1621Z","end":"2026-02-26T17:18:12.322083Z","steps":["trace[47267967] 'process raft request' (duration: 159.253893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:19.250464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.031634ms","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-02-26T17:18:19.250527Z","caller":"traceutil/trace.go:171","msg":"trace[602370248] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:12736; }","duration":"187.149536ms","start":"2026-02-26T17:18:19.063361Z","end":"2026-02-26T17:18:19.25051Z","steps":["trace[602370248] 'count revisions from in-memory index tree' (duration: 186.945703ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:20.09881Z","caller":"traceutil/trace.go:171","msg":"trace[90554129] transaction","detail":"{read_only:false; response_revision:12740; number_of_response:1; }","duration":"335.678776ms","start":"2026-02-26T17:18:19.763097Z","end":"2026-02-26T17:18:20.098776Z","steps":["trace[90554129] 'process raft request' (duration: 335.435966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:20.098984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:19.763062Z","time spent":"335.836126ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T17:18:20.90608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.821045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T17:18:20.906173Z","caller":"traceutil/trace.go:171","msg":"trace[932376064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12740; }","duration":"720.001796ms","start":"2026-02-26T17:18:20.186149Z","end":"2026-02-26T17:18:20.906151Z","steps":["trace[932376064] 'range keys from in-memory index tree' (duration: 719.518255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:20.906213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:20.186127Z","time spent":"720.078486ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T17:18:20.908678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.334668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T17:18:20.908841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.768696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T17:18:20.908903Z","caller":"traceutil/trace.go:171","msg":"trace[239403015] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12740; }","duration":"549.876086ms","start":"2026-02-26T17:18:20.359008Z","end":"2026-02-26T17:18:20.908884Z","steps":["trace[239403015] 'range keys from in-memory index tree' (duration: 549.605496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:20.908878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.414591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T17:18:20.908925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.272425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T17:18:20.909027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.430828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T17:18:20.909064Z","caller":"traceutil/trace.go:171","msg":"trace[1188687155] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:12740; }","duration":"221.519646ms","start":"2026-02-26T17:18:20.687515Z","end":"2026-02-26T17:18:20.909035Z","steps":["trace[1188687155] 'count revisions from in-memory index tree' (duration: 221.114345ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:20.908772Z","caller":"traceutil/trace.go:171","msg":"trace[424889513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12740; }","duration":"652.474078ms","start":"2026-02-26T17:18:20.256274Z","end":"2026-02-26T17:18:20.908748Z","steps":["trace[424889513] 'range keys from in-memory index tree' (duration: 652.030598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:20.909189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:20.256256Z","time spent":"652.919329ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T17:18:20.909035Z","caller":"traceutil/trace.go:171","msg":"trace[687291723] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:12740; }","duration":"172.651512ms","start":"2026-02-26T17:18:20.736344Z","end":"2026-02-26T17:18:20.908995Z","steps":["trace[687291723] 'count revisions from in-memory index tree' (duration: 172.287741ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:20.909093Z","caller":"traceutil/trace.go:171","msg":"trace[1460363811] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:12740; }","duration":"424.509318ms","start":"2026-02-26T17:18:20.484568Z","end":"2026-02-26T17:18:20.909077Z","steps":["trace[1460363811] 'count revisions from in-memory index tree' (duration: 424.270268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:20.909641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:20.48447Z","time spent":"425.149449ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":22,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-02-26T17:18:20.908954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:20.358988Z","time spent":"549.956037ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T17:18:20.90979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.23155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T17:18:20.909953Z","caller":"traceutil/trace.go:171","msg":"trace[1412798489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12740; }","duration":"554.4474ms","start":"2026-02-26T17:18:20.355481Z","end":"2026-02-26T17:18:20.909928Z","steps":["trace[1412798489] 'range keys from in-memory index tree' (duration: 553.332949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:20.90904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.742068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-26T17:18:20.910003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:20.355448Z","time spent":"554.5443ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T17:18:20.910099Z","caller":"traceutil/trace.go:171","msg":"trace[830599247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12740; }","duration":"552.753428ms","start":"2026-02-26T17:18:20.357289Z","end":"2026-02-26T17:18:20.910043Z","steps":["trace[830599247] 'range keys from in-memory index tree' (duration: 551.579878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:18:20.910263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T17:18:20.357211Z","time spent":"552.981228ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T17:18:21.122925Z","caller":"traceutil/trace.go:171","msg":"trace[1530328349] transaction","detail":"{read_only:false; response_revision:12741; number_of_response:1; }","duration":"209.209957ms","start":"2026-02-26T17:18:20.913687Z","end":"2026-02-26T17:18:21.122897Z","steps":["trace[1530328349] 'process raft request' (duration: 209.037237ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:21.189142Z","caller":"traceutil/trace.go:171","msg":"trace[1176555396] transaction","detail":"{read_only:false; response_revision:12742; number_of_response:1; }","duration":"274.659873ms","start":"2026-02-26T17:18:20.914444Z","end":"2026-02-26T17:18:21.189103Z","steps":["trace[1176555396] 'process raft request' (duration: 274.385353ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:21.189164Z","caller":"traceutil/trace.go:171","msg":"trace[1916510036] transaction","detail":"{read_only:false; response_revision:12744; number_of_response:1; }","duration":"274.110202ms","start":"2026-02-26T17:18:20.915019Z","end":"2026-02-26T17:18:21.189129Z","steps":["trace[1916510036] 'process raft request' (duration: 274.005822ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:21.189212Z","caller":"traceutil/trace.go:171","msg":"trace[1699874804] transaction","detail":"{read_only:false; response_revision:12743; number_of_response:1; }","duration":"274.308492ms","start":"2026-02-26T17:18:20.914857Z","end":"2026-02-26T17:18:21.189166Z","steps":["trace[1699874804] 'process raft request' (duration: 274.126672ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:18:21.189427Z","caller":"traceutil/trace.go:171","msg":"trace[1022696727] transaction","detail":"{read_only:false; response_revision:12745; number_of_response:1; }","duration":"272.913281ms","start":"2026-02-26T17:18:20.916464Z","end":"2026-02-26T17:18:21.189377Z","steps":["trace[1022696727] 'process raft request' (duration: 272.610691ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:19:21.547884Z","caller":"traceutil/trace.go:171","msg":"trace[1099988485] transaction","detail":"{read_only:false; response_revision:13562; number_of_response:1; }","duration":"158.837689ms","start":"2026-02-26T17:19:21.388999Z","end":"2026-02-26T17:19:21.547837Z","steps":["trace[1099988485] 'process raft request' (duration: 62.861452ms)","trace[1099988485] 'compare' (duration: 95.686991ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:19:21.548048Z","caller":"traceutil/trace.go:171","msg":"trace[1337612212] transaction","detail":"{read_only:false; response_revision:13564; number_of_response:1; }","duration":"155.460643ms","start":"2026-02-26T17:19:21.392556Z","end":"2026-02-26T17:19:21.548017Z","steps":["trace[1337612212] 'process raft request' (duration: 155.343861ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:19:21.548191Z","caller":"traceutil/trace.go:171","msg":"trace[573978104] transaction","detail":"{read_only:false; response_revision:13563; number_of_response:1; }","duration":"158.791859ms","start":"2026-02-26T17:19:21.389328Z","end":"2026-02-26T17:19:21.54812Z","steps":["trace[573978104] 'process raft request' (duration: 158.439941ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:19:26.559114Z","caller":"traceutil/trace.go:171","msg":"trace[1548648280] transaction","detail":"{read_only:false; response_revision:13601; number_of_response:1; }","duration":"100.157818ms","start":"2026-02-26T17:19:26.458927Z","end":"2026-02-26T17:19:26.559084Z","steps":["trace[1548648280] 'process raft request' (duration: 99.826952ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:19:26.898287Z","caller":"traceutil/trace.go:171","msg":"trace[168892736] linearizableReadLoop","detail":"{readStateIndex:14090; appliedIndex:14089; }","duration":"182.284242ms","start":"2026-02-26T17:19:26.715981Z","end":"2026-02-26T17:19:26.898265Z","steps":["trace[168892736] 'read index received' (duration: 182.077228ms)","trace[168892736] 'applied index is now lower than readState.Index' (duration: 206.324µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:19:26.898445Z","caller":"traceutil/trace.go:171","msg":"trace[1408829099] transaction","detail":"{read_only:false; response_revision:13602; number_of_response:1; }","duration":"236.710818ms","start":"2026-02-26T17:19:26.661678Z","end":"2026-02-26T17:19:26.898389Z","steps":["trace[1408829099] 'process raft request' (duration: 236.441882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:26.898623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.542798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-02-26T17:19:26.898705Z","caller":"traceutil/trace.go:171","msg":"trace[1073903685] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13602; }","duration":"176.673991ms","start":"2026-02-26T17:19:26.722013Z","end":"2026-02-26T17:19:26.898687Z","steps":["trace[1073903685] 'agreement among raft nodes before linearized reading' (duration: 176.496908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:26.898942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.525538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-02-26T17:19:26.898931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.948856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:19:26.898979Z","caller":"traceutil/trace.go:171","msg":"trace[1575235672] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13602; }","duration":"176.58524ms","start":"2026-02-26T17:19:26.722385Z","end":"2026-02-26T17:19:26.89897Z","steps":["trace[1575235672] 'agreement among raft nodes before linearized reading' (duration: 176.490978ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:19:26.899036Z","caller":"traceutil/trace.go:171","msg":"trace[288037241] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13602; }","duration":"183.039518ms","start":"2026-02-26T17:19:26.715937Z","end":"2026-02-26T17:19:26.898976Z","steps":["trace[288037241] 'agreement among raft nodes before linearized reading' (duration: 182.947286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:26.899025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.196208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-02-26T17:19:26.89916Z","caller":"traceutil/trace.go:171","msg":"trace[1264625729] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13602; }","duration":"153.423722ms","start":"2026-02-26T17:19:26.745716Z","end":"2026-02-26T17:19:26.89914Z","steps":["trace[1264625729] 'agreement among raft nodes before linearized reading' (duration: 153.079935ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:19:55.912085Z","caller":"traceutil/trace.go:171","msg":"trace[1241576930] linearizableReadLoop","detail":"{readStateIndex:14232; appliedIndex:14231; }","duration":"154.745088ms","start":"2026-02-26T17:19:55.757316Z","end":"2026-02-26T17:19:55.912061Z","steps":["trace[1241576930] 'read index received' (duration: 154.679857ms)","trace[1241576930] 'applied index is now lower than readState.Index' (duration: 61.531µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:19:55.912271Z","caller":"traceutil/trace.go:171","msg":"trace[1803139933] transaction","detail":"{read_only:false; response_revision:13738; number_of_response:1; }","duration":"160.721607ms","start":"2026-02-26T17:19:55.751423Z","end":"2026-02-26T17:19:55.912145Z","steps":["trace[1803139933] 'process raft request' (duration: 160.485152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:55.912419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.068926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T17:19:55.912584Z","caller":"traceutil/trace.go:171","msg":"trace[1447214656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13738; }","duration":"155.254879ms","start":"2026-02-26T17:19:55.757308Z","end":"2026-02-26T17:19:55.912563Z","steps":["trace[1447214656] 'agreement among raft nodes before linearized reading' (duration: 154.872892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:55.94607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.064419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T17:19:55.946164Z","caller":"traceutil/trace.go:171","msg":"trace[1031893761] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13740; }","duration":"183.179681ms","start":"2026-02-26T17:19:55.762959Z","end":"2026-02-26T17:19:55.946139Z","steps":["trace[1031893761] 'agreement among raft nodes before linearized reading' (duration: 182.959326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:55.946598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.856973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T17:19:55.94671Z","caller":"traceutil/trace.go:171","msg":"trace[1366951810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13740; }","duration":"188.996405ms","start":"2026-02-26T17:19:55.757688Z","end":"2026-02-26T17:19:55.946685Z","steps":["trace[1366951810] 'agreement among raft nodes before linearized reading' (duration: 188.531806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:55.946587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.706061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T17:19:55.946892Z","caller":"traceutil/trace.go:171","msg":"trace[331310101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13740; }","duration":"185.036147ms","start":"2026-02-26T17:19:55.761825Z","end":"2026-02-26T17:19:55.946861Z","steps":["trace[331310101] 'agreement among raft nodes before linearized reading' (duration: 184.245801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:19:55.947521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.266022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T17:19:55.947634Z","caller":"traceutil/trace.go:171","msg":"trace[2108728662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13740; }","duration":"181.490476ms","start":"2026-02-26T17:19:55.766118Z","end":"2026-02-26T17:19:55.947609Z","steps":["trace[2108728662] 'agreement among raft nodes before linearized reading' (duration: 179.805814ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:21:49.660924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12193} {"level":"info","ts":"2026-02-26T17:21:49.741146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12193,"took":"77.047137ms","hash":1087688838,"current-db-size-bytes":42573824,"current-db-size":"43 MB","current-db-size-in-use-bytes":27926528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T17:21:49.741222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087688838,"revision":12193,"compact-revision":10310} {"level":"warn","ts":"2026-02-26T17:22:44.975408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.181664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T17:22:44.97561Z","caller":"traceutil/trace.go:171","msg":"trace[218885962] linearizableReadLoop","detail":"{readStateIndex:15598; appliedIndex:15596; }","duration":"105.130091ms","start":"2026-02-26T17:22:44.870461Z","end":"2026-02-26T17:22:44.975591Z","steps":["trace[218885962] 'read index received' (duration: 98.739499ms)","trace[218885962] 'applied index is now lower than readState.Index' (duration: 6.389452ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:22:44.975707Z","caller":"traceutil/trace.go:171","msg":"trace[226533166] transaction","detail":"{read_only:false; response_revision:15057; number_of_response:1; }","duration":"159.082133ms","start":"2026-02-26T17:22:44.816611Z","end":"2026-02-26T17:22:44.975693Z","steps":["trace[226533166] 'process raft request' (duration: 30.50123ms)","trace[226533166] 'compare' (duration: 128.031711ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:22:44.975856Z","caller":"traceutil/trace.go:171","msg":"trace[765005598] transaction","detail":"{read_only:false; response_revision:15058; number_of_response:1; }","duration":"159.151183ms","start":"2026-02-26T17:22:44.816667Z","end":"2026-02-26T17:22:44.975819Z","steps":["trace[765005598] 'process raft request' (duration: 158.861157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:22:44.975923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.435128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:22:44.976008Z","caller":"traceutil/trace.go:171","msg":"trace[1500330121] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:0; response_revision:15058; }","duration":"105.581181ms","start":"2026-02-26T17:22:44.870407Z","end":"2026-02-26T17:22:44.975988Z","steps":["trace[1500330121] 'agreement among raft nodes before linearized reading' (duration: 105.328266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:22:44.976014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.422358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:22:44.976105Z","caller":"traceutil/trace.go:171","msg":"trace[1225412640] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:15058; }","duration":"105.579671ms","start":"2026-02-26T17:22:44.870504Z","end":"2026-02-26T17:22:44.976084Z","steps":["trace[1225412640] 'agreement among raft nodes before linearized reading' (duration: 105.309375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:22:44.97616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.958867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:22:44.97619Z","caller":"traceutil/trace.go:171","msg":"trace[511410489] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:15058; }","duration":"105.008777ms","start":"2026-02-26T17:22:44.871173Z","end":"2026-02-26T17:22:44.976181Z","steps":["trace[511410489] 'agreement among raft nodes before linearized reading' (duration: 104.964276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T17:22:44.976295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.51258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T17:22:44.976318Z","caller":"traceutil/trace.go:171","msg":"trace[1783285649] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:15058; }","duration":"105.55317ms","start":"2026-02-26T17:22:44.870758Z","end":"2026-02-26T17:22:44.976311Z","steps":["trace[1783285649] 'agreement among raft nodes before linearized reading' (duration: 105.516189ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T17:22:45.103957Z","caller":"traceutil/trace.go:171","msg":"trace[987054950] transaction","detail":"{read_only:false; response_revision:15060; number_of_response:1; }","duration":"117.736322ms","start":"2026-02-26T17:22:44.986191Z","end":"2026-02-26T17:22:45.103927Z","steps":["trace[987054950] 'process raft request' (duration: 90.983476ms)","trace[987054950] 'compare' (duration: 26.625023ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T17:26:49.666696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14479} {"level":"info","ts":"2026-02-26T17:26:49.762604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14479,"took":"90.95761ms","hash":3964376378,"current-db-size-bytes":42573824,"current-db-size":"43 MB","current-db-size-in-use-bytes":23109632,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T17:26:49.762722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3964376378,"revision":14479,"compact-revision":12193}