{"level":"warn","ts":"2026-04-13T14:13:19.140696Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:13:19.140783Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-04-13T14:13:19.140843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:13:19.140878Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-04-13T14:13:19.140894Z","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-04-13T14:13:19.141584Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-04-13T14:13:19.1417Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-04-13T14:13:19.150903Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.897804ms"} {"level":"info","ts":"2026-04-13T14:13:19.157221Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-04-13T14:13:19.157299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T14:13:19.157329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-04-13T14:13:19.157373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T14:13:19.157388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-04-13T14:13:19.157465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-04-13T14:13:19.163856Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T14:13:19.165076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T14:13:19.166221Z","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-04-13T14:13:19.169009Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T14:13:19.169178Z","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-04-13T14:13:19.169259Z","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-04-13T14:13:19.169272Z","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-04-13T14:13:19.169445Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T14:13:19.170382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-04-13T14:13:19.170494Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-04-13T14:13:19.174083Z","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-04-13T14:13:19.174553Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T14:13:19.174578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T14:13:19.17511Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-04-13T14:13:19.175139Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-04-13T14:13:20.158103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T14:13:20.158154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T14:13:20.158182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-04-13T14:13:20.158196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-04-13T14:13:20.158203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-04-13T14:13:20.158223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-04-13T14:13:20.158231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-04-13T14:13:20.161254Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:13:20.162187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:13:20.162183Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T14:13:20.162261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:13:20.162634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:13:20.162766Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:13:20.162696Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T14:13:20.162792Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T14:13:20.162797Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:13:20.166034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"info","ts":"2026-04-13T14:13:20.166782Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-13T14:13:36.850277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948124817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:13:36.910014Z","caller":"traceutil/trace.go:171","msg":"trace[2051606860] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"559.923946ms","start":"2026-04-13T14:13:36.350068Z","end":"2026-04-13T14:13:36.909992Z","steps":["trace[2051606860] 'read index received' (duration: 559.671435ms)","trace[2051606860] 'applied index is now lower than readState.Index' (duration: 251.561µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:13:36.910078Z","caller":"traceutil/trace.go:171","msg":"trace[566173480] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"609.059405ms","start":"2026-04-13T14:13:36.300989Z","end":"2026-04-13T14:13:36.910048Z","steps":["trace[566173480] 'process raft request' (duration: 608.843524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:36.910408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.271551ms","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-04-13T14:13:36.910449Z","caller":"traceutil/trace.go:171","msg":"trace[272838095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"171.506913ms","start":"2026-04-13T14:13:36.73893Z","end":"2026-04-13T14:13:36.910437Z","steps":["trace[272838095] 'agreement among raft nodes before linearized reading' (duration: 171.267541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:36.910471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.655427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:13:36.910656Z","caller":"traceutil/trace.go:171","msg":"trace[421643094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"344.08847ms","start":"2026-04-13T14:13:36.566545Z","end":"2026-04-13T14:13:36.910634Z","steps":["trace[421643094] 'agreement among raft nodes before linearized reading' (duration: 343.635487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:36.910719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:36.56653Z","time spent":"344.17466ms","remote":"127.0.0.1:56880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T14:13:36.910318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.082337ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:13:36.910831Z","caller":"traceutil/trace.go:171","msg":"trace[2002334786] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:413; }","duration":"560.762841ms","start":"2026-04-13T14:13:36.350061Z","end":"2026-04-13T14:13:36.910824Z","steps":["trace[2002334786] 'agreement among raft nodes before linearized reading' (duration: 560.069827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:36.911226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:36.300973Z","time spent":"609.176776ms","remote":"127.0.0.1:57282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:13:37.46382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.623593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:13:37.463944Z","caller":"traceutil/trace.go:171","msg":"trace[998109719] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"550.289437ms","start":"2026-04-13T14:13:36.913636Z","end":"2026-04-13T14:13:37.463926Z","steps":["trace[998109719] 'process raft request' (duration: 197.318852ms)","trace[998109719] 'compare' (duration: 352.267561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:13:37.46402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:36.913571Z","time spent":"550.421187ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:13:37.784972Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948124821,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:13:37.888529Z","caller":"traceutil/trace.go:171","msg":"trace[1831774564] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"968.745726ms","start":"2026-04-13T14:13:36.919757Z","end":"2026-04-13T14:13:37.888503Z","steps":["trace[1831774564] 'process raft request' (duration: 930.12432ms)","trace[1831774564] 'compare' (duration: 38.394595ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:13:37.888636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:36.919738Z","time spent":"968.843736ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:13:37.888687Z","caller":"traceutil/trace.go:171","msg":"trace[1383214963] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:426; }","duration":"604.561543ms","start":"2026-04-13T14:13:37.284084Z","end":"2026-04-13T14:13:37.888646Z","steps":["trace[1383214963] 'read index received' (duration: 565.844397ms)","trace[1383214963] 'applied index is now lower than readState.Index' (duration: 38.715236ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:13:37.888715Z","caller":"traceutil/trace.go:171","msg":"trace[970617681] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"968.599996ms","start":"2026-04-13T14:13:36.920063Z","end":"2026-04-13T14:13:37.888663Z","steps":["trace[970617681] 'process raft request' (duration: 968.367935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:37.888827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.743164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T14:13:37.888853Z","caller":"traceutil/trace.go:171","msg":"trace[1790368799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"604.806165ms","start":"2026-04-13T14:13:37.284041Z","end":"2026-04-13T14:13:37.888847Z","steps":["trace[1790368799] 'agreement among raft nodes before linearized reading' (duration: 604.726654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:37.888869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:37.284021Z","time spent":"604.844625ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T14:13:37.888884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:36.920041Z","time spent":"968.760346ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:13:37.888928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.71554ms","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-04-13T14:13:37.888974Z","caller":"traceutil/trace.go:171","msg":"trace[1342538400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"603.80132ms","start":"2026-04-13T14:13:37.285161Z","end":"2026-04-13T14:13:37.888962Z","steps":["trace[1342538400] 'agreement among raft nodes before linearized reading' (duration: 603.66268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:37.889013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:37.285146Z","time spent":"603.85957ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:13:37.889044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.801594ms","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-04-13T14:13:37.889064Z","caller":"traceutil/trace.go:171","msg":"trace[591611590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"398.856446ms","start":"2026-04-13T14:13:37.490202Z","end":"2026-04-13T14:13:37.889059Z","steps":["trace[591611590] 'agreement among raft nodes before linearized reading' (duration: 398.824425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:37.889077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:37.490186Z","time spent":"398.887696ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T14:13:45.542997Z","caller":"traceutil/trace.go:171","msg":"trace[1178344660] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"109.555796ms","start":"2026-04-13T14:13:45.433429Z","end":"2026-04-13T14:13:45.542985Z","steps":["trace[1178344660] 'process raft request' (duration: 109.315385ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.262854Z","caller":"traceutil/trace.go:171","msg":"trace[84510557] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"515.929503ms","start":"2026-04-13T14:13:49.746901Z","end":"2026-04-13T14:13:50.26283Z","steps":["trace[84510557] 'process raft request' (duration: 515.769562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.263025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.74688Z","time spent":"516.053123ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.6932Z","caller":"traceutil/trace.go:171","msg":"trace[1013727219] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"940.458737ms","start":"2026-04-13T14:13:49.752701Z","end":"2026-04-13T14:13:50.693159Z","steps":["trace[1013727219] 'process raft request' (duration: 925.367992ms)","trace[1013727219] 'compare' (duration: 14.713362ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:13:50.693301Z","caller":"traceutil/trace.go:171","msg":"trace[1697720553] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"940.430517ms","start":"2026-04-13T14:13:49.752841Z","end":"2026-04-13T14:13:50.693271Z","steps":["trace[1697720553] 'process raft request' (duration: 940.198517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.693322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.752681Z","time spent":"940.586567ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:13:50.693451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.752815Z","time spent":"940.571958ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.693622Z","caller":"traceutil/trace.go:171","msg":"trace[66588171] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"926.188477ms","start":"2026-04-13T14:13:49.7674Z","end":"2026-04-13T14:13:50.693588Z","steps":["trace[66588171] 'process raft request' (duration: 925.902656ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.69362Z","caller":"traceutil/trace.go:171","msg":"trace[1212619245] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"924.956371ms","start":"2026-04-13T14:13:49.768629Z","end":"2026-04-13T14:13:50.693586Z","steps":["trace[1212619245] 'process raft request' (duration: 924.74255ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.693664Z","caller":"traceutil/trace.go:171","msg":"trace[1170069551] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"913.313441ms","start":"2026-04-13T14:13:49.780336Z","end":"2026-04-13T14:13:50.693649Z","steps":["trace[1170069551] 'process raft request' (duration: 913.08027ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.693694Z","caller":"traceutil/trace.go:171","msg":"trace[1448322829] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"929.955752ms","start":"2026-04-13T14:13:49.763726Z","end":"2026-04-13T14:13:50.693682Z","steps":["trace[1448322829] 'process raft request' (duration: 929.38848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.693735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.767376Z","time spent":"926.306837ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:13:50.693758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.763711Z","time spent":"930.022272ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:13:50.693781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.768616Z","time spent":"925.076801ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.693693Z","caller":"traceutil/trace.go:171","msg":"trace[333700229] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"928.062235ms","start":"2026-04-13T14:13:49.765557Z","end":"2026-04-13T14:13:50.693619Z","steps":["trace[333700229] 'process raft request' (duration: 927.699283ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.693918Z","caller":"traceutil/trace.go:171","msg":"trace[901133652] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"903.416539ms","start":"2026-04-13T14:13:49.79045Z","end":"2026-04-13T14:13:50.693866Z","steps":["trace[901133652] 'process raft request' (duration: 903.061517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.694013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.79043Z","time spent":"903.517149ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:13:50.693747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.780275Z","time spent":"913.417231ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.693851Z","caller":"traceutil/trace.go:171","msg":"trace[460353297] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"928.611746ms","start":"2026-04-13T14:13:49.765178Z","end":"2026-04-13T14:13:50.69379Z","steps":["trace[460353297] 'process raft request' (duration: 928.040744ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.694176Z","caller":"traceutil/trace.go:171","msg":"trace[811047505] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"855.00442ms","start":"2026-04-13T14:13:49.839158Z","end":"2026-04-13T14:13:50.694163Z","steps":["trace[811047505] 'process raft request' (duration: 854.835639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.694234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.839139Z","time spent":"855.069261ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:13:50.69425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.392999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:13:50.694235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.765169Z","time spent":"929.033498ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.694286Z","caller":"traceutil/trace.go:171","msg":"trace[1929381377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"810.459939ms","start":"2026-04-13T14:13:49.883817Z","end":"2026-04-13T14:13:50.694277Z","steps":["trace[1929381377] 'agreement among raft nodes before linearized reading' (duration: 810.376029ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.694167Z","caller":"traceutil/trace.go:171","msg":"trace[1291928788] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:524; }","duration":"810.288289ms","start":"2026-04-13T14:13:49.883847Z","end":"2026-04-13T14:13:50.694135Z","steps":["trace[1291928788] 'read index received' (duration: 378.964236ms)","trace[1291928788] 'applied index is now lower than readState.Index' (duration: 431.322933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:13:50.694314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.883805Z","time spent":"810.50382ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T14:13:50.69422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.76555Z","time spent":"928.480456ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.694479Z","caller":"traceutil/trace.go:171","msg":"trace[138164063] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"742.622289ms","start":"2026-04-13T14:13:49.951775Z","end":"2026-04-13T14:13:50.694397Z","steps":["trace[138164063] 'process raft request' (duration: 742.298748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.694665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.951739Z","time spent":"742.8573ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.761691Z","caller":"traceutil/trace.go:171","msg":"trace[312842014] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"487.633983ms","start":"2026-04-13T14:13:50.273998Z","end":"2026-04-13T14:13:50.761632Z","steps":["trace[312842014] 'process raft request' (duration: 487.352692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.761805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.752138ms","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-04-13T14:13:50.761781Z","caller":"traceutil/trace.go:171","msg":"trace[1196387012] transaction","detail":"{read_only:false; number_of_response:1; response_revision:521; }","duration":"487.561242ms","start":"2026-04-13T14:13:50.27419Z","end":"2026-04-13T14:13:50.761751Z","steps":["trace[1196387012] 'process raft request' (duration: 487.400282ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:50.761832Z","caller":"traceutil/trace.go:171","msg":"trace[625946909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"789.796109ms","start":"2026-04-13T14:13:49.972029Z","end":"2026-04-13T14:13:50.761825Z","steps":["trace[625946909] 'agreement among raft nodes before linearized reading' (duration: 789.748158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.761809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.856533ms","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-04-13T14:13:50.761873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.972022Z","time spent":"789.845989ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:13:50.761932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:50.274168Z","time spent":"487.675183ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:13:50.76195Z","caller":"traceutil/trace.go:171","msg":"trace[1515370696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"798.079485ms","start":"2026-04-13T14:13:49.963805Z","end":"2026-04-13T14:13:50.761884Z","steps":["trace[1515370696] 'agreement among raft nodes before linearized reading' (duration: 797.846444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.761979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.170989ms","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-04-13T14:13:50.76205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.963788Z","time spent":"798.198435ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T14:13:50.762052Z","caller":"traceutil/trace.go:171","msg":"trace[1873858785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"792.282289ms","start":"2026-04-13T14:13:49.969754Z","end":"2026-04-13T14:13:50.762036Z","steps":["trace[1873858785] 'agreement among raft nodes before linearized reading' (duration: 792.140199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.762451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:49.969746Z","time spent":"792.687231ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T14:13:50.762501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:50.273976Z","time spent":"487.824013ms","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:50.76309Z","caller":"traceutil/trace.go:171","msg":"trace[403541725] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"487.674503ms","start":"2026-04-13T14:13:50.274087Z","end":"2026-04-13T14:13:50.761761Z","steps":["trace[403541725] 'process raft request' (duration: 487.419252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:50.763243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:50.274069Z","time spent":"489.10723ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:13:57.313409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948125183,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:13:57.515446Z","caller":"traceutil/trace.go:171","msg":"trace[1587655785] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"703.109696ms","start":"2026-04-13T14:13:56.81231Z","end":"2026-04-13T14:13:57.515419Z","steps":["trace[1587655785] 'read index received' (duration: 702.473853ms)","trace[1587655785] 'applied index is now lower than readState.Index' (duration: 634.903µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:13:57.515484Z","caller":"traceutil/trace.go:171","msg":"trace[856488242] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"796.124144ms","start":"2026-04-13T14:13:56.719326Z","end":"2026-04-13T14:13:57.51545Z","steps":["trace[856488242] 'process raft request' (duration: 795.565342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.515614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:56.719305Z","time spent":"796.237914ms","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:13:57.51568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.685467ms","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-04-13T14:13:57.515714Z","caller":"traceutil/trace.go:171","msg":"trace[341535599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"698.755758ms","start":"2026-04-13T14:13:56.816948Z","end":"2026-04-13T14:13:57.515703Z","steps":["trace[341535599] 'agreement among raft nodes before linearized reading' (duration: 698.615387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.515749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:56.81694Z","time spent":"698.800818ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:13:57.515723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.397577ms","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-04-13T14:13:57.515839Z","caller":"traceutil/trace.go:171","msg":"trace[487474114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"703.528287ms","start":"2026-04-13T14:13:56.812255Z","end":"2026-04-13T14:13:57.515784Z","steps":["trace[487474114] 'agreement among raft nodes before linearized reading' (duration: 703.272886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.515836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.532023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-89zvx\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-13T14:13:57.515885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:56.812239Z","time spent":"703.637307ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T14:13:57.51593Z","caller":"traceutil/trace.go:171","msg":"trace[2001892617] range","detail":"{range_begin:/registry/pods/kube-system/cilium-89zvx; range_end:; response_count:1; response_revision:615; }","duration":"416.668124ms","start":"2026-04-13T14:13:57.099234Z","end":"2026-04-13T14:13:57.515902Z","steps":["trace[2001892617] 'agreement among raft nodes before linearized reading' (duration: 416.352913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.515951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.964802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T14:13:57.515974Z","caller":"traceutil/trace.go:171","msg":"trace[1281175199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"673.019983ms","start":"2026-04-13T14:13:56.842948Z","end":"2026-04-13T14:13:57.515968Z","steps":["trace[1281175199] 'agreement among raft nodes before linearized reading' (duration: 672.930452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.51599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:56.842929Z","time spent":"673.057113ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T14:13:57.51598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:57.099222Z","time spent":"416.748405ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-89zvx\" "} {"level":"warn","ts":"2026-04-13T14:13:57.516055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.109459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:13:57.516104Z","caller":"traceutil/trace.go:171","msg":"trace[365240986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"630.184449ms","start":"2026-04-13T14:13:56.88591Z","end":"2026-04-13T14:13:57.516094Z","steps":["trace[365240986] 'agreement among raft nodes before linearized reading' (duration: 630.123719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.516153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:56.885891Z","time spent":"630.254779ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T14:13:57.866519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.027052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:13:57.866629Z","caller":"traceutil/trace.go:171","msg":"trace[44710704] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"345.629266ms","start":"2026-04-13T14:13:57.520981Z","end":"2026-04-13T14:13:57.86661Z","steps":["trace[44710704] 'process raft request' (duration: 196.421513ms)","trace[44710704] 'compare' (duration: 148.903631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:13:57.866691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:57.520958Z","time spent":"345.704146ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:13:57.960793Z","caller":"traceutil/trace.go:171","msg":"trace[1430667921] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"438.645412ms","start":"2026-04-13T14:13:57.522123Z","end":"2026-04-13T14:13:57.960768Z","steps":["trace[1430667921] 'process raft request' (duration: 438.492852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.960999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:57.5221Z","time spent":"438.822093ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:13:57.961247Z","caller":"traceutil/trace.go:171","msg":"trace[652014025] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"438.922714ms","start":"2026-04-13T14:13:57.522258Z","end":"2026-04-13T14:13:57.96118Z","steps":["trace[652014025] 'process raft request' (duration: 438.470752ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:13:57.961439Z","caller":"traceutil/trace.go:171","msg":"trace[2107739062] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"431.894864ms","start":"2026-04-13T14:13:57.529523Z","end":"2026-04-13T14:13:57.961418Z","steps":["trace[2107739062] 'process raft request' (duration: 431.463133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.96146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:57.522249Z","time spent":"439.071674ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:13:57.961569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:57.52949Z","time spent":"432.013065ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:13:57.961749Z","caller":"traceutil/trace.go:171","msg":"trace[395211446] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"395.920309ms","start":"2026-04-13T14:13:57.565817Z","end":"2026-04-13T14:13:57.961738Z","steps":["trace[395211446] 'process raft request' (duration: 395.354157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.961837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:57.565786Z","time spent":"396.022609ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:13:57.961474Z","caller":"traceutil/trace.go:171","msg":"trace[2104459879] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:635; }","duration":"369.381222ms","start":"2026-04-13T14:13:57.591948Z","end":"2026-04-13T14:13:57.961329Z","steps":["trace[2104459879] 'read index received' (duration: 125.494457ms)","trace[2104459879] 'applied index is now lower than readState.Index' (duration: 243.885505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:13:57.962278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.312227ms","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-04-13T14:13:57.962328Z","caller":"traceutil/trace.go:171","msg":"trace[1755171608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"370.408897ms","start":"2026-04-13T14:13:57.591905Z","end":"2026-04-13T14:13:57.962313Z","steps":["trace[1755171608] 'agreement among raft nodes before linearized reading' (duration: 370.097915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:57.96247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:57.591892Z","time spent":"370.564047ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T14:13:59.173169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.258841ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T14:13:59.200917Z","caller":"traceutil/trace.go:171","msg":"trace[1848387132] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"315.324811ms","start":"2026-04-13T14:13:58.885575Z","end":"2026-04-13T14:13:59.2009Z","steps":["trace[1848387132] 'read index received' (duration: 314.762138ms)","trace[1848387132] 'applied index is now lower than readState.Index' (duration: 562.133µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:13:59.201003Z","caller":"traceutil/trace.go:171","msg":"trace[58866158] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"733.402337ms","start":"2026-04-13T14:13:58.467575Z","end":"2026-04-13T14:13:59.200978Z","steps":["trace[58866158] 'process raft request' (duration: 732.949826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:59.201155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:58.46754Z","time spent":"733.531939ms","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:13:59.201039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.458181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:13:59.201255Z","caller":"traceutil/trace.go:171","msg":"trace[706945847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"315.730942ms","start":"2026-04-13T14:13:58.885509Z","end":"2026-04-13T14:13:59.20124Z","steps":["trace[706945847] 'agreement among raft nodes before linearized reading' (duration: 315.492201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:13:59.201308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:13:58.885484Z","time spent":"315.807982ms","remote":"127.0.0.1:56880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T14:14:01.307347Z","caller":"traceutil/trace.go:171","msg":"trace[115269424] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"108.679838ms","start":"2026-04-13T14:14:01.198648Z","end":"2026-04-13T14:14:01.307328Z","steps":["trace[115269424] 'read index received' (duration: 41.275763ms)","trace[115269424] 'applied index is now lower than readState.Index' (duration: 67.403135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:14:01.307471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.811068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-13T14:14:01.307516Z","caller":"traceutil/trace.go:171","msg":"trace[783834416] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"128.164734ms","start":"2026-04-13T14:14:01.179327Z","end":"2026-04-13T14:14:01.307492Z","steps":["trace[783834416] 'process raft request' (duration: 60.593469ms)","trace[783834416] 'compare' (duration: 67.163444ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:14:01.307558Z","caller":"traceutil/trace.go:171","msg":"trace[1204682825] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:640; }","duration":"108.944319ms","start":"2026-04-13T14:14:01.198603Z","end":"2026-04-13T14:14:01.307548Z","steps":["trace[1204682825] 'agreement among raft nodes before linearized reading' (duration: 108.815318ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:01.307366Z","caller":"traceutil/trace.go:171","msg":"trace[679335674] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"116.098038ms","start":"2026-04-13T14:14:01.191245Z","end":"2026-04-13T14:14:01.307343Z","steps":["trace[679335674] 'process raft request' (duration: 116.006018ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:01.59571Z","caller":"traceutil/trace.go:171","msg":"trace[351736459] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"167.880921ms","start":"2026-04-13T14:14:01.427804Z","end":"2026-04-13T14:14:01.595685Z","steps":["trace[351736459] 'process raft request' (duration: 152.872321ms)","trace[351736459] 'compare' (duration: 14.785858ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:14:01.59574Z","caller":"traceutil/trace.go:171","msg":"trace[1652772982] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"167.76479ms","start":"2026-04-13T14:14:01.427953Z","end":"2026-04-13T14:14:01.595717Z","steps":["trace[1652772982] 'process raft request' (duration: 167.67457ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:01.596144Z","caller":"traceutil/trace.go:171","msg":"trace[1763586483] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"130.921395ms","start":"2026-04-13T14:14:01.465161Z","end":"2026-04-13T14:14:01.596082Z","steps":["trace[1763586483] 'process raft request' (duration: 130.516414ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:01.599022Z","caller":"traceutil/trace.go:171","msg":"trace[1338348987] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"100.908097ms","start":"2026-04-13T14:14:01.498095Z","end":"2026-04-13T14:14:01.599003Z","steps":["trace[1338348987] 'process raft request' (duration: 100.563936ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:04.406369Z","caller":"traceutil/trace.go:171","msg":"trace[516356385] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"132.977345ms","start":"2026-04-13T14:14:04.273352Z","end":"2026-04-13T14:14:04.406329Z","steps":["trace[516356385] 'process raft request' (duration: 132.781724ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:15.43319Z","caller":"traceutil/trace.go:171","msg":"trace[48430051] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"203.749847ms","start":"2026-04-13T14:14:15.229418Z","end":"2026-04-13T14:14:15.433168Z","steps":["trace[48430051] 'process raft request' (duration: 203.593857ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:29.829096Z","caller":"traceutil/trace.go:171","msg":"trace[44689791] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"128.81488ms","start":"2026-04-13T14:14:29.700258Z","end":"2026-04-13T14:14:29.829073Z","steps":["trace[44689791] 'process raft request' (duration: 128.62904ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:29.829216Z","caller":"traceutil/trace.go:171","msg":"trace[128871910] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"127.839456ms","start":"2026-04-13T14:14:29.701359Z","end":"2026-04-13T14:14:29.829199Z","steps":["trace[128871910] 'process raft request' (duration: 127.664795ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:29.961964Z","caller":"traceutil/trace.go:171","msg":"trace[705815617] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"125.146297ms","start":"2026-04-13T14:14:29.836795Z","end":"2026-04-13T14:14:29.961942Z","steps":["trace[705815617] 'process raft request' (duration: 124.868045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:30.148044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.213563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-j5jd6\" ","response":"range_response_count:1 size:4881"} {"level":"info","ts":"2026-04-13T14:14:30.148104Z","caller":"traceutil/trace.go:171","msg":"trace[128803812] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-j5jd6; range_end:; response_count:1; response_revision:950; }","duration":"107.305423ms","start":"2026-04-13T14:14:30.040786Z","end":"2026-04-13T14:14:30.148092Z","steps":["trace[128803812] 'range keys from in-memory index tree' (duration: 107.123362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:45.151555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.649609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:14:45.15236Z","caller":"traceutil/trace.go:171","msg":"trace[1127663299] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"787.237405ms","start":"2026-04-13T14:14:44.365025Z","end":"2026-04-13T14:14:45.152262Z","steps":["trace[1127663299] 'process raft request' (duration: 362.771744ms)","trace[1127663299] 'compare' (duration: 423.105627ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:14:45.152459Z","caller":"traceutil/trace.go:171","msg":"trace[1394618858] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"787.400036ms","start":"2026-04-13T14:14:44.365028Z","end":"2026-04-13T14:14:45.152428Z","steps":["trace[1394618858] 'process raft request' (duration: 787.084575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:45.152531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:44.365007Z","time spent":"787.438246ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:14:45.152548Z","caller":"traceutil/trace.go:171","msg":"trace[1963578291] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1215; }","duration":"645.978288ms","start":"2026-04-13T14:14:44.506545Z","end":"2026-04-13T14:14:45.152524Z","steps":["trace[1963578291] 'read index received' (duration: 221.264546ms)","trace[1963578291] 'applied index is now lower than readState.Index' (duration: 424.712432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:14:45.152568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:44.365008Z","time spent":"787.484886ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:14:45.152376Z","caller":"traceutil/trace.go:171","msg":"trace[1315664992] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"786.649993ms","start":"2026-04-13T14:14:44.3657Z","end":"2026-04-13T14:14:45.15235Z","steps":["trace[1315664992] 'process raft request' (duration: 786.536813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:45.152723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:44.365688Z","time spent":"787.004165ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:14:45.152733Z","caller":"traceutil/trace.go:171","msg":"trace[1656386504] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"717.932041ms","start":"2026-04-13T14:14:44.434789Z","end":"2026-04-13T14:14:45.152721Z","steps":["trace[1656386504] 'process raft request' (duration: 717.493639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:45.152876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.535704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:14:45.152915Z","caller":"traceutil/trace.go:171","msg":"trace[1518847817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"250.598494ms","start":"2026-04-13T14:14:44.902303Z","end":"2026-04-13T14:14:45.152902Z","steps":["trace[1518847817] 'agreement among raft nodes before linearized reading' (duration: 250.532304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:45.152918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:44.434766Z","time spent":"717.993741ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:14:45.153084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.54227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T14:14:45.153095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.73961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:14:45.153112Z","caller":"traceutil/trace.go:171","msg":"trace[1359629117] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1187; }","duration":"646.61674ms","start":"2026-04-13T14:14:44.506487Z","end":"2026-04-13T14:14:45.153104Z","steps":["trace[1359629117] 'agreement among raft nodes before linearized reading' (duration: 646.55621ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:45.153127Z","caller":"traceutil/trace.go:171","msg":"trace[1546125671] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1187; }","duration":"112.84168ms","start":"2026-04-13T14:14:45.040276Z","end":"2026-04-13T14:14:45.153117Z","steps":["trace[1546125671] 'agreement among raft nodes before linearized reading' (duration: 112.78045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:45.153138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:44.506467Z","time spent":"646.66409ms","remote":"127.0.0.1:56982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":18,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-13T14:14:49.878897Z","caller":"traceutil/trace.go:171","msg":"trace[897978673] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"328.501068ms","start":"2026-04-13T14:14:49.550375Z","end":"2026-04-13T14:14:49.878876Z","steps":["trace[897978673] 'read index received' (duration: 279.843896ms)","trace[897978673] 'applied index is now lower than readState.Index' (duration: 48.656112ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:14:49.878962Z","caller":"traceutil/trace.go:171","msg":"trace[1179724740] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"329.347511ms","start":"2026-04-13T14:14:49.549582Z","end":"2026-04-13T14:14:49.87893Z","steps":["trace[1179724740] 'process raft request' (duration: 280.730839ms)","trace[1179724740] 'compare' (duration: 48.284371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:14:49.879354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:49.549549Z","time spent":"329.763374ms","remote":"127.0.0.1:56974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":740,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:14:49.879171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.77398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/self-signed-ca-sdrff\" ","response":"range_response_count:1 size:997"} {"level":"info","ts":"2026-04-13T14:14:49.879581Z","caller":"traceutil/trace.go:171","msg":"trace[2079062894] range","detail":"{range_begin:/registry/secrets/cert-manager/self-signed-ca-sdrff; range_end:; response_count:1; response_revision:1249; }","duration":"329.223811ms","start":"2026-04-13T14:14:49.550348Z","end":"2026-04-13T14:14:49.879572Z","steps":["trace[2079062894] 'agreement among raft nodes before linearized reading' (duration: 328.665779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:49.879623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:49.550341Z","time spent":"329.274051ms","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":1020,"request content":"key:\"/registry/secrets/cert-manager/self-signed-ca-sdrff\" "} {"level":"info","ts":"2026-04-13T14:14:58.667632Z","caller":"traceutil/trace.go:171","msg":"trace[1130665452] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"403.996295ms","start":"2026-04-13T14:14:58.263615Z","end":"2026-04-13T14:14:58.667611Z","steps":["trace[1130665452] 'process raft request' (duration: 403.880784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:58.667759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:14:58.263603Z","time spent":"404.090205ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:14:58.690094Z","caller":"traceutil/trace.go:171","msg":"trace[754583734] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"251.13947ms","start":"2026-04-13T14:14:58.438934Z","end":"2026-04-13T14:14:58.690073Z","steps":["trace[754583734] 'read index received' (duration: 228.736136ms)","trace[754583734] 'applied index is now lower than readState.Index' (duration: 22.402084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:14:58.690281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.336311ms","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-04-13T14:14:58.690312Z","caller":"traceutil/trace.go:171","msg":"trace[973726683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"251.419031ms","start":"2026-04-13T14:14:58.438884Z","end":"2026-04-13T14:14:58.690303Z","steps":["trace[973726683] 'agreement among raft nodes before linearized reading' (duration: 251.28041ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:59.387348Z","caller":"traceutil/trace.go:171","msg":"trace[1210975225] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"121.822043ms","start":"2026-04-13T14:14:59.265496Z","end":"2026-04-13T14:14:59.387318Z","steps":["trace[1210975225] 'process raft request' (duration: 92.282014ms)","trace[1210975225] 'compare' (duration: 29.277348ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:14:59.38736Z","caller":"traceutil/trace.go:171","msg":"trace[1335700] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1333; }","duration":"106.303221ms","start":"2026-04-13T14:14:59.281028Z","end":"2026-04-13T14:14:59.387331Z","steps":["trace[1335700] 'read index received' (duration: 76.836802ms)","trace[1335700] 'applied index is now lower than readState.Index' (duration: 29.465199ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:14:59.387481Z","caller":"traceutil/trace.go:171","msg":"trace[60721708] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"115.840133ms","start":"2026-04-13T14:14:59.271615Z","end":"2026-04-13T14:14:59.387456Z","steps":["trace[60721708] 'process raft request' (duration: 115.619433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:14:59.387589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.551392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T14:14:59.387625Z","caller":"traceutil/trace.go:171","msg":"trace[2008960393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1296; }","duration":"106.643362ms","start":"2026-04-13T14:14:59.28097Z","end":"2026-04-13T14:14:59.387613Z","steps":["trace[2008960393] 'agreement among raft nodes before linearized reading' (duration: 106.487391ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:14:59.790116Z","caller":"traceutil/trace.go:171","msg":"trace[371382941] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"107.388704ms","start":"2026-04-13T14:14:59.682703Z","end":"2026-04-13T14:14:59.790092Z","steps":["trace[371382941] 'process raft request' (duration: 101.004033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:07.648002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.590181ms","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-04-13T14:15:07.648118Z","caller":"traceutil/trace.go:171","msg":"trace[771350202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1439; }","duration":"140.768051ms","start":"2026-04-13T14:15:07.507333Z","end":"2026-04-13T14:15:07.648101Z","steps":["trace[771350202] 'range keys from in-memory index tree' (duration: 140.364021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:07.64838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.617267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T14:15:07.648408Z","caller":"traceutil/trace.go:171","msg":"trace[1394291807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1439; }","duration":"136.678847ms","start":"2026-04-13T14:15:07.51172Z","end":"2026-04-13T14:15:07.648399Z","steps":["trace[1394291807] 'range keys from in-memory index tree' (duration: 136.470877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:07.648553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.549078ms","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-04-13T14:15:07.648589Z","caller":"traceutil/trace.go:171","msg":"trace[387898364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"136.617948ms","start":"2026-04-13T14:15:07.511962Z","end":"2026-04-13T14:15:07.64858Z","steps":["trace[387898364] 'range keys from in-memory index tree' (duration: 136.437547ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:07.839077Z","caller":"traceutil/trace.go:171","msg":"trace[602082306] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"224.925937ms","start":"2026-04-13T14:15:07.614129Z","end":"2026-04-13T14:15:07.839054Z","steps":["trace[602082306] 'process raft request' (duration: 224.775997ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:07.839829Z","caller":"traceutil/trace.go:171","msg":"trace[35938832] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"185.505197ms","start":"2026-04-13T14:15:07.654309Z","end":"2026-04-13T14:15:07.839814Z","steps":["trace[35938832] 'process raft request' (duration: 185.442576ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:07.839848Z","caller":"traceutil/trace.go:171","msg":"trace[653402504] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"187.553715ms","start":"2026-04-13T14:15:07.652261Z","end":"2026-04-13T14:15:07.839814Z","steps":["trace[653402504] 'process raft request' (duration: 187.412984ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:07.840399Z","caller":"traceutil/trace.go:171","msg":"trace[1597538384] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"186.002938ms","start":"2026-04-13T14:15:07.654379Z","end":"2026-04-13T14:15:07.840382Z","steps":["trace[1597538384] 'process raft request' (duration: 185.403036ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:12.599062Z","caller":"traceutil/trace.go:171","msg":"trace[687060358] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"112.995789ms","start":"2026-04-13T14:15:12.486038Z","end":"2026-04-13T14:15:12.599034Z","steps":["trace[687060358] 'process raft request' (duration: 112.823828ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:12.874014Z","caller":"traceutil/trace.go:171","msg":"trace[1313005303] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"165.780701ms","start":"2026-04-13T14:15:12.70821Z","end":"2026-04-13T14:15:12.87399Z","steps":["trace[1313005303] 'process raft request' (duration: 164.696058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:21.578956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.021268ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:15:21.579059Z","caller":"traceutil/trace.go:171","msg":"trace[1871072628] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"101.519479ms","start":"2026-04-13T14:15:21.477515Z","end":"2026-04-13T14:15:21.579035Z","steps":["trace[1871072628] 'process raft request' (duration: 100.395565ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:21.579112Z","caller":"traceutil/trace.go:171","msg":"trace[294461934] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1684; }","duration":"104.200949ms","start":"2026-04-13T14:15:21.47489Z","end":"2026-04-13T14:15:21.579091Z","steps":["trace[294461934] 'range keys from in-memory index tree' (duration: 103.999678ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:27.37739Z","caller":"traceutil/trace.go:171","msg":"trace[907870333] linearizableReadLoop","detail":"{readStateIndex:1827; appliedIndex:1826; }","duration":"109.998575ms","start":"2026-04-13T14:15:27.267369Z","end":"2026-04-13T14:15:27.377367Z","steps":["trace[907870333] 'read index received' (duration: 107.034695ms)","trace[907870333] 'applied index is now lower than readState.Index' (duration: 2.96312ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:15:27.377484Z","caller":"traceutil/trace.go:171","msg":"trace[1084521912] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"125.195074ms","start":"2026-04-13T14:15:27.252282Z","end":"2026-04-13T14:15:27.377477Z","steps":["trace[1084521912] 'process raft request' (duration: 122.099374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:27.377626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.933866ms","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:5"} {"level":"info","ts":"2026-04-13T14:15:27.377692Z","caller":"traceutil/trace.go:171","msg":"trace[2130238056] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1759; }","duration":"166.021557ms","start":"2026-04-13T14:15:27.211654Z","end":"2026-04-13T14:15:27.377676Z","steps":["trace[2130238056] 'agreement among raft nodes before linearized reading' (duration: 165.915216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:27.377696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.101853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:15:27.377725Z","caller":"traceutil/trace.go:171","msg":"trace[1288629254] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1759; }","duration":"106.171704ms","start":"2026-04-13T14:15:27.271545Z","end":"2026-04-13T14:15:27.377717Z","steps":["trace[1288629254] 'agreement among raft nodes before linearized reading' (duration: 106.112694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:27.377859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.264194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:15:27.377886Z","caller":"traceutil/trace.go:171","msg":"trace[1835605206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1759; }","duration":"134.319414ms","start":"2026-04-13T14:15:27.243561Z","end":"2026-04-13T14:15:27.37788Z","steps":["trace[1835605206] 'agreement among raft nodes before linearized reading' (duration: 134.275984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:27.377831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.831493ms","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:5"} {"level":"warn","ts":"2026-04-13T14:15:27.378078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.338034ms","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:5"} {"level":"info","ts":"2026-04-13T14:15:27.37811Z","caller":"traceutil/trace.go:171","msg":"trace[575616511] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1759; }","duration":"134.163444ms","start":"2026-04-13T14:15:27.243855Z","end":"2026-04-13T14:15:27.378018Z","steps":["trace[575616511] 'agreement among raft nodes before linearized reading' (duration: 133.799633ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:27.378135Z","caller":"traceutil/trace.go:171","msg":"trace[1798919112] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1759; }","duration":"106.434704ms","start":"2026-04-13T14:15:27.271689Z","end":"2026-04-13T14:15:27.378124Z","steps":["trace[1798919112] 'agreement among raft nodes before linearized reading' (duration: 106.320104ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:27.804208Z","caller":"traceutil/trace.go:171","msg":"trace[324096951] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"265.865929ms","start":"2026-04-13T14:15:27.538304Z","end":"2026-04-13T14:15:27.80417Z","steps":["trace[324096951] 'process raft request' (duration: 265.692758ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:27.805332Z","caller":"traceutil/trace.go:171","msg":"trace[319057528] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"209.657597ms","start":"2026-04-13T14:15:27.595644Z","end":"2026-04-13T14:15:27.805302Z","steps":["trace[319057528] 'process raft request' (duration: 209.283606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:37.626982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948127703,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:15:37.87983Z","caller":"traceutil/trace.go:171","msg":"trace[1140793242] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"821.410432ms","start":"2026-04-13T14:15:37.058384Z","end":"2026-04-13T14:15:37.879794Z","steps":["trace[1140793242] 'process raft request' (duration: 821.234711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:37.880104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:37.058366Z","time spent":"821.561453ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:15:37.906102Z","caller":"traceutil/trace.go:171","msg":"trace[1002681007] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"846.278911ms","start":"2026-04-13T14:15:37.059798Z","end":"2026-04-13T14:15:37.906077Z","steps":["trace[1002681007] 'process raft request' (duration: 846.132021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:37.90624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:37.05978Z","time spent":"846.377592ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:15:37.906285Z","caller":"traceutil/trace.go:171","msg":"trace[92545525] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"543.286847ms","start":"2026-04-13T14:15:37.362977Z","end":"2026-04-13T14:15:37.906264Z","steps":["trace[92545525] 'process raft request' (duration: 543.068426ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:37.90635Z","caller":"traceutil/trace.go:171","msg":"trace[1265126234] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1950; }","duration":"780.21945ms","start":"2026-04-13T14:15:37.126111Z","end":"2026-04-13T14:15:37.90633Z","steps":["trace[1265126234] 'read index received' (duration: 753.595985ms)","trace[1265126234] 'applied index is now lower than readState.Index' (duration: 26.622415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:15:37.906404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:37.362952Z","time spent":"543.394797ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:15:37.906497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.392611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:15:37.906525Z","caller":"traceutil/trace.go:171","msg":"trace[233167471] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1884; }","duration":"780.421401ms","start":"2026-04-13T14:15:37.126095Z","end":"2026-04-13T14:15:37.906517Z","steps":["trace[233167471] 'agreement among raft nodes before linearized reading' (duration: 780.374321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:37.906547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:37.126019Z","time spent":"780.522131ms","remote":"127.0.0.1:33892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-13T14:15:37.906685Z","caller":"traceutil/trace.go:171","msg":"trace[1843425355] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"455.204413ms","start":"2026-04-13T14:15:37.451473Z","end":"2026-04-13T14:15:37.906677Z","steps":["trace[1843425355] 'process raft request' (duration: 454.698102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:37.90673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:37.45145Z","time spent":"455.258193ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:15:38.312666Z","caller":"traceutil/trace.go:171","msg":"trace[870298341] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"316.789539ms","start":"2026-04-13T14:15:37.99585Z","end":"2026-04-13T14:15:38.31264Z","steps":["trace[870298341] 'read index received' (duration: 316.579849ms)","trace[870298341] 'applied index is now lower than readState.Index' (duration: 208.74µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:15:38.312828Z","caller":"traceutil/trace.go:171","msg":"trace[1119815643] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"333.334843ms","start":"2026-04-13T14:15:37.979462Z","end":"2026-04-13T14:15:38.312797Z","steps":["trace[1119815643] 'process raft request' (duration: 332.963032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:38.312947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.07117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T14:15:38.312995Z","caller":"traceutil/trace.go:171","msg":"trace[1603705683] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1885; }","duration":"317.16693ms","start":"2026-04-13T14:15:37.995815Z","end":"2026-04-13T14:15:38.312982Z","steps":["trace[1603705683] 'agreement among raft nodes before linearized reading' (duration: 316.97278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:38.312994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:37.979439Z","time spent":"333.462193ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:15:38.313032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:37.995801Z","time spent":"317.22209ms","remote":"127.0.0.1:52492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-13T14:15:38.316974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.855872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T14:15:38.317161Z","caller":"traceutil/trace.go:171","msg":"trace[651686118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"262.033473ms","start":"2026-04-13T14:15:38.055083Z","end":"2026-04-13T14:15:38.317117Z","steps":["trace[651686118] 'agreement among raft nodes before linearized reading' (duration: 261.796272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:38.317061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.989963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T14:15:38.317496Z","caller":"traceutil/trace.go:171","msg":"trace[241400350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"262.422234ms","start":"2026-04-13T14:15:38.055035Z","end":"2026-04-13T14:15:38.317457Z","steps":["trace[241400350] 'agreement among raft nodes before linearized reading' (duration: 261.873142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:38.317015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.29588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T14:15:38.318021Z","caller":"traceutil/trace.go:171","msg":"trace[1497968651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1886; }","duration":"265.399223ms","start":"2026-04-13T14:15:38.052608Z","end":"2026-04-13T14:15:38.318007Z","steps":["trace[1497968651] 'agreement among raft nodes before linearized reading' (duration: 264.23028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:38.822708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.164831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:15:38.82329Z","caller":"traceutil/trace.go:171","msg":"trace[43320725] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"501.300483ms","start":"2026-04-13T14:15:38.321958Z","end":"2026-04-13T14:15:38.823258Z","steps":["trace[43320725] 'process raft request' (duration: 388.49873ms)","trace[43320725] 'compare' (duration: 111.83405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:15:38.823525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:38.321934Z","time spent":"501.439683ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:15:38.82357Z","caller":"traceutil/trace.go:171","msg":"trace[182497438] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"501.316012ms","start":"2026-04-13T14:15:38.322221Z","end":"2026-04-13T14:15:38.823537Z","steps":["trace[182497438] 'process raft request' (duration: 501.005961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:38.823789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:38.322204Z","time spent":"501.501943ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:15:38.995081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.463502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T14:15:38.995405Z","caller":"traceutil/trace.go:171","msg":"trace[1109072286] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1958; }","duration":"347.752438ms","start":"2026-04-13T14:15:38.647632Z","end":"2026-04-13T14:15:38.995385Z","steps":["trace[1109072286] 'read index received' (duration: 62.845581ms)","trace[1109072286] 'applied index is now lower than readState.Index' (duration: 284.904887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:15:38.995654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.994048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10327"} {"level":"info","ts":"2026-04-13T14:15:38.995722Z","caller":"traceutil/trace.go:171","msg":"trace[941283359] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1889; }","duration":"348.108249ms","start":"2026-04-13T14:15:38.647601Z","end":"2026-04-13T14:15:38.995709Z","steps":["trace[941283359] 'agreement among raft nodes before linearized reading' (duration: 347.868199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:38.995775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:38.647586Z","time spent":"348.18349ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10350,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-13T14:15:44.446344Z","caller":"traceutil/trace.go:171","msg":"trace[1995574454] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"103.956764ms","start":"2026-04-13T14:15:44.342355Z","end":"2026-04-13T14:15:44.446312Z","steps":["trace[1995574454] 'process raft request' (duration: 68.130249ms)","trace[1995574454] 'compare' (duration: 35.549504ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:15:50.877244Z","caller":"traceutil/trace.go:171","msg":"trace[1357951049] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"258.011257ms","start":"2026-04-13T14:15:50.619204Z","end":"2026-04-13T14:15:50.877215Z","steps":["trace[1357951049] 'process raft request' (duration: 257.779736ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:51.007303Z","caller":"traceutil/trace.go:171","msg":"trace[1622987930] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"112.674291ms","start":"2026-04-13T14:15:50.894596Z","end":"2026-04-13T14:15:51.007271Z","steps":["trace[1622987930] 'process raft request' (duration: 112.492831ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:52.084955Z","caller":"traceutil/trace.go:171","msg":"trace[2041257119] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"107.434584ms","start":"2026-04-13T14:15:51.977498Z","end":"2026-04-13T14:15:52.084932Z","steps":["trace[2041257119] 'process raft request' (duration: 107.398534ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:52.085377Z","caller":"traceutil/trace.go:171","msg":"trace[1514631617] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"128.603602ms","start":"2026-04-13T14:15:51.956763Z","end":"2026-04-13T14:15:52.085366Z","steps":["trace[1514631617] 'process raft request' (duration: 127.960819ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:52.085523Z","caller":"traceutil/trace.go:171","msg":"trace[1409625029] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"114.348376ms","start":"2026-04-13T14:15:51.971165Z","end":"2026-04-13T14:15:52.085514Z","steps":["trace[1409625029] 'process raft request' (duration: 113.684903ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:54.244779Z","caller":"traceutil/trace.go:171","msg":"trace[397935451] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"155.767708ms","start":"2026-04-13T14:15:54.088992Z","end":"2026-04-13T14:15:54.244759Z","steps":["trace[397935451] 'process raft request' (duration: 152.709398ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:54.247246Z","caller":"traceutil/trace.go:171","msg":"trace[1061503203] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"157.649344ms","start":"2026-04-13T14:15:54.089573Z","end":"2026-04-13T14:15:54.247222Z","steps":["trace[1061503203] 'process raft request' (duration: 157.547304ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:15:54.247406Z","caller":"traceutil/trace.go:171","msg":"trace[1516561990] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2093; }","duration":"151.254895ms","start":"2026-04-13T14:15:54.096133Z","end":"2026-04-13T14:15:54.247388Z","steps":["trace[1516561990] 'read index received' (duration: 145.580746ms)","trace[1516561990] 'applied index is now lower than readState.Index' (duration: 5.673199ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:15:54.247589Z","caller":"traceutil/trace.go:171","msg":"trace[1294437443] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"157.916745ms","start":"2026-04-13T14:15:54.089651Z","end":"2026-04-13T14:15:54.247568Z","steps":["trace[1294437443] 'process raft request' (duration: 157.539954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:54.247818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.662887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:15:54.247857Z","caller":"traceutil/trace.go:171","msg":"trace[525321627] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2020; }","duration":"151.759277ms","start":"2026-04-13T14:15:54.096088Z","end":"2026-04-13T14:15:54.247847Z","steps":["trace[525321627] 'agreement among raft nodes before linearized reading' (duration: 151.349885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:55.522104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948128014,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:15:55.888085Z","caller":"traceutil/trace.go:171","msg":"trace[381233920] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2099; }","duration":"866.724634ms","start":"2026-04-13T14:15:55.021339Z","end":"2026-04-13T14:15:55.888064Z","steps":["trace[381233920] 'read index received' (duration: 866.460604ms)","trace[381233920] 'applied index is now lower than readState.Index' (duration: 263.09µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:15:55.888252Z","caller":"traceutil/trace.go:171","msg":"trace[868632747] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"905.81361ms","start":"2026-04-13T14:15:54.982398Z","end":"2026-04-13T14:15:55.888212Z","steps":["trace[868632747] 'process raft request' (duration: 905.493839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:55.888368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.011655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T14:15:55.888406Z","caller":"traceutil/trace.go:171","msg":"trace[413762711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2024; }","duration":"867.101605ms","start":"2026-04-13T14:15:55.021293Z","end":"2026-04-13T14:15:55.888394Z","steps":["trace[413762711] 'agreement among raft nodes before linearized reading' (duration: 866.911465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:55.888437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:55.021274Z","time spent":"867.155575ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:15:55.888576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.762728ms","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-04-13T14:15:55.888635Z","caller":"traceutil/trace.go:171","msg":"trace[1669675928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2024; }","duration":"717.874778ms","start":"2026-04-13T14:15:55.170745Z","end":"2026-04-13T14:15:55.88862Z","steps":["trace[1669675928] 'agreement among raft nodes before linearized reading' (duration: 717.695428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:55.888663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:55.170725Z","time spent":"717.932608ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T14:15:55.888875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.75016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-13T14:15:55.88883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:54.982374Z","time spent":"905.99397ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:15:55.889051Z","caller":"traceutil/trace.go:171","msg":"trace[132997695] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2024; }","duration":"368.87155ms","start":"2026-04-13T14:15:55.520065Z","end":"2026-04-13T14:15:55.888937Z","steps":["trace[132997695] 'agreement among raft nodes before linearized reading' (duration: 368.442149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:15:55.889199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:15:55.520043Z","time spent":"369.06564ms","remote":"127.0.0.1:52492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-13T14:16:12.654005Z","caller":"traceutil/trace.go:171","msg":"trace[1642034488] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"121.298357ms","start":"2026-04-13T14:16:12.532682Z","end":"2026-04-13T14:16:12.653981Z","steps":["trace[1642034488] 'process raft request' (duration: 120.962916ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:18.127686Z","caller":"traceutil/trace.go:171","msg":"trace[1773296776] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"133.232556ms","start":"2026-04-13T14:16:17.994429Z","end":"2026-04-13T14:16:18.127661Z","steps":["trace[1773296776] 'process raft request' (duration: 133.084206ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:18.180701Z","caller":"traceutil/trace.go:171","msg":"trace[1603914786] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"186.064073ms","start":"2026-04-13T14:16:17.994606Z","end":"2026-04-13T14:16:18.18067Z","steps":["trace[1603914786] 'process raft request' (duration: 185.850453ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:18.197565Z","caller":"traceutil/trace.go:171","msg":"trace[196785480] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2226; }","duration":"190.811899ms","start":"2026-04-13T14:16:18.006736Z","end":"2026-04-13T14:16:18.197548Z","steps":["trace[196785480] 'read index received' (duration: 120.852056ms)","trace[196785480] 'applied index is now lower than readState.Index' (duration: 69.958803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:16:18.197944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.1993ms","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-04-13T14:16:18.19799Z","caller":"traceutil/trace.go:171","msg":"trace[1496336792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2148; }","duration":"191.28034ms","start":"2026-04-13T14:16:18.006699Z","end":"2026-04-13T14:16:18.19798Z","steps":["trace[1496336792] 'agreement among raft nodes before linearized reading' (duration: 190.952519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:18.198451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T14:16:18.198498Z","caller":"traceutil/trace.go:171","msg":"trace[962017413] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"153.58609ms","start":"2026-04-13T14:16:18.044901Z","end":"2026-04-13T14:16:18.198487Z","steps":["trace[962017413] 'agreement among raft nodes before linearized reading' (duration: 153.476569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:18.198936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.370933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:16:18.198984Z","caller":"traceutil/trace.go:171","msg":"trace[652232378] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2148; }","duration":"104.443213ms","start":"2026-04-13T14:16:18.094532Z","end":"2026-04-13T14:16:18.198975Z","steps":["trace[652232378] 'agreement among raft nodes before linearized reading' (duration: 104.362383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:18.199453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.336998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a5f03ab9501987\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-13T14:16:18.199498Z","caller":"traceutil/trace.go:171","msg":"trace[417286413] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a5f03ab9501987; range_end:; response_count:1; response_revision:2148; }","duration":"112.435759ms","start":"2026-04-13T14:16:18.087052Z","end":"2026-04-13T14:16:18.199488Z","steps":["trace[417286413] 'agreement among raft nodes before linearized reading' (duration: 112.325388ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:18.361962Z","caller":"traceutil/trace.go:171","msg":"trace[1044615429] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"157.875145ms","start":"2026-04-13T14:16:18.204064Z","end":"2026-04-13T14:16:18.36194Z","steps":["trace[1044615429] 'process raft request' (duration: 141.297472ms)","trace[1044615429] 'compare' (duration: 16.15273ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:16:18.362498Z","caller":"traceutil/trace.go:171","msg":"trace[602957108] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"156.364619ms","start":"2026-04-13T14:16:18.206091Z","end":"2026-04-13T14:16:18.362455Z","steps":["trace[602957108] 'process raft request' (duration: 155.786537ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:19.445036Z","caller":"traceutil/trace.go:171","msg":"trace[669370043] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"109.53156ms","start":"2026-04-13T14:16:19.335455Z","end":"2026-04-13T14:16:19.444987Z","steps":["trace[669370043] 'process raft request' (duration: 109.375609ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:20.462998Z","caller":"traceutil/trace.go:171","msg":"trace[1217609433] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"277.440405ms","start":"2026-04-13T14:16:20.185535Z","end":"2026-04-13T14:16:20.462975Z","steps":["trace[1217609433] 'read index received' (duration: 277.304864ms)","trace[1217609433] 'applied index is now lower than readState.Index' (duration: 134.501µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:16:20.463271Z","caller":"traceutil/trace.go:171","msg":"trace[1910421557] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"327.202044ms","start":"2026-04-13T14:16:20.136061Z","end":"2026-04-13T14:16:20.463263Z","steps":["trace[1910421557] 'process raft request' (duration: 326.792322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:20.463363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.136044Z","time spent":"327.252414ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:16:20.463573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.049376ms","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-04-13T14:16:20.463605Z","caller":"traceutil/trace.go:171","msg":"trace[433048776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2158; }","duration":"278.116076ms","start":"2026-04-13T14:16:20.18548Z","end":"2026-04-13T14:16:20.463596Z","steps":["trace[433048776] 'agreement among raft nodes before linearized reading' (duration: 278.015336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:20.963725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948128334,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T14:16:21.171569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.675974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:16:21.171754Z","caller":"traceutil/trace.go:171","msg":"trace[1160810841] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"785.832518ms","start":"2026-04-13T14:16:20.385904Z","end":"2026-04-13T14:16:21.171737Z","steps":["trace[1160810841] 'process raft request' (duration: 785.792167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.171829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.385876Z","time spent":"785.923448ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:16:21.172274Z","caller":"traceutil/trace.go:171","msg":"trace[1222826336] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"803.067212ms","start":"2026-04-13T14:16:20.369175Z","end":"2026-04-13T14:16:21.172242Z","steps":["trace[1222826336] 'process raft request' (duration: 802.47987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.172477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.369162Z","time spent":"803.239392ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:16:21.172422Z","caller":"traceutil/trace.go:171","msg":"trace[2102671203] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"804.119934ms","start":"2026-04-13T14:16:20.368269Z","end":"2026-04-13T14:16:21.172389Z","steps":["trace[2102671203] 'process raft request' (duration: 601.524038ms)","trace[2102671203] 'compare' (duration: 201.572254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:16:21.172759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.368111Z","time spent":"804.530106ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:16:21.177505Z","caller":"traceutil/trace.go:171","msg":"trace[1050067388] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2239; }","duration":"714.411869ms","start":"2026-04-13T14:16:20.463076Z","end":"2026-04-13T14:16:21.177487Z","steps":["trace[1050067388] 'read index received' (duration: 506.711256ms)","trace[1050067388] 'applied index is now lower than readState.Index' (duration: 207.699433ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:16:21.177602Z","caller":"traceutil/trace.go:171","msg":"trace[1754270712] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"481.320656ms","start":"2026-04-13T14:16:20.696234Z","end":"2026-04-13T14:16:21.177554Z","steps":["trace[1754270712] 'process raft request' (duration: 481.215366ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:21.177668Z","caller":"traceutil/trace.go:171","msg":"trace[1331009861] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"602.582513ms","start":"2026-04-13T14:16:20.575078Z","end":"2026-04-13T14:16:21.17766Z","steps":["trace[1331009861] 'process raft request' (duration: 602.331802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.177686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.058514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T14:16:21.177732Z","caller":"traceutil/trace.go:171","msg":"trace[305998129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2164; }","duration":"810.135914ms","start":"2026-04-13T14:16:20.367586Z","end":"2026-04-13T14:16:21.177722Z","steps":["trace[305998129] 'agreement among raft nodes before linearized reading' (duration: 809.997513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.177741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.696201Z","time spent":"481.471067ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:16:21.177757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.575048Z","time spent":"602.646943ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:16:21.177764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.367573Z","time spent":"810.183334ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T14:16:21.17761Z","caller":"traceutil/trace.go:171","msg":"trace[552899530] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"711.003248ms","start":"2026-04-13T14:16:20.466573Z","end":"2026-04-13T14:16:21.177576Z","steps":["trace[552899530] 'process raft request' (duration: 710.724578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.177871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.466555Z","time spent":"711.294689ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:16:21.177868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.008801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T14:16:21.177914Z","caller":"traceutil/trace.go:171","msg":"trace[116235038] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"966.077002ms","start":"2026-04-13T14:16:20.211822Z","end":"2026-04-13T14:16:21.177899Z","steps":["trace[116235038] 'agreement among raft nodes before linearized reading' (duration: 965.956652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.177933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.529419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-13T14:16:21.177962Z","caller":"traceutil/trace.go:171","msg":"trace[75224503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2164; }","duration":"811.609429ms","start":"2026-04-13T14:16:20.366344Z","end":"2026-04-13T14:16:21.177953Z","steps":["trace[75224503] 'agreement among raft nodes before linearized reading' (duration: 811.497209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.177962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.211811Z","time spent":"966.141522ms","remote":"127.0.0.1:52492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-13T14:16:21.17799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.366009Z","time spent":"811.97623ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T14:16:21.178032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.160606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T14:16:21.17806Z","caller":"traceutil/trace.go:171","msg":"trace[1117798081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2164; }","duration":"431.225666ms","start":"2026-04-13T14:16:20.746828Z","end":"2026-04-13T14:16:21.178053Z","steps":["trace[1117798081] 'agreement among raft nodes before linearized reading' (duration: 431.112435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.178079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.74681Z","time spent":"431.265226ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T14:16:21.178168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.521603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:16:21.178211Z","caller":"traceutil/trace.go:171","msg":"trace[256195985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2164; }","duration":"292.609843ms","start":"2026-04-13T14:16:20.885591Z","end":"2026-04-13T14:16:21.178201Z","steps":["trace[256195985] 'agreement among raft nodes before linearized reading' (duration: 292.547773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.178288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.182343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-13T14:16:21.178322Z","caller":"traceutil/trace.go:171","msg":"trace[552875766] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2164; }","duration":"283.268343ms","start":"2026-04-13T14:16:20.895046Z","end":"2026-04-13T14:16:21.178314Z","steps":["trace[552875766] 'agreement among raft nodes before linearized reading' (duration: 283.189993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.178177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.380755ms","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-04-13T14:16:21.178519Z","caller":"traceutil/trace.go:171","msg":"trace[790040327] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2164; }","duration":"371.603806ms","start":"2026-04-13T14:16:20.806756Z","end":"2026-04-13T14:16:21.17836Z","steps":["trace[790040327] 'agreement among raft nodes before linearized reading' (duration: 371.385545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.178577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:20.806742Z","time spent":"371.817706ms","remote":"127.0.0.1:57268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:16:21.720381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.552946ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:16:21.720535Z","caller":"traceutil/trace.go:171","msg":"trace[1992957596] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2164; }","duration":"236.715966ms","start":"2026-04-13T14:16:21.483792Z","end":"2026-04-13T14:16:21.720508Z","steps":["trace[1992957596] 'range keys from in-memory index tree' (duration: 236.537516ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:21.720993Z","caller":"traceutil/trace.go:171","msg":"trace[578281663] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"537.622316ms","start":"2026-04-13T14:16:21.18335Z","end":"2026-04-13T14:16:21.720972Z","steps":["trace[578281663] 'process raft request' (duration: 448.14284ms)","trace[578281663] 'compare' (duration: 88.735163ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:16:21.721126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:21.183333Z","time spent":"537.725736ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:16:21.725958Z","caller":"traceutil/trace.go:171","msg":"trace[259645016] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2245; }","duration":"294.354359ms","start":"2026-04-13T14:16:21.43158Z","end":"2026-04-13T14:16:21.725934Z","steps":["trace[259645016] 'read index received' (duration: 199.943138ms)","trace[259645016] 'applied index is now lower than readState.Index' (duration: 94.410341ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:16:21.726185Z","caller":"traceutil/trace.go:171","msg":"trace[1821990036] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"542.535971ms","start":"2026-04-13T14:16:21.18363Z","end":"2026-04-13T14:16:21.726166Z","steps":["trace[1821990036] 'process raft request' (duration: 542.226289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.726255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.704144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-13T14:16:21.726279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:21.1836Z","time spent":"542.634781ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:16:21.726307Z","caller":"traceutil/trace.go:171","msg":"trace[353415208] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2168; }","duration":"292.789865ms","start":"2026-04-13T14:16:21.433506Z","end":"2026-04-13T14:16:21.726296Z","steps":["trace[353415208] 'agreement among raft nodes before linearized reading' (duration: 292.690794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.726364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.774291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120559"} {"level":"info","ts":"2026-04-13T14:16:21.726469Z","caller":"traceutil/trace.go:171","msg":"trace[554025805] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2168; }","duration":"294.885411ms","start":"2026-04-13T14:16:21.431533Z","end":"2026-04-13T14:16:21.726419Z","steps":["trace[554025805] 'agreement among raft nodes before linearized reading' (duration: 294.62357ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:21.726379Z","caller":"traceutil/trace.go:171","msg":"trace[1031412798] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"542.855542ms","start":"2026-04-13T14:16:21.18351Z","end":"2026-04-13T14:16:21.726365Z","steps":["trace[1031412798] 'process raft request' (duration: 542.223329ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:16:21.726757Z","caller":"traceutil/trace.go:171","msg":"trace[1979005241] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"542.641331ms","start":"2026-04-13T14:16:21.184104Z","end":"2026-04-13T14:16:21.726745Z","steps":["trace[1979005241] 'process raft request' (duration: 541.794197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:16:21.726841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:21.184085Z","time spent":"542.716902ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:16:21.726921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:16:21.183493Z","time spent":"543.239903ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:16:22.37083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.445285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T14:16:22.370906Z","caller":"traceutil/trace.go:171","msg":"trace[762194369] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2168; }","duration":"183.562016ms","start":"2026-04-13T14:16:22.18732Z","end":"2026-04-13T14:16:22.370882Z","steps":["trace[762194369] 'range keys from in-memory index tree' (duration: 183.269215ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:17:07.009897Z","caller":"traceutil/trace.go:171","msg":"trace[1166843717] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"252.631763ms","start":"2026-04-13T14:17:06.757245Z","end":"2026-04-13T14:17:07.009876Z","steps":["trace[1166843717] 'process raft request' (duration: 252.506783ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:17:16.968702Z","caller":"traceutil/trace.go:171","msg":"trace[844117849] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"208.563365ms","start":"2026-04-13T14:17:16.760106Z","end":"2026-04-13T14:17:16.968669Z","steps":["trace[844117849] 'process raft request' (duration: 207.927283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:43.26383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.708972ms","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-04-13T14:17:43.264055Z","caller":"traceutil/trace.go:171","msg":"trace[1845066110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2711; }","duration":"167.816043ms","start":"2026-04-13T14:17:43.096075Z","end":"2026-04-13T14:17:43.263891Z","steps":["trace[1845066110] 'range keys from in-memory index tree' (duration: 167.578192ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:17:49.637771Z","caller":"traceutil/trace.go:171","msg":"trace[1265253064] linearizableReadLoop","detail":"{readStateIndex:2844; appliedIndex:2843; }","duration":"275.217785ms","start":"2026-04-13T14:17:49.362527Z","end":"2026-04-13T14:17:49.637745Z","steps":["trace[1265253064] 'read index received' (duration: 275.129975ms)","trace[1265253064] 'applied index is now lower than readState.Index' (duration: 86.3µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:17:49.638011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.462156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a5f04ed05105de\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-13T14:17:49.638049Z","caller":"traceutil/trace.go:171","msg":"trace[399022006] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a5f04ed05105de; range_end:; response_count:1; response_revision:2742; }","duration":"275.545446ms","start":"2026-04-13T14:17:49.362492Z","end":"2026-04-13T14:17:49.638038Z","steps":["trace[399022006] 'agreement among raft nodes before linearized reading' (duration: 275.371665ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:17:49.637994Z","caller":"traceutil/trace.go:171","msg":"trace[234736050] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"325.824456ms","start":"2026-04-13T14:17:49.312059Z","end":"2026-04-13T14:17:49.637883Z","steps":["trace[234736050] 'process raft request' (duration: 325.497675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:49.63926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:49.312041Z","time spent":"326.981619ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:17:50.138734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948129747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T14:17:50.639519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948129747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T14:17:50.833526Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.195980905s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T14:17:50.834151Z","caller":"traceutil/trace.go:171","msg":"trace[52805999] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"1.292571333s","start":"2026-04-13T14:17:49.54156Z","end":"2026-04-13T14:17:50.834131Z","steps":["trace[52805999] 'process raft request' (duration: 1.292488133s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.834365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:49.541547Z","time spent":"1.292725204s","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:17:50.834493Z","caller":"traceutil/trace.go:171","msg":"trace[47676359] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"1.299527614s","start":"2026-04-13T14:17:49.534956Z","end":"2026-04-13T14:17:50.834483Z","steps":["trace[47676359] 'process raft request' (duration: 1.298938772s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.834628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:49.534923Z","time spent":"1.299620065s","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:17:50.83442Z","caller":"traceutil/trace.go:171","msg":"trace[19041984] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"1.289636633s","start":"2026-04-13T14:17:49.544767Z","end":"2026-04-13T14:17:50.834404Z","steps":["trace[19041984] 'process raft request' (duration: 1.289320602s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.835002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:49.544737Z","time spent":"1.290234554s","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:17:50.834857Z","caller":"traceutil/trace.go:171","msg":"trace[577801517] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"1.279159579s","start":"2026-04-13T14:17:49.555691Z","end":"2026-04-13T14:17:50.834851Z","steps":["trace[577801517] 'process raft request' (duration: 1.278646458s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.835244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:49.55568Z","time spent":"1.2794434s","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:17:50.859898Z","caller":"traceutil/trace.go:171","msg":"trace[62044607] linearizableReadLoop","detail":"{readStateIndex:2849; appliedIndex:2844; }","duration":"1.222036628s","start":"2026-04-13T14:17:49.637838Z","end":"2026-04-13T14:17:50.859875Z","steps":["trace[62044607] 'read index received' (duration: 1.196031325s)","trace[62044607] 'applied index is now lower than readState.Index' (duration: 26.004033ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:17:50.859995Z","caller":"traceutil/trace.go:171","msg":"trace[449532254] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"1.219899291s","start":"2026-04-13T14:17:49.640085Z","end":"2026-04-13T14:17:50.859984Z","steps":["trace[449532254] 'process raft request' (duration: 1.21962335s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.860158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:49.640064Z","time spent":"1.219962081s","remote":"127.0.0.1:56974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:17:50.860408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273404791s","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-04-13T14:17:50.860542Z","caller":"traceutil/trace.go:171","msg":"trace[1408233801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2747; }","duration":"1.273617012s","start":"2026-04-13T14:17:49.586914Z","end":"2026-04-13T14:17:50.860531Z","steps":["trace[1408233801] 'agreement among raft nodes before linearized reading' (duration: 1.27319595s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.860687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:49.586886Z","time spent":"1.273791712s","remote":"127.0.0.1:57122","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-04-13T14:17:50.92606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.841989ms","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-04-13T14:17:50.926129Z","caller":"traceutil/trace.go:171","msg":"trace[308675486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2749; }","duration":"750.956109ms","start":"2026-04-13T14:17:50.175157Z","end":"2026-04-13T14:17:50.926113Z","steps":["trace[308675486] 'agreement among raft nodes before linearized reading' (duration: 750.809518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.926316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:50.175135Z","time spent":"751.023069ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:17:50.926338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.025944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-13T14:17:50.92653Z","caller":"traceutil/trace.go:171","msg":"trace[1752183631] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2749; }","duration":"853.255935ms","start":"2026-04-13T14:17:50.07326Z","end":"2026-04-13T14:17:50.926516Z","steps":["trace[1752183631] 'agreement among raft nodes before linearized reading' (duration: 852.769524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.926833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:50.073152Z","time spent":"853.666726ms","remote":"127.0.0.1:57062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-13T14:17:50.926589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.302459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T14:17:50.926962Z","caller":"traceutil/trace.go:171","msg":"trace[1958760140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2749; }","duration":"741.71372ms","start":"2026-04-13T14:17:50.185214Z","end":"2026-04-13T14:17:50.926928Z","steps":["trace[1958760140] 'agreement among raft nodes before linearized reading' (duration: 741.089688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.926606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.659579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-13T14:17:50.927054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:50.185205Z","time spent":"741.81469ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T14:17:50.927058Z","caller":"traceutil/trace.go:171","msg":"trace[974421941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2749; }","duration":"745.17116ms","start":"2026-04-13T14:17:50.181859Z","end":"2026-04-13T14:17:50.92703Z","steps":["trace[974421941] 'agreement among raft nodes before linearized reading' (duration: 744.483688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:17:50.927339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:17:50.18184Z","time spent":"745.379471ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T14:21:24.920745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.955058ms","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-04-13T14:21:24.920838Z","caller":"traceutil/trace.go:171","msg":"trace[615425684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4066; }","duration":"169.101038ms","start":"2026-04-13T14:21:24.751717Z","end":"2026-04-13T14:21:24.920818Z","steps":["trace[615425684] 'range keys from in-memory index tree' (duration: 168.766156ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:33.450022Z","caller":"traceutil/trace.go:171","msg":"trace[1943606533] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"103.078099ms","start":"2026-04-13T14:21:33.346923Z","end":"2026-04-13T14:21:33.450001Z","steps":["trace[1943606533] 'process raft request' (duration: 102.999448ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:33.45054Z","caller":"traceutil/trace.go:171","msg":"trace[1290366297] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"105.358295ms","start":"2026-04-13T14:21:33.345172Z","end":"2026-04-13T14:21:33.45053Z","steps":["trace[1290366297] 'process raft request' (duration: 104.049071ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:33.450527Z","caller":"traceutil/trace.go:171","msg":"trace[602649940] linearizableReadLoop","detail":"{readStateIndex:4695; appliedIndex:4694; }","duration":"103.76046ms","start":"2026-04-13T14:21:33.346734Z","end":"2026-04-13T14:21:33.450494Z","steps":["trace[602649940] 'read index received' (duration: 102.456166ms)","trace[602649940] 'applied index is now lower than readState.Index' (duration: 1.301484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:33.450798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.046121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-32c6ae85-7da5-4b38-be6d-f75cc6c4b62d\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-04-13T14:21:33.450858Z","caller":"traceutil/trace.go:171","msg":"trace[1178541140] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-32c6ae85-7da5-4b38-be6d-f75cc6c4b62d; range_end:; response_count:1; response_revision:4523; }","duration":"104.137351ms","start":"2026-04-13T14:21:33.346707Z","end":"2026-04-13T14:21:33.450844Z","steps":["trace[1178541140] 'agreement among raft nodes before linearized reading' (duration: 103.95128ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:35.925509Z","caller":"traceutil/trace.go:171","msg":"trace[1673166774] linearizableReadLoop","detail":"{readStateIndex:4736; appliedIndex:4735; }","duration":"148.596272ms","start":"2026-04-13T14:21:35.776891Z","end":"2026-04-13T14:21:35.925487Z","steps":["trace[1673166774] 'read index received' (duration: 120.210923ms)","trace[1673166774] 'applied index is now lower than readState.Index' (duration: 28.384289ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:21:35.925785Z","caller":"traceutil/trace.go:171","msg":"trace[190545295] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"179.103489ms","start":"2026-04-13T14:21:35.746664Z","end":"2026-04-13T14:21:35.925768Z","steps":["trace[190545295] 'process raft request' (duration: 150.423069ms)","trace[190545295] 'compare' (duration: 28.098389ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:35.926073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.131704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-32c6ae85-7da5-4b38-be6d-f75cc6c4b62d\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-04-13T14:21:35.926191Z","caller":"traceutil/trace.go:171","msg":"trace[793820016] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-32c6ae85-7da5-4b38-be6d-f75cc6c4b62d; range_end:; response_count:1; response_revision:4562; }","duration":"149.360994ms","start":"2026-04-13T14:21:35.776812Z","end":"2026-04-13T14:21:35.926173Z","steps":["trace[793820016] 'agreement among raft nodes before linearized reading' (duration: 148.979273ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:35.929324Z","caller":"traceutil/trace.go:171","msg":"trace[1294314938] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"113.32937ms","start":"2026-04-13T14:21:35.815968Z","end":"2026-04-13T14:21:35.929298Z","steps":["trace[1294314938] 'process raft request' (duration: 112.730679ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:35.92964Z","caller":"traceutil/trace.go:171","msg":"trace[1638241250] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"111.333263ms","start":"2026-04-13T14:21:35.818291Z","end":"2026-04-13T14:21:35.929625Z","steps":["trace[1638241250] 'process raft request' (duration: 110.549621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:38.883462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.870584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:21:38.883543Z","caller":"traceutil/trace.go:171","msg":"trace[74353428] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4639; }","duration":"212.020974ms","start":"2026-04-13T14:21:38.671504Z","end":"2026-04-13T14:21:38.883525Z","steps":["trace[74353428] 'count revisions from in-memory index tree' (duration: 211.772494ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:39.514127Z","caller":"traceutil/trace.go:171","msg":"trace[122012639] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"547.597432ms","start":"2026-04-13T14:21:38.966508Z","end":"2026-04-13T14:21:39.514105Z","steps":["trace[122012639] 'process raft request' (duration: 547.151731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:39.514268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:38.9665Z","time spent":"547.694102ms","remote":"127.0.0.1:57038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:21:39.514312Z","caller":"traceutil/trace.go:171","msg":"trace[974005007] linearizableReadLoop","detail":"{readStateIndex:4821; appliedIndex:4819; }","duration":"507.247373ms","start":"2026-04-13T14:21:39.007029Z","end":"2026-04-13T14:21:39.514277Z","steps":["trace[974005007] 'read index received' (duration: 47.19529ms)","trace[974005007] 'applied index is now lower than readState.Index' (duration: 460.050473ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:21:39.51442Z","caller":"traceutil/trace.go:171","msg":"trace[1788228624] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"545.018584ms","start":"2026-04-13T14:21:38.969389Z","end":"2026-04-13T14:21:39.514408Z","steps":["trace[1788228624] 'process raft request' (duration: 544.656043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:39.514537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:38.969383Z","time spent":"545.061644ms","remote":"127.0.0.1:57130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:21:39.51469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.666754ms","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-04-13T14:21:39.514729Z","caller":"traceutil/trace.go:171","msg":"trace[222961194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4643; }","duration":"507.751025ms","start":"2026-04-13T14:21:39.006967Z","end":"2026-04-13T14:21:39.514719Z","steps":["trace[222961194] 'agreement among raft nodes before linearized reading' (duration: 507.550844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:39.514759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:39.006943Z","time spent":"507.809175ms","remote":"127.0.0.1:57122","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-04-13T14:21:39.594211Z","caller":"traceutil/trace.go:171","msg":"trace[1206971734] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"532.963515ms","start":"2026-04-13T14:21:39.06122Z","end":"2026-04-13T14:21:39.594184Z","steps":["trace[1206971734] 'process raft request' (duration: 532.447864ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:39.594433Z","caller":"traceutil/trace.go:171","msg":"trace[410862369] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"175.612969ms","start":"2026-04-13T14:21:39.418782Z","end":"2026-04-13T14:21:39.594395Z","steps":["trace[410862369] 'process raft request' (duration: 175.355957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:39.59448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:39.061197Z","time spent":"533.101906ms","remote":"127.0.0.1:57282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5114,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:21:39.594515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.907554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-w5wmb\" ","response":"range_response_count:1 size:9633"} {"level":"info","ts":"2026-04-13T14:21:39.594581Z","caller":"traceutil/trace.go:171","msg":"trace[1001787358] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-w5wmb; range_end:; response_count:1; response_revision:4645; }","duration":"177.016564ms","start":"2026-04-13T14:21:39.417548Z","end":"2026-04-13T14:21:39.594564Z","steps":["trace[1001787358] 'agreement among raft nodes before linearized reading' (duration: 176.801344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:45.689016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.540977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-13T14:21:45.68913Z","caller":"traceutil/trace.go:171","msg":"trace[1591230113] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4748; }","duration":"379.758517ms","start":"2026-04-13T14:21:45.309345Z","end":"2026-04-13T14:21:45.689104Z","steps":["trace[1591230113] 'range keys from in-memory index tree' (duration: 378.200983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:45.689179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.309315Z","time spent":"379.851308ms","remote":"127.0.0.1:56982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2193188,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-04-13T14:21:46.131743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.904485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:21:46.131919Z","caller":"traceutil/trace.go:171","msg":"trace[1968891225] linearizableReadLoop","detail":"{readStateIndex:4930; appliedIndex:4929; }","duration":"432.957427ms","start":"2026-04-13T14:21:45.698944Z","end":"2026-04-13T14:21:46.131902Z","steps":["trace[1968891225] 'read index received' (duration: 138.867661ms)","trace[1968891225] 'applied index is now lower than readState.Index' (duration: 294.088476ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:21:46.132002Z","caller":"traceutil/trace.go:171","msg":"trace[1714078638] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"436.662289ms","start":"2026-04-13T14:21:45.695328Z","end":"2026-04-13T14:21:46.13199Z","steps":["trace[1714078638] 'process raft request' (duration: 142.423353ms)","trace[1714078638] 'compare' (duration: 293.650134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:46.132063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.695307Z","time spent":"436.725479ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:21:46.132246Z","caller":"traceutil/trace.go:171","msg":"trace[234243905] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"327.776193ms","start":"2026-04-13T14:21:45.80444Z","end":"2026-04-13T14:21:46.132216Z","steps":["trace[234243905] 'process raft request' (duration: 327.670842ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:46.132429Z","caller":"traceutil/trace.go:171","msg":"trace[756761428] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"431.481832ms","start":"2026-04-13T14:21:45.700932Z","end":"2026-04-13T14:21:46.132413Z","steps":["trace[756761428] 'process raft request' (duration: 430.918091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.132441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.8044Z","time spent":"327.953813ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:21:46.13252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.700921Z","time spent":"431.545252ms","remote":"127.0.0.1:56974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:21:46.132805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.83656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-04-13T14:21:46.132897Z","caller":"traceutil/trace.go:171","msg":"trace[251485042] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4752; }","duration":"433.9726ms","start":"2026-04-13T14:21:45.698903Z","end":"2026-04-13T14:21:46.132875Z","steps":["trace[251485042] 'agreement among raft nodes before linearized reading' (duration: 433.71924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.132944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.698884Z","time spent":"434.051031ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12997,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-13T14:21:46.198566Z","caller":"traceutil/trace.go:171","msg":"trace[515991711] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"232.70723ms","start":"2026-04-13T14:21:45.965825Z","end":"2026-04-13T14:21:46.198532Z","steps":["trace[515991711] 'process raft request' (duration: 232.443589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.198858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.819796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:21:46.19891Z","caller":"traceutil/trace.go:171","msg":"trace[692717473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4755; }","duration":"312.942296ms","start":"2026-04-13T14:21:45.885956Z","end":"2026-04-13T14:21:46.198899Z","steps":["trace[692717473] 'agreement among raft nodes before linearized reading' (duration: 312.844426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.198902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.709003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T14:21:46.198964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.584678ms","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-04-13T14:21:46.19899Z","caller":"traceutil/trace.go:171","msg":"trace[2002423652] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"228.177525ms","start":"2026-04-13T14:21:45.970798Z","end":"2026-04-13T14:21:46.198976Z","steps":["trace[2002423652] 'process raft request' (duration: 227.908114ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:46.199028Z","caller":"traceutil/trace.go:171","msg":"trace[337732308] 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:4755; }","duration":"464.675198ms","start":"2026-04-13T14:21:45.734333Z","end":"2026-04-13T14:21:46.199008Z","steps":["trace[337732308] 'agreement among raft nodes before linearized reading' (duration: 464.492738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.199056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.080807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-13T14:21:46.198947Z","caller":"traceutil/trace.go:171","msg":"trace[1262276749] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"231.972448ms","start":"2026-04-13T14:21:45.966946Z","end":"2026-04-13T14:21:46.198918Z","steps":["trace[1262276749] 'process raft request' (duration: 231.541607ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:46.199078Z","caller":"traceutil/trace.go:171","msg":"trace[142855336] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4755; }","duration":"477.135517ms","start":"2026-04-13T14:21:45.721933Z","end":"2026-04-13T14:21:46.199069Z","steps":["trace[142855336] 'agreement among raft nodes before linearized reading' (duration: 477.022497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.19908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.734321Z","time spent":"464.747128ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-13T14:21:46.199094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.721915Z","time spent":"477.175528ms","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-04-13T14:21:46.198986Z","caller":"traceutil/trace.go:171","msg":"trace[828467361] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4755; }","duration":"104.841063ms","start":"2026-04-13T14:21:46.094128Z","end":"2026-04-13T14:21:46.198969Z","steps":["trace[828467361] 'agreement among raft nodes before linearized reading' (duration: 104.665473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.199135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.635704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed439df0-d7bc-4872-9d5d-77371c5e7d6f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-13T14:21:46.199167Z","caller":"traceutil/trace.go:171","msg":"trace[1443309958] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed439df0-d7bc-4872-9d5d-77371c5e7d6f; range_end:; response_count:1; response_revision:4755; }","duration":"450.680214ms","start":"2026-04-13T14:21:45.748477Z","end":"2026-04-13T14:21:46.199157Z","steps":["trace[1443309958] 'agreement among raft nodes before linearized reading' (duration: 450.542283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.199209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.74847Z","time spent":"450.730794ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed439df0-d7bc-4872-9d5d-77371c5e7d6f\" "} {"level":"warn","ts":"2026-04-13T14:21:46.198937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:45.885909Z","time spent":"313.023306ms","remote":"127.0.0.1:56880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T14:21:46.265441Z","caller":"traceutil/trace.go:171","msg":"trace[312948981] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"117.527754ms","start":"2026-04-13T14:21:46.147889Z","end":"2026-04-13T14:21:46.265417Z","steps":["trace[312948981] 'process raft request' (duration: 90.216097ms)","trace[312948981] 'compare' (duration: 26.924266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:46.26586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.295741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5d8dbdd9fb-7g4zj.18a5f08a352c4208\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-13T14:21:46.265915Z","caller":"traceutil/trace.go:171","msg":"trace[519421008] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5d8dbdd9fb-7g4zj.18a5f08a352c4208; range_end:; response_count:1; response_revision:4756; }","duration":"129.410571ms","start":"2026-04-13T14:21:46.13649Z","end":"2026-04-13T14:21:46.265901Z","steps":["trace[519421008] 'agreement among raft nodes before linearized reading' (duration: 129.16843ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:46.919389Z","caller":"traceutil/trace.go:171","msg":"trace[868362712] linearizableReadLoop","detail":"{readStateIndex:4947; appliedIndex:4946; }","duration":"434.921393ms","start":"2026-04-13T14:21:46.484444Z","end":"2026-04-13T14:21:46.919365Z","steps":["trace[868362712] 'read index received' (duration: 434.626272ms)","trace[868362712] 'applied index is now lower than readState.Index' (duration: 293.631µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:21:46.919589Z","caller":"traceutil/trace.go:171","msg":"trace[1093123841] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"485.401204ms","start":"2026-04-13T14:21:46.434052Z","end":"2026-04-13T14:21:46.919454Z","steps":["trace[1093123841] 'process raft request' (duration: 485.080313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.919675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.237974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-13T14:21:46.919733Z","caller":"traceutil/trace.go:171","msg":"trace[1268963495] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4767; }","duration":"435.333354ms","start":"2026-04-13T14:21:46.484387Z","end":"2026-04-13T14:21:46.91972Z","steps":["trace[1268963495] 'agreement among raft nodes before linearized reading' (duration: 435.103064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.919778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:46.48437Z","time spent":"435.398835ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-13T14:21:46.91987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:46.434036Z","time spent":"485.640884ms","remote":"127.0.0.1:47954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:21:46.919883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.43201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:21:46.920014Z","caller":"traceutil/trace.go:171","msg":"trace[732371908] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4767; }","duration":"147.61195ms","start":"2026-04-13T14:21:46.772388Z","end":"2026-04-13T14:21:46.92Z","steps":["trace[732371908] 'agreement among raft nodes before linearized reading' (duration: 147.346549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.919883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.39867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-13T14:21:46.920267Z","caller":"traceutil/trace.go:171","msg":"trace[780345631] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4767; }","duration":"144.767551ms","start":"2026-04-13T14:21:46.775444Z","end":"2026-04-13T14:21:46.920211Z","steps":["trace[780345631] 'agreement among raft nodes before linearized reading' (duration: 144.244428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:46.919648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.310502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16086"} {"level":"info","ts":"2026-04-13T14:21:46.921501Z","caller":"traceutil/trace.go:171","msg":"trace[1041057083] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4767; }","duration":"210.036748ms","start":"2026-04-13T14:21:46.71126Z","end":"2026-04-13T14:21:46.921297Z","steps":["trace[1041057083] 'agreement among raft nodes before linearized reading' (duration: 208.258322ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:47.072414Z","caller":"traceutil/trace.go:171","msg":"trace[683373505] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"137.890078ms","start":"2026-04-13T14:21:46.934485Z","end":"2026-04-13T14:21:47.072376Z","steps":["trace[683373505] 'process raft request' (duration: 86.496425ms)","trace[683373505] 'compare' (duration: 50.806542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:47.343732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.026322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed439df0-d7bc-4872-9d5d-77371c5e7d6f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-13T14:21:47.343822Z","caller":"traceutil/trace.go:171","msg":"trace[94371204] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed439df0-d7bc-4872-9d5d-77371c5e7d6f; range_end:; response_count:1; response_revision:4772; }","duration":"139.203473ms","start":"2026-04-13T14:21:47.204597Z","end":"2026-04-13T14:21:47.343801Z","steps":["trace[94371204] 'range keys from in-memory index tree' (duration: 138.785102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:47.344535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.209542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-13T14:21:47.344618Z","caller":"traceutil/trace.go:171","msg":"trace[2032568564] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4772; }","duration":"167.345313ms","start":"2026-04-13T14:21:47.177254Z","end":"2026-04-13T14:21:47.3446Z","steps":["trace[2032568564] 'range keys from in-memory index tree' (duration: 166.095349ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:48.541884Z","caller":"traceutil/trace.go:171","msg":"trace[524810472] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"177.251954ms","start":"2026-04-13T14:21:48.364613Z","end":"2026-04-13T14:21:48.541865Z","steps":["trace[524810472] 'process raft request' (duration: 177.154184ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:48.685556Z","caller":"traceutil/trace.go:171","msg":"trace[1886670827] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"318.806305ms","start":"2026-04-13T14:21:48.366735Z","end":"2026-04-13T14:21:48.685541Z","steps":["trace[1886670827] 'process raft request' (duration: 318.739185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:48.685706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:48.366724Z","time spent":"318.917975ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:21:48.685557Z","caller":"traceutil/trace.go:171","msg":"trace[900897326] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"319.954168ms","start":"2026-04-13T14:21:48.365579Z","end":"2026-04-13T14:21:48.685533Z","steps":["trace[900897326] 'process raft request' (duration: 319.733027ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:48.685849Z","caller":"traceutil/trace.go:171","msg":"trace[112914699] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"318.752905ms","start":"2026-04-13T14:21:48.367084Z","end":"2026-04-13T14:21:48.685837Z","steps":["trace[112914699] 'process raft request' (duration: 318.423084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:48.685899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:48.367071Z","time spent":"318.805405ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:21:48.685931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:48.365565Z","time spent":"320.274169ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:21:48.68762Z","caller":"traceutil/trace.go:171","msg":"trace[1774725863] linearizableReadLoop","detail":"{readStateIndex:4969; appliedIndex:4965; }","duration":"118.289537ms","start":"2026-04-13T14:21:48.569319Z","end":"2026-04-13T14:21:48.687608Z","steps":["trace[1774725863] 'read index received' (duration: 115.896109ms)","trace[1774725863] 'applied index is now lower than readState.Index' (duration: 2.392738ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:48.687685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.370237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:21:48.687711Z","caller":"traceutil/trace.go:171","msg":"trace[406440986] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4788; }","duration":"118.443637ms","start":"2026-04-13T14:21:48.569261Z","end":"2026-04-13T14:21:48.687705Z","steps":["trace[406440986] 'agreement among raft nodes before linearized reading' (duration: 118.389027ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:56.35271Z","caller":"traceutil/trace.go:171","msg":"trace[1220448258] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"118.207936ms","start":"2026-04-13T14:21:56.234466Z","end":"2026-04-13T14:21:56.352673Z","steps":["trace[1220448258] 'process raft request' (duration: 118.088345ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:56.353533Z","caller":"traceutil/trace.go:171","msg":"trace[91777332] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"102.413395ms","start":"2026-04-13T14:21:56.251102Z","end":"2026-04-13T14:21:56.353516Z","steps":["trace[91777332] 'process raft request' (duration: 102.372375ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:56.353569Z","caller":"traceutil/trace.go:171","msg":"trace[1591050094] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"108.322225ms","start":"2026-04-13T14:21:56.245207Z","end":"2026-04-13T14:21:56.353529Z","steps":["trace[1591050094] 'process raft request' (duration: 108.197555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:00.770792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033910502948135036,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:22:00.873471Z","caller":"traceutil/trace.go:171","msg":"trace[573971314] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"665.880308ms","start":"2026-04-13T14:22:00.207569Z","end":"2026-04-13T14:22:00.873449Z","steps":["trace[573971314] 'process raft request' (duration: 665.717658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:00.873606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.207551Z","time spent":"665.982878ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:01.195135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.655401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:22:01.195558Z","caller":"traceutil/trace.go:171","msg":"trace[1879502502] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5120; }","duration":"925.150373ms","start":"2026-04-13T14:22:00.270396Z","end":"2026-04-13T14:22:01.195546Z","steps":["trace[1879502502] 'read index received' (duration: 602.897668ms)","trace[1879502502] 'applied index is now lower than readState.Index' (duration: 322.251875ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:22:01.195694Z","caller":"traceutil/trace.go:171","msg":"trace[1102903481] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"832.375687ms","start":"2026-04-13T14:22:00.363311Z","end":"2026-04-13T14:22:01.195687Z","steps":["trace[1102903481] 'process raft request' (duration: 517.103994ms)","trace[1102903481] 'compare' (duration: 314.3295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:22:01.195777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.363285Z","time spent":"832.445707ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:22:01.195966Z","caller":"traceutil/trace.go:171","msg":"trace[350666639] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"830.835953ms","start":"2026-04-13T14:22:00.365124Z","end":"2026-04-13T14:22:01.19596Z","steps":["trace[350666639] 'process raft request' (duration: 830.367032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.196024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.365104Z","time spent":"830.901973ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:22:01.19611Z","caller":"traceutil/trace.go:171","msg":"trace[577007747] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"827.594112ms","start":"2026-04-13T14:22:00.368511Z","end":"2026-04-13T14:22:01.196105Z","steps":["trace[577007747] 'process raft request' (duration: 827.013891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.196161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.368486Z","time spent":"827.656423ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:01.196324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.939305ms","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-04-13T14:22:01.196396Z","caller":"traceutil/trace.go:171","msg":"trace[742847879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4938; }","duration":"926.055156ms","start":"2026-04-13T14:22:00.270335Z","end":"2026-04-13T14:22:01.19639Z","steps":["trace[742847879] 'agreement among raft nodes before linearized reading' (duration: 925.901386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.196424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.270317Z","time spent":"926.101656ms","remote":"127.0.0.1:57122","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-04-13T14:22:01.208217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.095681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13845"} {"level":"info","ts":"2026-04-13T14:22:01.208273Z","caller":"traceutil/trace.go:171","msg":"trace[1271668459] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4939; }","duration":"368.187001ms","start":"2026-04-13T14:22:00.84007Z","end":"2026-04-13T14:22:01.208257Z","steps":["trace[1271668459] 'agreement among raft nodes before linearized reading' (duration: 368.04247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.208334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.840059Z","time spent":"368.267031ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13868,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-13T14:22:01.208604Z","caller":"traceutil/trace.go:171","msg":"trace[1549056481] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"142.470544ms","start":"2026-04-13T14:22:01.066122Z","end":"2026-04-13T14:22:01.208592Z","steps":["trace[1549056481] 'process raft request' (duration: 141.843212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.208841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.969768ms","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":"info","ts":"2026-04-13T14:22:01.208882Z","caller":"traceutil/trace.go:171","msg":"trace[1638987552] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4939; }","duration":"355.099529ms","start":"2026-04-13T14:22:00.85377Z","end":"2026-04-13T14:22:01.20887Z","steps":["trace[1638987552] 'agreement among raft nodes before linearized reading' (duration: 354.947118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.208916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.853755Z","time spent":"355.153969ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-13T14:22:01.209416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.462382ms","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-04-13T14:22:01.209458Z","caller":"traceutil/trace.go:171","msg":"trace[1406598088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4939; }","duration":"437.545833ms","start":"2026-04-13T14:22:00.771903Z","end":"2026-04-13T14:22:01.209448Z","steps":["trace[1406598088] 'agreement among raft nodes before linearized reading' (duration: 437.450083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.209494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.771886Z","time spent":"437.599873ms","remote":"127.0.0.1:57122","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-04-13T14:22:01.209632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.466506ms","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-04-13T14:22:01.209657Z","caller":"traceutil/trace.go:171","msg":"trace[1975321359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4939; }","duration":"435.519666ms","start":"2026-04-13T14:22:00.774132Z","end":"2026-04-13T14:22:01.209651Z","steps":["trace[1975321359] 'agreement among raft nodes before linearized reading' (duration: 435.465366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.209677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.77412Z","time spent":"435.553436ms","remote":"127.0.0.1:57122","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-04-13T14:22:01.209877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.501747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-13T14:22:01.209902Z","caller":"traceutil/trace.go:171","msg":"trace[943940123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4939; }","duration":"442.573458ms","start":"2026-04-13T14:22:00.767324Z","end":"2026-04-13T14:22:01.209897Z","steps":["trace[943940123] 'agreement among raft nodes before linearized reading' (duration: 442.513998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.20992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.767297Z","time spent":"442.618719ms","remote":"127.0.0.1:57122","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":"warn","ts":"2026-04-13T14:22:01.210771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.505732ms","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-04-13T14:22:01.210855Z","caller":"traceutil/trace.go:171","msg":"trace[913060239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4939; }","duration":"541.611572ms","start":"2026-04-13T14:22:00.669224Z","end":"2026-04-13T14:22:01.210835Z","steps":["trace[913060239] 'agreement among raft nodes before linearized reading' (duration: 541.330761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.210895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.66916Z","time spent":"541.727243ms","remote":"127.0.0.1:57122","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-04-13T14:22:01.211414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.507851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:22:01.211521Z","caller":"traceutil/trace.go:171","msg":"trace[2007812742] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:4939; }","duration":"657.651951ms","start":"2026-04-13T14:22:00.553829Z","end":"2026-04-13T14:22:01.211481Z","steps":["trace[2007812742] 'agreement among raft nodes before linearized reading' (duration: 654.24346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.211555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:00.553813Z","time spent":"657.733961ms","remote":"127.0.0.1:36028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-04-13T14:22:01.437663Z","caller":"traceutil/trace.go:171","msg":"trace[1775190094] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5125; }","duration":"226.180589ms","start":"2026-04-13T14:22:01.211463Z","end":"2026-04-13T14:22:01.437644Z","steps":["trace[1775190094] 'read index received' (duration: 199.897256ms)","trace[1775190094] 'applied index is now lower than readState.Index' (duration: 26.282873ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:22:01.437746Z","caller":"traceutil/trace.go:171","msg":"trace[154161768] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"232.249809ms","start":"2026-04-13T14:22:01.205487Z","end":"2026-04-13T14:22:01.437737Z","steps":["trace[154161768] 'process raft request' (duration: 205.858705ms)","trace[154161768] 'compare' (duration: 26.195833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:22:01.437955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.47121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ed439df0-d7bc-4872-9d5d-77371c5e7d6f\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-13T14:22:01.438207Z","caller":"traceutil/trace.go:171","msg":"trace[1694553318] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ed439df0-d7bc-4872-9d5d-77371c5e7d6f; range_end:; response_count:1; response_revision:4940; }","duration":"226.758941ms","start":"2026-04-13T14:22:01.211425Z","end":"2026-04-13T14:22:01.438184Z","steps":["trace[1694553318] 'agreement among raft nodes before linearized reading' (duration: 226.36405ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:01.57633Z","caller":"traceutil/trace.go:171","msg":"trace[1399080608] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"361.40934ms","start":"2026-04-13T14:22:01.214875Z","end":"2026-04-13T14:22:01.576284Z","steps":["trace[1399080608] 'process raft request' (duration: 356.690155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.576458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.214843Z","time spent":"361.545451ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:22:01.576773Z","caller":"traceutil/trace.go:171","msg":"trace[1812490961] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"361.080438ms","start":"2026-04-13T14:22:01.215651Z","end":"2026-04-13T14:22:01.576731Z","steps":["trace[1812490961] 'process raft request' (duration: 360.603557ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:01.576856Z","caller":"traceutil/trace.go:171","msg":"trace[1901011440] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"355.818871ms","start":"2026-04-13T14:22:01.221031Z","end":"2026-04-13T14:22:01.57685Z","steps":["trace[1901011440] 'process raft request' (duration: 355.675261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.57692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.221022Z","time spent":"355.854301ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:22:01.576912Z","caller":"traceutil/trace.go:171","msg":"trace[1218451052] linearizableReadLoop","detail":"{readStateIndex:5131; appliedIndex:5126; }","duration":"139.161473ms","start":"2026-04-13T14:22:01.437712Z","end":"2026-04-13T14:22:01.576874Z","steps":["trace[1218451052] 'read index received' (duration: 133.788287ms)","trace[1218451052] 'applied index is now lower than readState.Index' (duration: 5.371506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:22:01.577136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.754321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:22:01.577171Z","caller":"traceutil/trace.go:171","msg":"trace[1913061755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4945; }","duration":"223.839292ms","start":"2026-04-13T14:22:01.353324Z","end":"2026-04-13T14:22:01.577163Z","steps":["trace[1913061755] 'agreement among raft nodes before linearized reading' (duration: 223.783442ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:01.577289Z","caller":"traceutil/trace.go:171","msg":"trace[1159639813] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"354.416308ms","start":"2026-04-13T14:22:01.222862Z","end":"2026-04-13T14:22:01.577278Z","steps":["trace[1159639813] 'process raft request' (duration: 353.902236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.5773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.215643Z","time spent":"361.218668ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:01.577338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.222853Z","time spent":"354.458288ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13642,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:01.577408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.366938ms","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-04-13T14:22:01.577473Z","caller":"traceutil/trace.go:171","msg":"trace[246380448] 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:4945; }","duration":"134.543528ms","start":"2026-04-13T14:22:01.442908Z","end":"2026-04-13T14:22:01.577452Z","steps":["trace[246380448] 'agreement among raft nodes before linearized reading' (duration: 134.213437ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:01.576817Z","caller":"traceutil/trace.go:171","msg":"trace[1680117599] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"356.730385ms","start":"2026-04-13T14:22:01.220077Z","end":"2026-04-13T14:22:01.576808Z","steps":["trace[1680117599] 'process raft request' (duration: 356.503835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:01.57781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.22007Z","time spent":"357.678048ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:02.360131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.91584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:22:02.360257Z","caller":"traceutil/trace.go:171","msg":"trace[764495241] linearizableReadLoop","detail":"{readStateIndex:5137; appliedIndex:5136; }","duration":"510.856994ms","start":"2026-04-13T14:22:01.849379Z","end":"2026-04-13T14:22:02.360236Z","steps":["trace[764495241] 'read index received' (duration: 25.77µs)","trace[764495241] 'applied index is now lower than readState.Index' (duration: 510.825354ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:22:02.360387Z","caller":"traceutil/trace.go:171","msg":"trace[213474368] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"688.280059ms","start":"2026-04-13T14:22:01.672088Z","end":"2026-04-13T14:22:02.360368Z","steps":["trace[213474368] 'process raft request' (duration: 33.992778ms)","trace[213474368] 'compare' (duration: 653.756939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:22:02.360457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.672075Z","time spent":"688.347619ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4711,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:02.360575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.07471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:22:02.360667Z","caller":"traceutil/trace.go:171","msg":"trace[893914280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4951; }","duration":"475.178052ms","start":"2026-04-13T14:22:01.88547Z","end":"2026-04-13T14:22:02.360648Z","steps":["trace[893914280] 'agreement among raft nodes before linearized reading' (duration: 475.04198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:02.360707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.885396Z","time spent":"475.303432ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T14:22:02.360866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.449117ms","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-04-13T14:22:02.360965Z","caller":"traceutil/trace.go:171","msg":"trace[984392002] 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:4951; }","duration":"511.581507ms","start":"2026-04-13T14:22:01.849358Z","end":"2026-04-13T14:22:02.360939Z","steps":["trace[984392002] 'agreement among raft nodes before linearized reading' (duration: 511.298157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:02.361018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:01.849351Z","time spent":"511.656868ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-13T14:22:21.311588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.253649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10707"} {"level":"info","ts":"2026-04-13T14:22:21.311669Z","caller":"traceutil/trace.go:171","msg":"trace[904696825] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5070; }","duration":"100.35496ms","start":"2026-04-13T14:22:21.211296Z","end":"2026-04-13T14:22:21.311651Z","steps":["trace[904696825] 'range keys from in-memory index tree' (duration: 100.159788ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:39.891599Z","caller":"traceutil/trace.go:171","msg":"trace[1616649469] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"181.788659ms","start":"2026-04-13T14:22:39.709782Z","end":"2026-04-13T14:22:39.891571Z","steps":["trace[1616649469] 'process raft request' (duration: 181.602548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:43.549548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.043387Z","time spent":"506.15711ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-13T14:22:43.81775Z","caller":"traceutil/trace.go:171","msg":"trace[2095313098] linearizableReadLoop","detail":"{readStateIndex:5484; appliedIndex:5482; }","duration":"473.678216ms","start":"2026-04-13T14:22:43.344048Z","end":"2026-04-13T14:22:43.817726Z","steps":["trace[2095313098] 'read index received' (duration: 205.523733ms)","trace[2095313098] 'applied index is now lower than readState.Index' (duration: 268.153583ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:22:43.818886Z","caller":"traceutil/trace.go:171","msg":"trace[1406084693] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"310.030447ms","start":"2026-04-13T14:22:43.508839Z","end":"2026-04-13T14:22:43.81887Z","steps":["trace[1406084693] 'process raft request' (duration: 308.818883ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:43.818938Z","caller":"traceutil/trace.go:171","msg":"trace[499201490] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"683.460314ms","start":"2026-04-13T14:22:43.13545Z","end":"2026-04-13T14:22:43.81891Z","steps":["trace[499201490] 'process raft request' (duration: 682.11853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:43.819016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.508806Z","time spent":"310.131377ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:22:43.819215Z","caller":"traceutil/trace.go:171","msg":"trace[207435676] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"386.137979ms","start":"2026-04-13T14:22:43.433066Z","end":"2026-04-13T14:22:43.819204Z","steps":["trace[207435676] 'process raft request' (duration: 384.558924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:43.819163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.135429Z","time spent":"683.620564ms","remote":"127.0.0.1:57106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6069,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:43.819471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.433025Z","time spent":"386.404509ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:43.819893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.844872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-13T14:22:43.819938Z","caller":"traceutil/trace.go:171","msg":"trace[787679155] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5291; }","duration":"475.942833ms","start":"2026-04-13T14:22:43.343986Z","end":"2026-04-13T14:22:43.819929Z","steps":["trace[787679155] 'agreement among raft nodes before linearized reading' (duration: 475.804332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:43.819969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.343956Z","time spent":"476.006123ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-13T14:22:43.819945Z","caller":"traceutil/trace.go:171","msg":"trace[846538077] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"310.909459ms","start":"2026-04-13T14:22:43.509003Z","end":"2026-04-13T14:22:43.819912Z","steps":["trace[846538077] 'process raft request' (duration: 308.690422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:43.820092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.508967Z","time spent":"311.0467ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:22:43.82274Z","caller":"traceutil/trace.go:171","msg":"trace[243722383] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"707.14214ms","start":"2026-04-13T14:22:43.110861Z","end":"2026-04-13T14:22:43.818003Z","steps":["trace[243722383] 'process raft request' (duration: 673.268843ms)","trace[243722383] 'compare' (duration: 33.258905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:22:43.82285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.110837Z","time spent":"711.965355ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:43.873805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.737427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:22:43.8739Z","caller":"traceutil/trace.go:171","msg":"trace[814471579] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5292; }","duration":"108.912807ms","start":"2026-04-13T14:22:43.764972Z","end":"2026-04-13T14:22:43.873885Z","steps":["trace[814471579] 'agreement among raft nodes before linearized reading' (duration: 108.729997ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:43.873792Z","caller":"traceutil/trace.go:171","msg":"trace[1812379132] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"319.559937ms","start":"2026-04-13T14:22:43.554169Z","end":"2026-04-13T14:22:43.873729Z","steps":["trace[1812379132] 'process raft request' (duration: 319.400626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:43.874172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:22:43.554154Z","time spent":"319.878527ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:22:54.183568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.351194ms","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-04-13T14:22:54.183659Z","caller":"traceutil/trace.go:171","msg":"trace[648710736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5371; }","duration":"139.484504ms","start":"2026-04-13T14:22:54.044154Z","end":"2026-04-13T14:22:54.183638Z","steps":["trace[648710736] 'range keys from in-memory index tree' (duration: 139.106442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:54.183788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.960686ms","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-04-13T14:22:54.183876Z","caller":"traceutil/trace.go:171","msg":"trace[443983435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5371; }","duration":"140.100646ms","start":"2026-04-13T14:22:54.043754Z","end":"2026-04-13T14:22:54.183855Z","steps":["trace[443983435] 'range keys from in-memory index tree' (duration: 139.781984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:22:54.18412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.085615ms","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-04-13T14:22:54.184212Z","caller":"traceutil/trace.go:171","msg":"trace[1372721046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5371; }","duration":"140.211366ms","start":"2026-04-13T14:22:54.043978Z","end":"2026-04-13T14:22:54.18419Z","steps":["trace[1372721046] 'range keys from in-memory index tree' (duration: 139.938045ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:22:54.184493Z","caller":"traceutil/trace.go:171","msg":"trace[793286341] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"138.27541ms","start":"2026-04-13T14:22:54.046203Z","end":"2026-04-13T14:22:54.184479Z","steps":["trace[793286341] 'process raft request' (duration: 38.404963ms)","trace[793286341] 'compare' (duration: 98.858264ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:23:16.132067Z","caller":"traceutil/trace.go:171","msg":"trace[2085706955] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"127.278824ms","start":"2026-04-13T14:23:16.004746Z","end":"2026-04-13T14:23:16.132025Z","steps":["trace[2085706955] 'process raft request' (duration: 74.550776ms)","trace[2085706955] 'compare' (duration: 52.479968ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:23:16.132163Z","caller":"traceutil/trace.go:171","msg":"trace[2025894898] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"127.310225ms","start":"2026-04-13T14:23:16.004822Z","end":"2026-04-13T14:23:16.132132Z","steps":["trace[2025894898] 'process raft request' (duration: 127.138254ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:23:20.191283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2902} {"level":"info","ts":"2026-04-13T14:23:20.286206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2902,"took":"93.330286ms","hash":2451532687,"current-db-size-bytes":53530624,"current-db-size":"54 MB","current-db-size-in-use-bytes":28950528,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-13T14:23:20.286277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2451532687,"revision":2902,"compact-revision":-1} {"level":"info","ts":"2026-04-13T14:24:22.432463Z","caller":"traceutil/trace.go:171","msg":"trace[1235339650] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"108.814267ms","start":"2026-04-13T14:24:22.323619Z","end":"2026-04-13T14:24:22.432433Z","steps":["trace[1235339650] 'process raft request' (duration: 55.644808ms)","trace[1235339650] 'compare' (duration: 52.937978ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:25:21.760657Z","caller":"traceutil/trace.go:171","msg":"trace[1461903819] transaction","detail":"{read_only:false; response_revision:6377; number_of_response:1; }","duration":"103.201178ms","start":"2026-04-13T14:25:21.657424Z","end":"2026-04-13T14:25:21.760625Z","steps":["trace[1461903819] 'process raft request' (duration: 102.995727ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:22.754733Z","caller":"traceutil/trace.go:171","msg":"trace[563694339] linearizableReadLoop","detail":"{readStateIndex:6617; appliedIndex:6616; }","duration":"116.620101ms","start":"2026-04-13T14:25:22.638093Z","end":"2026-04-13T14:25:22.754713Z","steps":["trace[563694339] 'read index received' (duration: 80.218785ms)","trace[563694339] 'applied index is now lower than readState.Index' (duration: 36.400776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:25:22.754846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.733271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:25:22.754822Z","caller":"traceutil/trace.go:171","msg":"trace[537338873] transaction","detail":"{read_only:false; response_revision:6383; number_of_response:1; }","duration":"120.234492ms","start":"2026-04-13T14:25:22.63456Z","end":"2026-04-13T14:25:22.754794Z","steps":["trace[537338873] 'process raft request' (duration: 83.787886ms)","trace[537338873] 'compare' (duration: 36.272636ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:25:22.754869Z","caller":"traceutil/trace.go:171","msg":"trace[1463290391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:6383; }","duration":"116.780981ms","start":"2026-04-13T14:25:22.63808Z","end":"2026-04-13T14:25:22.754861Z","steps":["trace[1463290391] 'agreement among raft nodes before linearized reading' (duration: 116.705261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:33.467679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.622772ms","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-04-13T14:25:33.467758Z","caller":"traceutil/trace.go:171","msg":"trace[141288779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6484; }","duration":"195.733252ms","start":"2026-04-13T14:25:33.272007Z","end":"2026-04-13T14:25:33.46774Z","steps":["trace[141288779] 'range keys from in-memory index tree' (duration: 195.501192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:33.468263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.318315ms","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-04-13T14:25:33.468408Z","caller":"traceutil/trace.go:171","msg":"trace[762626357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6484; }","duration":"196.490995ms","start":"2026-04-13T14:25:33.271906Z","end":"2026-04-13T14:25:33.468397Z","steps":["trace[762626357] 'range keys from in-memory index tree' (duration: 196.223454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:33.468683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.544256ms","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-04-13T14:25:33.46877Z","caller":"traceutil/trace.go:171","msg":"trace[710483467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6484; }","duration":"196.702596ms","start":"2026-04-13T14:25:33.272045Z","end":"2026-04-13T14:25:33.468748Z","steps":["trace[710483467] 'range keys from in-memory index tree' (duration: 196.335104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:33.468771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.281932ms","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-04-13T14:25:33.468908Z","caller":"traceutil/trace.go:171","msg":"trace[572544142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6484; }","duration":"173.450582ms","start":"2026-04-13T14:25:33.295447Z","end":"2026-04-13T14:25:33.468897Z","steps":["trace[572544142] 'range keys from in-memory index tree' (duration: 173.187321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:33.468909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.292608ms","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-04-13T14:25:33.468978Z","caller":"traceutil/trace.go:171","msg":"trace[1301631181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6484; }","duration":"172.39083ms","start":"2026-04-13T14:25:33.296569Z","end":"2026-04-13T14:25:33.46896Z","steps":["trace[1301631181] 'range keys from in-memory index tree' (duration: 172.126318ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:33.601421Z","caller":"traceutil/trace.go:171","msg":"trace[850014463] transaction","detail":"{read_only:false; response_revision:6485; number_of_response:1; }","duration":"128.551008ms","start":"2026-04-13T14:25:33.472849Z","end":"2026-04-13T14:25:33.6014Z","steps":["trace[850014463] 'process raft request' (duration: 128.311238ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:33.646152Z","caller":"traceutil/trace.go:171","msg":"trace[1814592653] transaction","detail":"{read_only:false; response_revision:6487; number_of_response:1; }","duration":"171.615076ms","start":"2026-04-13T14:25:33.474515Z","end":"2026-04-13T14:25:33.64613Z","steps":["trace[1814592653] 'process raft request' (duration: 171.521996ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:33.646201Z","caller":"traceutil/trace.go:171","msg":"trace[1133912667] transaction","detail":"{read_only:false; response_revision:6486; number_of_response:1; }","duration":"172.334288ms","start":"2026-04-13T14:25:33.473823Z","end":"2026-04-13T14:25:33.646158Z","steps":["trace[1133912667] 'process raft request' (duration: 172.112007ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:33.646445Z","caller":"traceutil/trace.go:171","msg":"trace[793832568] transaction","detail":"{read_only:false; response_revision:6488; number_of_response:1; }","duration":"171.530446ms","start":"2026-04-13T14:25:33.474885Z","end":"2026-04-13T14:25:33.646415Z","steps":["trace[793832568] 'process raft request' (duration: 171.196045ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:33.646519Z","caller":"traceutil/trace.go:171","msg":"trace[948048648] transaction","detail":"{read_only:false; response_revision:6489; number_of_response:1; }","duration":"171.101664ms","start":"2026-04-13T14:25:33.475402Z","end":"2026-04-13T14:25:33.646503Z","steps":["trace[948048648] 'process raft request' (duration: 170.913653ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:34.77679Z","caller":"traceutil/trace.go:171","msg":"trace[1203254925] transaction","detail":"{read_only:false; response_revision:6490; number_of_response:1; }","duration":"157.654203ms","start":"2026-04-13T14:25:34.619116Z","end":"2026-04-13T14:25:34.776771Z","steps":["trace[1203254925] 'process raft request' (duration: 157.547032ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:40.183806Z","caller":"traceutil/trace.go:171","msg":"trace[1375776760] linearizableReadLoop","detail":"{readStateIndex:6759; appliedIndex:6758; }","duration":"152.122763ms","start":"2026-04-13T14:25:40.03166Z","end":"2026-04-13T14:25:40.183783Z","steps":["trace[1375776760] 'read index received' (duration: 151.921673ms)","trace[1375776760] 'applied index is now lower than readState.Index' (duration: 200.19µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:25:40.183911Z","caller":"traceutil/trace.go:171","msg":"trace[1124588032] transaction","detail":"{read_only:false; response_revision:6521; number_of_response:1; }","duration":"220.549362ms","start":"2026-04-13T14:25:39.963351Z","end":"2026-04-13T14:25:40.1839Z","steps":["trace[1124588032] 'process raft request' (duration: 220.311771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:40.184025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.352454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:25:40.184083Z","caller":"traceutil/trace.go:171","msg":"trace[626629773] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:6521; }","duration":"152.466714ms","start":"2026-04-13T14:25:40.031599Z","end":"2026-04-13T14:25:40.184066Z","steps":["trace[626629773] 'agreement among raft nodes before linearized reading' (duration: 152.337754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:41.232005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.361139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"warn","ts":"2026-04-13T14:25:41.23207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.853413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-04-13T14:25:41.232111Z","caller":"traceutil/trace.go:171","msg":"trace[1518125111] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6525; }","duration":"147.520839ms","start":"2026-04-13T14:25:41.084563Z","end":"2026-04-13T14:25:41.232084Z","steps":["trace[1518125111] 'range keys from in-memory index tree' (duration: 147.178209ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:41.232162Z","caller":"traceutil/trace.go:171","msg":"trace[65362538] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6525; }","duration":"148.999974ms","start":"2026-04-13T14:25:41.083139Z","end":"2026-04-13T14:25:41.232139Z","steps":["trace[65362538] 'range keys from in-memory index tree' (duration: 148.597393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:41.23258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.415322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-04-13T14:25:41.232681Z","caller":"traceutil/trace.go:171","msg":"trace[1542056353] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6525; }","duration":"148.549722ms","start":"2026-04-13T14:25:41.084098Z","end":"2026-04-13T14:25:41.232647Z","steps":["trace[1542056353] 'range keys from in-memory index tree' (duration: 148.204561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:25:41.233119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.974785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:25:41.23321Z","caller":"traceutil/trace.go:171","msg":"trace[109408312] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:6525; }","duration":"109.089087ms","start":"2026-04-13T14:25:41.124105Z","end":"2026-04-13T14:25:41.233194Z","steps":["trace[109408312] 'count revisions from in-memory index tree' (duration: 107.716222ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:41.953927Z","caller":"traceutil/trace.go:171","msg":"trace[1850395146] transaction","detail":"{read_only:false; response_revision:6530; number_of_response:1; }","duration":"225.304567ms","start":"2026-04-13T14:25:41.728592Z","end":"2026-04-13T14:25:41.953897Z","steps":["trace[1850395146] 'process raft request' (duration: 206.352016ms)","trace[1850395146] 'compare' (duration: 18.83442ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:25:42.012605Z","caller":"traceutil/trace.go:171","msg":"trace[545320007] transaction","detail":"{read_only:false; response_revision:6533; number_of_response:1; }","duration":"283.543442ms","start":"2026-04-13T14:25:41.729038Z","end":"2026-04-13T14:25:42.012581Z","steps":["trace[545320007] 'process raft request' (duration: 283.499831ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:42.012847Z","caller":"traceutil/trace.go:171","msg":"trace[972585279] transaction","detail":"{read_only:false; response_revision:6531; number_of_response:1; }","duration":"284.183404ms","start":"2026-04-13T14:25:41.728645Z","end":"2026-04-13T14:25:42.012829Z","steps":["trace[972585279] 'process raft request' (duration: 283.741003ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:25:42.013024Z","caller":"traceutil/trace.go:171","msg":"trace[2144555864] transaction","detail":"{read_only:false; response_revision:6532; number_of_response:1; }","duration":"284.309975ms","start":"2026-04-13T14:25:41.728647Z","end":"2026-04-13T14:25:42.012957Z","steps":["trace[2144555864] 'process raft request' (duration: 283.852833ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:26:04.201512Z","caller":"traceutil/trace.go:171","msg":"trace[1895521309] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"115.111876ms","start":"2026-04-13T14:26:04.08638Z","end":"2026-04-13T14:26:04.201492Z","steps":["trace[1895521309] 'process raft request' (duration: 114.932125ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:26:31.192456Z","caller":"traceutil/trace.go:171","msg":"trace[18674312] transaction","detail":"{read_only:false; response_revision:6842; number_of_response:1; }","duration":"107.225392ms","start":"2026-04-13T14:26:31.085212Z","end":"2026-04-13T14:26:31.192437Z","steps":["trace[18674312] 'process raft request' (duration: 107.115501ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:28:20.198191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5563} {"level":"info","ts":"2026-04-13T14:28:20.308441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5563,"took":"108.893136ms","hash":992958738,"current-db-size-bytes":53530624,"current-db-size":"54 MB","current-db-size-in-use-bytes":18292736,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-13T14:28:20.308502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":992958738,"revision":5563,"compact-revision":2902}