{"level":"warn","ts":"2026-10-17T10:57:28.49973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:57:28.499854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.193: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://162.253.55.193:2380","--initial-cluster=instance=https://162.253.55.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.193: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-10-17T10:57:28.499927Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:57:28.499941Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-17T10:57:28.499964Z","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-10-17T10:57:28.500696Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"]} {"level":"info","ts":"2026-10-17T10:57:28.50078Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.193: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-10-17T10:57:28.503207Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.162556ms"} {"level":"info","ts":"2026-10-17T10:57:28.50839Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6814e3e3d6b4d713","cluster-id":"e5e99c9118b1492a"} {"level":"info","ts":"2026-10-17T10:57:28.508453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:57:28.508473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 0"} {"level":"info","ts":"2026-10-17T10:57:28.508482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6814e3e3d6b4d713 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:57:28.508501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 1"} {"level":"info","ts":"2026-10-17T10:57:28.508533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"warn","ts":"2026-10-17T10:57:28.513312Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:57:28.51472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:57:28.516071Z","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-10-17T10:57:28.518221Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6814e3e3d6b4d713","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:57:28.518755Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6814e3e3d6b4d713","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:57:28.51869Z","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-10-17T10:57:28.518979Z","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-10-17T10:57:28.519085Z","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-10-17T10:57:28.520583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"info","ts":"2026-10-17T10:57:28.521181Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","added-peer-id":"6814e3e3d6b4d713","added-peer-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-17T10:57:28.522387Z","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-10-17T10:57:28.522496Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-17T10:57:28.522567Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-17T10:57:28.52263Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6814e3e3d6b4d713","initial-advertise-peer-urls":["https://162.253.55.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:57:28.522673Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:57:29.208928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:57:29.208994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:57:29.209031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgPreVoteResp from 6814e3e3d6b4d713 at term 1"} {"level":"info","ts":"2026-10-17T10:57:29.209048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:57:29.209058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgVoteResp from 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-17T10:57:29.209073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became leader at term 2"} {"level":"info","ts":"2026-10-17T10:57:29.209085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6814e3e3d6b4d713 elected leader 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-17T10:57:29.210427Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:57:29.211427Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:57:29.21142Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6814e3e3d6b4d713","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.193:2379]}","request-path":"/0/members/6814e3e3d6b4d713/attributes","cluster-id":"e5e99c9118b1492a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:57:29.211469Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:57:29.211741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:57:29.211866Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:57:29.212071Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:57:29.212217Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:57:29.212257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:57:29.214637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.193:2379"} {"level":"info","ts":"2026-10-17T10:57:29.214728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T10:57:35.232264Z","caller":"traceutil/trace.go:171","msg":"trace[1702641984] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"121.366403ms","start":"2026-10-17T10:57:35.110875Z","end":"2026-10-17T10:57:35.232241Z","steps":["trace[1702641984] 'process raft request' (duration: 121.199059ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:42.186218Z","caller":"traceutil/trace.go:171","msg":"trace[12646456] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"110.519532ms","start":"2026-10-17T10:57:42.075679Z","end":"2026-10-17T10:57:42.186198Z","steps":["trace[12646456] 'process raft request' (duration: 110.369788ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:58:22.09691Z","caller":"traceutil/trace.go:171","msg":"trace[871589053] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"207.476761ms","start":"2026-10-17T10:58:21.889375Z","end":"2026-10-17T10:58:22.096852Z","steps":["trace[871589053] 'read index received' (duration: 206.340222ms)","trace[871589053] 'applied index is now lower than readState.Index' (duration: 1.135329ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:58:22.096958Z","caller":"traceutil/trace.go:171","msg":"trace[688563639] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"228.837773ms","start":"2026-10-17T10:58:21.868082Z","end":"2026-10-17T10:58:22.09692Z","steps":["trace[688563639] 'process raft request' (duration: 228.001812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:58:22.097197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.79797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k57c2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-17T10:58:22.097326Z","caller":"traceutil/trace.go:171","msg":"trace[1276014763] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k57c2; range_end:; response_count:1; response_revision:617; }","duration":"207.963093ms","start":"2026-10-17T10:58:21.88933Z","end":"2026-10-17T10:58:22.097293Z","steps":["trace[1276014763] 'agreement among raft nodes before linearized reading' (duration: 207.677156ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:58:22.109013Z","caller":"traceutil/trace.go:171","msg":"trace[809598879] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"183.44467ms","start":"2026-10-17T10:58:21.925545Z","end":"2026-10-17T10:58:22.108989Z","steps":["trace[809598879] 'process raft request' (duration: 182.794723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:58:22.631198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.947153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:58:22.631325Z","caller":"traceutil/trace.go:171","msg":"trace[1129062511] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"516.931627ms","start":"2026-10-17T10:58:22.114371Z","end":"2026-10-17T10:58:22.631303Z","steps":["trace[1129062511] 'process raft request' (duration: 344.304336ms)","trace[1129062511] 'compare' (duration: 171.835481ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:58:22.6314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:58:22.114279Z","time spent":"517.08335ms","remote":"127.0.0.1:49142","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-10-17T10:58:22.638153Z","caller":"traceutil/trace.go:171","msg":"trace[2076774888] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"518.576459ms","start":"2026-10-17T10:58:22.119541Z","end":"2026-10-17T10:58:22.638118Z","steps":["trace[2076774888] 'process raft request' (duration: 518.24931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:58:22.638269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:58:22.119512Z","time spent":"518.703762ms","remote":"127.0.0.1:49048","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-10-17T10:58:22.638388Z","caller":"traceutil/trace.go:171","msg":"trace[917797124] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"453.936339ms","start":"2026-10-17T10:58:22.184412Z","end":"2026-10-17T10:58:22.638349Z","steps":["trace[917797124] 'process raft request' (duration: 453.60056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:58:22.638594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:58:22.184378Z","time spent":"454.111563ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T10:58:39.010542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.811402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T10:58:39.010589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.714115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:58:39.01063Z","caller":"traceutil/trace.go:171","msg":"trace[422641549] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:787; }","duration":"264.940496ms","start":"2026-10-17T10:58:38.745671Z","end":"2026-10-17T10:58:39.010612Z","steps":["trace[422641549] 'range keys from in-memory index tree' (duration: 264.71608ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:58:39.01067Z","caller":"traceutil/trace.go:171","msg":"trace[1199968538] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:787; }","duration":"265.837109ms","start":"2026-10-17T10:58:38.744815Z","end":"2026-10-17T10:58:39.010652Z","steps":["trace[1199968538] 'count revisions from in-memory index tree' (duration: 265.588962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:58:39.01083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.833489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:58:39.01102Z","caller":"traceutil/trace.go:171","msg":"trace[703191370] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:787; }","duration":"266.092876ms","start":"2026-10-17T10:58:38.744891Z","end":"2026-10-17T10:58:39.010984Z","steps":["trace[703191370] 'range keys from in-memory index tree' (duration: 265.704725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:59:59.963623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.504499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:59:59.963756Z","caller":"traceutil/trace.go:171","msg":"trace[45430119] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"189.173746ms","start":"2026-10-17T10:59:59.774562Z","end":"2026-10-17T10:59:59.963736Z","steps":["trace[45430119] 'read index received' (duration: 55.762µs)","trace[45430119] 'applied index is now lower than readState.Index' (duration: 189.116204ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:59:59.963783Z","caller":"traceutil/trace.go:171","msg":"trace[868762784] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"301.906036ms","start":"2026-10-17T10:59:59.661849Z","end":"2026-10-17T10:59:59.963755Z","steps":["trace[868762784] 'process raft request' (duration: 15.169341ms)","trace[868762784] 'compare' (duration: 286.382266ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:59:59.963902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:59:59.661821Z","time spent":"302.012799ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:59:59.963968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.406311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-17T10:59:59.963989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.348182ms","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-10-17T10:59:59.964026Z","caller":"traceutil/trace.go:171","msg":"trace[947648879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1881; }","duration":"189.503114ms","start":"2026-10-17T10:59:59.774507Z","end":"2026-10-17T10:59:59.96401Z","steps":["trace[947648879] 'agreement among raft nodes before linearized reading' (duration: 189.332719ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:59:59.964078Z","caller":"traceutil/trace.go:171","msg":"trace[1743700086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1881; }","duration":"181.467676ms","start":"2026-10-17T10:59:59.782592Z","end":"2026-10-17T10:59:59.964059Z","steps":["trace[1743700086] 'agreement among raft nodes before linearized reading' (duration: 181.262101ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:00.378339Z","caller":"traceutil/trace.go:171","msg":"trace[1533139336] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"109.864397ms","start":"2026-10-17T11:00:00.268451Z","end":"2026-10-17T11:00:00.378315Z","steps":["trace[1533139336] 'process raft request' (duration: 109.698353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:00:44.822266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.007501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:00:44.822384Z","caller":"traceutil/trace.go:171","msg":"trace[514117153] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2175; }","duration":"127.162745ms","start":"2026-10-17T11:00:44.695197Z","end":"2026-10-17T11:00:44.82236Z","steps":["trace[514117153] 'agreement among raft nodes before linearized reading' (duration: 126.954709ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:44.822283Z","caller":"traceutil/trace.go:171","msg":"trace[1082287159] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"105.122005ms","start":"2026-10-17T11:00:44.717132Z","end":"2026-10-17T11:00:44.822254Z","steps":["trace[1082287159] 'process raft request' (duration: 104.812397ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:51.009146Z","caller":"traceutil/trace.go:171","msg":"trace[124275001] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2286; }","duration":"159.46977ms","start":"2026-10-17T11:00:50.849619Z","end":"2026-10-17T11:00:51.009089Z","steps":["trace[124275001] 'read index received' (duration: 23.107937ms)","trace[124275001] 'applied index is now lower than readState.Index' (duration: 136.360423ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:00:51.009198Z","caller":"traceutil/trace.go:171","msg":"trace[1378756795] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"168.39302ms","start":"2026-10-17T11:00:50.840779Z","end":"2026-10-17T11:00:51.009172Z","steps":["trace[1378756795] 'process raft request' (duration: 167.012264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:00:51.009391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.761867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-17T11:00:51.009412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.253891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:00:51.009451Z","caller":"traceutil/trace.go:171","msg":"trace[538030065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2207; }","duration":"159.87657ms","start":"2026-10-17T11:00:50.849556Z","end":"2026-10-17T11:00:51.009432Z","steps":["trace[538030065] 'agreement among raft nodes before linearized reading' (duration: 159.708715ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:51.009485Z","caller":"traceutil/trace.go:171","msg":"trace[719773587] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2207; }","duration":"126.383794ms","start":"2026-10-17T11:00:50.883081Z","end":"2026-10-17T11:00:51.009465Z","steps":["trace[719773587] 'agreement among raft nodes before linearized reading' (duration: 126.21534ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:56.445629Z","caller":"traceutil/trace.go:171","msg":"trace[1588940745] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"113.744988ms","start":"2026-10-17T11:00:56.331858Z","end":"2026-10-17T11:00:56.445603Z","steps":["trace[1588940745] 'process raft request' (duration: 113.595234ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:56.551315Z","caller":"traceutil/trace.go:171","msg":"trace[1900362237] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"219.324955ms","start":"2026-10-17T11:00:56.331969Z","end":"2026-10-17T11:00:56.551294Z","steps":["trace[1900362237] 'process raft request' (duration: 204.723368ms)","trace[1900362237] 'compare' (duration: 14.463074ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:02:19.603343Z","caller":"traceutil/trace.go:171","msg":"trace[496399282] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"113.412764ms","start":"2026-10-17T11:02:19.489904Z","end":"2026-10-17T11:02:19.603317Z","steps":["trace[496399282] 'process raft request' (duration: 113.169608ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:19.888699Z","caller":"traceutil/trace.go:171","msg":"trace[19539402] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"122.665262ms","start":"2026-10-17T11:02:19.766002Z","end":"2026-10-17T11:02:19.888667Z","steps":["trace[19539402] 'process raft request' (duration: 122.482048ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:20.085422Z","caller":"traceutil/trace.go:171","msg":"trace[320241265] linearizableReadLoop","detail":"{readStateIndex:2990; appliedIndex:2988; }","duration":"200.774076ms","start":"2026-10-17T11:02:19.884618Z","end":"2026-10-17T11:02:20.085392Z","steps":["trace[320241265] 'read index received' (duration: 3.85349ms)","trace[320241265] 'applied index is now lower than readState.Index' (duration: 196.919106ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:02:20.085438Z","caller":"traceutil/trace.go:171","msg":"trace[497476637] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"255.284001ms","start":"2026-10-17T11:02:19.830119Z","end":"2026-10-17T11:02:20.085403Z","steps":["trace[497476637] 'process raft request' (duration: 197.4698ms)","trace[497476637] 'compare' (duration: 57.606825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:02:20.08561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.984131ms","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-10-17T11:02:20.085677Z","caller":"traceutil/trace.go:171","msg":"trace[355275806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2882; }","duration":"201.092994ms","start":"2026-10-17T11:02:19.884569Z","end":"2026-10-17T11:02:20.085662Z","steps":["trace[355275806] 'agreement among raft nodes before linearized reading' (duration: 200.91381ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:53.788414Z","caller":"traceutil/trace.go:171","msg":"trace[18340624] linearizableReadLoop","detail":"{readStateIndex:5323; appliedIndex:5322; }","duration":"102.019502ms","start":"2026-10-17T11:06:53.686348Z","end":"2026-10-17T11:06:53.788368Z","steps":["trace[18340624] 'read index received' (duration: 33.698793ms)","trace[18340624] 'applied index is now lower than readState.Index' (duration: 68.318999ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:06:53.788466Z","caller":"traceutil/trace.go:171","msg":"trace[1383259420] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"127.164782ms","start":"2026-10-17T11:06:53.661219Z","end":"2026-10-17T11:06:53.788383Z","steps":["trace[1383259420] 'process raft request' (duration: 58.882705ms)","trace[1383259420] 'compare' (duration: 68.028251ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:06:53.78887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.500175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-10-17T11:06:53.788972Z","caller":"traceutil/trace.go:171","msg":"trace[760082553] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5127; }","duration":"102.657529ms","start":"2026-10-17T11:06:53.686295Z","end":"2026-10-17T11:06:53.788952Z","steps":["trace[760082553] 'agreement among raft nodes before linearized reading' (duration: 102.200317ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:59.014206Z","caller":"traceutil/trace.go:171","msg":"trace[1034413513] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5466; }","duration":"124.979526ms","start":"2026-10-17T11:06:58.889203Z","end":"2026-10-17T11:06:59.014183Z","steps":["trace[1034413513] 'read index received' (duration: 124.861683ms)","trace[1034413513] 'applied index is now lower than readState.Index' (duration: 116.893µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:06:59.014414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.196991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cd956af-fdb2-49aa-97ba-54afaa4ea788\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-17T11:06:59.014443Z","caller":"traceutil/trace.go:171","msg":"trace[1494174259] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cd956af-fdb2-49aa-97ba-54afaa4ea788; range_end:; response_count:1; response_revision:5267; }","duration":"125.282123ms","start":"2026-10-17T11:06:58.889151Z","end":"2026-10-17T11:06:59.014434Z","steps":["trace[1494174259] 'agreement among raft nodes before linearized reading' (duration: 125.122819ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:59.014776Z","caller":"traceutil/trace.go:171","msg":"trace[1864743549] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"195.232356ms","start":"2026-10-17T11:06:58.81953Z","end":"2026-10-17T11:06:59.014762Z","steps":["trace[1864743549] 'process raft request' (duration: 194.474995ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:59.023122Z","caller":"traceutil/trace.go:171","msg":"trace[1309614394] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"107.190556ms","start":"2026-10-17T11:06:58.915901Z","end":"2026-10-17T11:06:59.023092Z","steps":["trace[1309614394] 'process raft request' (duration: 106.99297ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:59.166359Z","caller":"traceutil/trace.go:171","msg":"trace[21561001] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5468; }","duration":"118.141711ms","start":"2026-10-17T11:06:59.048196Z","end":"2026-10-17T11:06:59.166338Z","steps":["trace[21561001] 'read index received' (duration: 104.135328ms)","trace[21561001] 'applied index is now lower than readState.Index' (duration: 14.005053ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:06:59.16642Z","caller":"traceutil/trace.go:171","msg":"trace[496270436] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"142.368627ms","start":"2026-10-17T11:06:59.024016Z","end":"2026-10-17T11:06:59.166384Z","steps":["trace[496270436] 'process raft request' (duration: 128.297132ms)","trace[496270436] 'compare' (duration: 13.907191ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:06:59.166506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.250026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:06:59.166546Z","caller":"traceutil/trace.go:171","msg":"trace[514598484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5269; }","duration":"100.343599ms","start":"2026-10-17T11:06:59.066193Z","end":"2026-10-17T11:06:59.166536Z","steps":["trace[514598484] 'agreement among raft nodes before linearized reading' (duration: 100.271547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:06:59.166584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.379697ms","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-10-17T11:06:59.166634Z","caller":"traceutil/trace.go:171","msg":"trace[1974046510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5269; }","duration":"118.470959ms","start":"2026-10-17T11:06:59.04815Z","end":"2026-10-17T11:06:59.166621Z","steps":["trace[1974046510] 'agreement among raft nodes before linearized reading' (duration: 118.259523ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:59.5294Z","caller":"traceutil/trace.go:171","msg":"trace[1287155069] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"158.520984ms","start":"2026-10-17T11:06:59.370843Z","end":"2026-10-17T11:06:59.529364Z","steps":["trace[1287155069] 'process raft request' (duration: 158.223167ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:59.567647Z","caller":"traceutil/trace.go:171","msg":"trace[1293536770] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"136.402292ms","start":"2026-10-17T11:06:59.431216Z","end":"2026-10-17T11:06:59.567618Z","steps":["trace[1293536770] 'process raft request' (duration: 136.224478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:07:14.086784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.243003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:07:14.086857Z","caller":"traceutil/trace.go:171","msg":"trace[1190781020] linearizableReadLoop","detail":"{readStateIndex:5746; appliedIndex:5745; }","duration":"279.893228ms","start":"2026-10-17T11:07:13.806952Z","end":"2026-10-17T11:07:14.086846Z","steps":["trace[1190781020] 'read index received' (duration: 42.49905ms)","trace[1190781020] 'applied index is now lower than readState.Index' (duration: 237.393638ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:07:14.086912Z","caller":"traceutil/trace.go:171","msg":"trace[2065175096] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"367.629699ms","start":"2026-10-17T11:07:13.719273Z","end":"2026-10-17T11:07:14.086903Z","steps":["trace[2065175096] 'process raft request' (duration: 130.209561ms)","trace[2065175096] 'compare' (duration: 237.160362ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:07:14.086943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:07:13.719237Z","time spent":"367.689421ms","remote":"127.0.0.1:49048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8335,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:07:14.08721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.223117ms","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-10-17T11:07:14.087338Z","caller":"traceutil/trace.go:171","msg":"trace[1666104632] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5537; }","duration":"280.402211ms","start":"2026-10-17T11:07:13.8069Z","end":"2026-10-17T11:07:14.087302Z","steps":["trace[1666104632] 'agreement among raft nodes before linearized reading' (duration: 280.163525ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:07:14.3014Z","caller":"traceutil/trace.go:171","msg":"trace[1651437184] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"208.394196ms","start":"2026-10-17T11:07:14.09298Z","end":"2026-10-17T11:07:14.301374Z","steps":["trace[1651437184] 'process raft request' (duration: 195.244476ms)","trace[1651437184] 'compare' (duration: 13.042697ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:07:14.304473Z","caller":"traceutil/trace.go:171","msg":"trace[1281301837] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"210.805959ms","start":"2026-10-17T11:07:14.093608Z","end":"2026-10-17T11:07:14.304414Z","steps":["trace[1281301837] 'process raft request' (duration: 210.46134ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:07:14.304627Z","caller":"traceutil/trace.go:171","msg":"trace[774584763] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"210.781879ms","start":"2026-10-17T11:07:14.093827Z","end":"2026-10-17T11:07:14.304609Z","steps":["trace[774584763] 'process raft request' (duration: 210.452149ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:07:14.304721Z","caller":"traceutil/trace.go:171","msg":"trace[1763343258] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"209.514176ms","start":"2026-10-17T11:07:14.09518Z","end":"2026-10-17T11:07:14.304694Z","steps":["trace[1763343258] 'process raft request' (duration: 209.158486ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:07:29.250935Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2930} {"level":"info","ts":"2026-10-17T11:07:29.325837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2930,"took":"73.508794ms","hash":3715892670,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:07:29.325908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3715892670,"revision":2930,"compact-revision":-1} {"level":"info","ts":"2026-10-17T11:12:29.25955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5826} {"level":"info","ts":"2026-10-17T11:12:29.35434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5826,"took":"92.983055ms","hash":1815488552,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T11:12:29.354411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1815488552,"revision":5826,"compact-revision":2930} {"level":"info","ts":"2026-10-17T11:13:38.6011Z","caller":"traceutil/trace.go:171","msg":"trace[1011003940] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"112.51411ms","start":"2026-10-17T11:13:38.488559Z","end":"2026-10-17T11:13:38.601073Z","steps":["trace[1011003940] 'process raft request' (duration: 112.314535ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:38.771305Z","caller":"traceutil/trace.go:171","msg":"trace[1235835328] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"161.92293ms","start":"2026-10-17T11:13:38.609337Z","end":"2026-10-17T11:13:38.77126Z","steps":["trace[1235835328] 'process raft request' (duration: 123.533512ms)","trace[1235835328] 'compare' (duration: 37.197498ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:13:38.771417Z","caller":"traceutil/trace.go:171","msg":"trace[1174365487] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"159.604192ms","start":"2026-10-17T11:13:38.611762Z","end":"2026-10-17T11:13:38.771366Z","steps":["trace[1174365487] 'process raft request' (duration: 159.408287ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:50.031521Z","caller":"traceutil/trace.go:171","msg":"trace[911420696] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"127.611598ms","start":"2026-10-17T11:13:49.90388Z","end":"2026-10-17T11:13:50.031491Z","steps":["trace[911420696] 'process raft request' (duration: 127.416393ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:50.974745Z","caller":"traceutil/trace.go:171","msg":"trace[455918759] linearizableReadLoop","detail":"{readStateIndex:8964; appliedIndex:8963; }","duration":"134.590158ms","start":"2026-10-17T11:13:50.840132Z","end":"2026-10-17T11:13:50.974722Z","steps":["trace[455918759] 'read index received' (duration: 134.455615ms)","trace[455918759] 'applied index is now lower than readState.Index' (duration: 133.633µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:13:50.974979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.831774ms","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-10-17T11:13:50.975031Z","caller":"traceutil/trace.go:171","msg":"trace[1072456592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8655; }","duration":"134.932508ms","start":"2026-10-17T11:13:50.840083Z","end":"2026-10-17T11:13:50.975015Z","steps":["trace[1072456592] 'agreement among raft nodes before linearized reading' (duration: 134.755293ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:50.975408Z","caller":"traceutil/trace.go:171","msg":"trace[1634651140] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"144.334549ms","start":"2026-10-17T11:13:50.830467Z","end":"2026-10-17T11:13:50.974801Z","steps":["trace[1634651140] 'process raft request' (duration: 144.127894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:50.9756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.002273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-17T11:13:50.975871Z","caller":"traceutil/trace.go:171","msg":"trace[1182828115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8655; }","duration":"134.343782ms","start":"2026-10-17T11:13:50.841506Z","end":"2026-10-17T11:13:50.975849Z","steps":["trace[1182828115] 'agreement among raft nodes before linearized reading' (duration: 133.377417ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:51.140298Z","caller":"traceutil/trace.go:171","msg":"trace[333045098] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"161.011719ms","start":"2026-10-17T11:13:50.979253Z","end":"2026-10-17T11:13:51.140264Z","steps":["trace[333045098] 'process raft request' (duration: 122.755453ms)","trace[333045098] 'compare' (duration: 37.816034ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:13:51.140394Z","caller":"traceutil/trace.go:171","msg":"trace[1551086503] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"160.991658ms","start":"2026-10-17T11:13:50.979383Z","end":"2026-10-17T11:13:51.140375Z","steps":["trace[1551086503] 'process raft request' (duration: 160.810413ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:22.25927Z","caller":"traceutil/trace.go:171","msg":"trace[448727602] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"116.604204ms","start":"2026-10-17T11:14:22.142643Z","end":"2026-10-17T11:14:22.259247Z","steps":["trace[448727602] 'process raft request' (duration: 116.42255ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:22.259632Z","caller":"traceutil/trace.go:171","msg":"trace[942511453] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"112.996021ms","start":"2026-10-17T11:14:22.14662Z","end":"2026-10-17T11:14:22.259616Z","steps":["trace[942511453] 'process raft request' (duration: 112.58836ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:30.162038Z","caller":"traceutil/trace.go:171","msg":"trace[557206580] transaction","detail":"{read_only:false; response_revision:8917; number_of_response:1; }","duration":"139.614502ms","start":"2026-10-17T11:14:30.022372Z","end":"2026-10-17T11:14:30.161987Z","steps":["trace[557206580] 'process raft request' (duration: 139.403957ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:32.8879Z","caller":"traceutil/trace.go:171","msg":"trace[1341205420] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8941; }","duration":"172.126188ms","start":"2026-10-17T11:14:32.715749Z","end":"2026-10-17T11:14:32.887875Z","steps":["trace[1341205420] 'process raft request' (duration: 106.340916ms)","trace[1341205420] 'compare' (duration: 65.652419ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:14:32.888155Z","caller":"traceutil/trace.go:171","msg":"trace[241289943] linearizableReadLoop","detail":"{readStateIndex:9263; appliedIndex:9261; }","duration":"118.94727ms","start":"2026-10-17T11:14:32.769194Z","end":"2026-10-17T11:14:32.888141Z","steps":["trace[241289943] 'read index received' (duration: 52.909541ms)","trace[241289943] 'applied index is now lower than readState.Index' (duration: 66.036839ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:14:32.888237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.031812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:14:32.888207Z","caller":"traceutil/trace.go:171","msg":"trace[2113286208] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8942; }","duration":"172.024496ms","start":"2026-10-17T11:14:32.71615Z","end":"2026-10-17T11:14:32.888175Z","steps":["trace[2113286208] 'process raft request' (duration: 171.665326ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:32.888277Z","caller":"traceutil/trace.go:171","msg":"trace[2067532095] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8942; }","duration":"119.131755ms","start":"2026-10-17T11:14:32.769133Z","end":"2026-10-17T11:14:32.888265Z","steps":["trace[2067532095] 'agreement among raft nodes before linearized reading' (duration: 119.051643ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:33.139162Z","caller":"traceutil/trace.go:171","msg":"trace[937421476] linearizableReadLoop","detail":"{readStateIndex:9264; appliedIndex:9263; }","duration":"244.740458ms","start":"2026-10-17T11:14:32.894389Z","end":"2026-10-17T11:14:33.13913Z","steps":["trace[937421476] 'read index received' (duration: 207.463899ms)","trace[937421476] 'applied index is now lower than readState.Index' (duration: 37.275239ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:14:33.139178Z","caller":"traceutil/trace.go:171","msg":"trace[1139278892] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"248.647618ms","start":"2026-10-17T11:14:32.890489Z","end":"2026-10-17T11:14:33.139136Z","steps":["trace[1139278892] 'process raft request' (duration: 211.284957ms)","trace[1139278892] 'compare' (duration: 37.227747ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:14:33.139416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.011464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-17T11:14:33.13947Z","caller":"traceutil/trace.go:171","msg":"trace[8288688] range","detail":"{range_begin:/registry/masterleases/162.253.55.193; range_end:; response_count:1; response_revision:8943; }","duration":"245.108087ms","start":"2026-10-17T11:14:32.894345Z","end":"2026-10-17T11:14:33.139453Z","steps":["trace[8288688] 'agreement among raft nodes before linearized reading' (duration: 244.909952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:14:33.153847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.184121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-17T11:14:33.153931Z","caller":"traceutil/trace.go:171","msg":"trace[555102670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8944; }","duration":"249.315745ms","start":"2026-10-17T11:14:32.9046Z","end":"2026-10-17T11:14:33.153915Z","steps":["trace[555102670] 'agreement among raft nodes before linearized reading' (duration: 249.109799ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:46.190745Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024bd.wal"} {"level":"info","ts":"2026-10-17T11:15:41.163898Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:15:41.174578Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T11:15:41.174671Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-17T11:16:00.192384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.827628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:16:00.192465Z","caller":"traceutil/trace.go:171","msg":"trace[1013812344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9780; }","duration":"125.965371ms","start":"2026-10-17T11:16:00.066481Z","end":"2026-10-17T11:16:00.192446Z","steps":["trace[1013812344] 'range keys from in-memory index tree' (duration: 125.751755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:00.192624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.503169ms","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-10-17T11:16:00.192705Z","caller":"traceutil/trace.go:171","msg":"trace[934508362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9780; }","duration":"139.641212ms","start":"2026-10-17T11:16:00.053047Z","end":"2026-10-17T11:16:00.192688Z","steps":["trace[934508362] 'range keys from in-memory index tree' (duration: 139.270414ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:17:29.270541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7991} {"level":"info","ts":"2026-10-17T11:17:29.349603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7991,"took":"77.111785ms","hash":2165852793,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":28000256,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-17T11:17:29.349664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165852793,"revision":7991,"compact-revision":5826} {"level":"warn","ts":"2026-10-17T11:21:43.744853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.880371ms","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-10-17T11:21:43.744948Z","caller":"traceutil/trace.go:171","msg":"trace[20120012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12239; }","duration":"107.042874ms","start":"2026-10-17T11:21:43.637887Z","end":"2026-10-17T11:21:43.74493Z","steps":["trace[20120012] 'range keys from in-memory index tree' (duration: 106.715646ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:29.277791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10391} {"level":"info","ts":"2026-10-17T11:22:29.363464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10391,"took":"83.08454ms","hash":1908808941,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":25796608,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T11:22:29.363534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1908808941,"revision":10391,"compact-revision":7991} {"level":"info","ts":"2026-10-17T11:27:29.283804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12563} {"level":"info","ts":"2026-10-17T11:27:29.379051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12563,"took":"92.728533ms","hash":1978004316,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":27205632,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T11:27:29.379114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1978004316,"revision":12563,"compact-revision":10391} {"level":"info","ts":"2026-10-17T11:27:42.235176Z","caller":"traceutil/trace.go:171","msg":"trace[1597840864] linearizableReadLoop","detail":"{readStateIndex:15193; appliedIndex:15192; }","duration":"114.120085ms","start":"2026-10-17T11:27:42.121034Z","end":"2026-10-17T11:27:42.235154Z","steps":["trace[1597840864] 'read index received' (duration: 113.92161ms)","trace[1597840864] 'applied index is now lower than readState.Index' (duration: 197.575µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:27:42.235285Z","caller":"traceutil/trace.go:171","msg":"trace[1133568848] transaction","detail":"{read_only:false; response_revision:14680; number_of_response:1; }","duration":"159.115556ms","start":"2026-10-17T11:27:42.07616Z","end":"2026-10-17T11:27:42.235275Z","steps":["trace[1133568848] 'process raft request' (duration: 158.85547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:27:42.235491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.424993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:27:42.235576Z","caller":"traceutil/trace.go:171","msg":"trace[1723862928] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:14680; }","duration":"114.525875ms","start":"2026-10-17T11:27:42.121028Z","end":"2026-10-17T11:27:42.235554Z","steps":["trace[1723862928] 'agreement among raft nodes before linearized reading' (duration: 114.372661ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:28:19.81445Z","caller":"traceutil/trace.go:171","msg":"trace[1963617608] transaction","detail":"{read_only:false; response_revision:14892; number_of_response:1; }","duration":"160.579423ms","start":"2026-10-17T11:28:19.65384Z","end":"2026-10-17T11:28:19.814419Z","steps":["trace[1963617608] 'process raft request' (duration: 160.232814ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:28:20.398469Z","caller":"traceutil/trace.go:171","msg":"trace[826522363] linearizableReadLoop","detail":"{readStateIndex:15417; appliedIndex:15416; }","duration":"331.246608ms","start":"2026-10-17T11:28:20.067194Z","end":"2026-10-17T11:28:20.398441Z","steps":["trace[826522363] 'read index received' (duration: 331.038852ms)","trace[826522363] 'applied index is now lower than readState.Index' (duration: 202.835µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:28:20.398612Z","caller":"traceutil/trace.go:171","msg":"trace[300038978] transaction","detail":"{read_only:false; response_revision:14895; number_of_response:1; }","duration":"343.171905ms","start":"2026-10-17T11:28:20.055425Z","end":"2026-10-17T11:28:20.398597Z","steps":["trace[300038978] 'process raft request' (duration: 342.800345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:20.398735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:28:20.055369Z","time spent":"343.276589ms","remote":"127.0.0.1:49048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:28:20.398873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.659808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:28:20.398944Z","caller":"traceutil/trace.go:171","msg":"trace[944351849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14895; }","duration":"331.768041ms","start":"2026-10-17T11:28:20.067159Z","end":"2026-10-17T11:28:20.398927Z","steps":["trace[944351849] 'agreement among raft nodes before linearized reading' (duration: 331.656358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:20.398978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:28:20.067144Z","time spent":"331.826972ms","remote":"127.0.0.1:48882","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-10-17T11:28:20.415504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.73798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:28:20.415593Z","caller":"traceutil/trace.go:171","msg":"trace[1609788657] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:14896; }","duration":"269.884124ms","start":"2026-10-17T11:28:20.145687Z","end":"2026-10-17T11:28:20.415572Z","steps":["trace[1609788657] 'agreement among raft nodes before linearized reading' (duration: 269.73643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:20.415602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.190149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-17T11:28:20.415688Z","caller":"traceutil/trace.go:171","msg":"trace[1194473580] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14896; }","duration":"173.299202ms","start":"2026-10-17T11:28:20.242364Z","end":"2026-10-17T11:28:20.415663Z","steps":["trace[1194473580] 'agreement among raft nodes before linearized reading' (duration: 173.022525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:20.415829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.853382ms","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-10-17T11:28:20.415883Z","caller":"traceutil/trace.go:171","msg":"trace[2045741851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14896; }","duration":"336.925714ms","start":"2026-10-17T11:28:20.078944Z","end":"2026-10-17T11:28:20.41587Z","steps":["trace[2045741851] 'agreement among raft nodes before linearized reading' (duration: 336.845052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:20.415911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:28:20.078932Z","time spent":"336.972055ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-17T11:28:44.731142Z","caller":"traceutil/trace.go:171","msg":"trace[207661182] transaction","detail":"{read_only:false; response_revision:15212; number_of_response:1; }","duration":"352.893956ms","start":"2026-10-17T11:28:44.378223Z","end":"2026-10-17T11:28:44.731117Z","steps":["trace[207661182] 'process raft request' (duration: 352.720642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:44.731301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:28:44.378199Z","time spent":"353.02273ms","remote":"127.0.0.1:49048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:28:44.801312Z","caller":"traceutil/trace.go:171","msg":"trace[1171456871] linearizableReadLoop","detail":"{readStateIndex:15747; appliedIndex:15745; }","duration":"250.183215ms","start":"2026-10-17T11:28:44.551105Z","end":"2026-10-17T11:28:44.801288Z","steps":["trace[1171456871] 'read index received' (duration: 179.782439ms)","trace[1171456871] 'applied index is now lower than readState.Index' (duration: 70.399476ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:28:44.801433Z","caller":"traceutil/trace.go:171","msg":"trace[885294029] transaction","detail":"{read_only:false; response_revision:15213; number_of_response:1; }","duration":"279.506732ms","start":"2026-10-17T11:28:44.52186Z","end":"2026-10-17T11:28:44.801367Z","steps":["trace[885294029] 'process raft request' (duration: 279.295427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:44.801535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.398161ms","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-10-17T11:28:44.80162Z","caller":"traceutil/trace.go:171","msg":"trace[2000032132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15213; }","duration":"250.539514ms","start":"2026-10-17T11:28:44.55106Z","end":"2026-10-17T11:28:44.8016Z","steps":["trace[2000032132] 'agreement among raft nodes before linearized reading' (duration: 250.392931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:44.80532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.490938ms","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-10-17T11:28:44.805444Z","caller":"traceutil/trace.go:171","msg":"trace[1353819644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15214; }","duration":"251.612672ms","start":"2026-10-17T11:28:44.553781Z","end":"2026-10-17T11:28:44.805393Z","steps":["trace[1353819644] 'agreement among raft nodes before linearized reading' (duration: 251.450778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:28:44.805573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.762709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mcr8n\" ","response":"range_response_count:1 size:20807"} {"level":"info","ts":"2026-10-17T11:28:44.805704Z","caller":"traceutil/trace.go:171","msg":"trace[2096381754] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-mcr8n; range_end:; response_count:1; response_revision:15214; }","duration":"119.984976ms","start":"2026-10-17T11:28:44.6857Z","end":"2026-10-17T11:28:44.805685Z","steps":["trace[2096381754] 'agreement among raft nodes before linearized reading' (duration: 119.634096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:11.711155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.108752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:30:11.711275Z","caller":"traceutil/trace.go:171","msg":"trace[1174465239] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16028; }","duration":"192.282827ms","start":"2026-10-17T11:30:11.518969Z","end":"2026-10-17T11:30:11.711252Z","steps":["trace[1174465239] 'range keys from in-memory index tree' (duration: 192.036411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:11.711435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.647152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-17T11:30:11.711547Z","caller":"traceutil/trace.go:171","msg":"trace[278889289] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16028; }","duration":"176.793916ms","start":"2026-10-17T11:30:11.534734Z","end":"2026-10-17T11:30:11.711528Z","steps":["trace[278889289] 'range keys from in-memory index tree' (duration: 176.467048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:11.711561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.650362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:30:11.711645Z","caller":"traceutil/trace.go:171","msg":"trace[2134873072] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16028; }","duration":"193.779235ms","start":"2026-10-17T11:30:11.517846Z","end":"2026-10-17T11:30:11.711625Z","steps":["trace[2134873072] 'range keys from in-memory index tree' (duration: 193.569789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:11.71175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.902898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:30:11.711797Z","caller":"traceutil/trace.go:171","msg":"trace[211810607] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16028; }","duration":"193.952729ms","start":"2026-10-17T11:30:11.517835Z","end":"2026-10-17T11:30:11.711787Z","steps":["trace[211810607] 'range keys from in-memory index tree' (duration: 193.813756ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:32:29.292621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14570} {"level":"info","ts":"2026-10-17T11:32:29.393698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14570,"took":"98.2354ms","hash":927695839,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":31764480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:32:29.393781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":927695839,"revision":14570,"compact-revision":12563} {"level":"info","ts":"2026-10-17T11:35:59.435239Z","caller":"traceutil/trace.go:171","msg":"trace[363298600] transaction","detail":"{read_only:false; response_revision:18614; number_of_response:1; }","duration":"205.510456ms","start":"2026-10-17T11:35:59.229685Z","end":"2026-10-17T11:35:59.435195Z","steps":["trace[363298600] 'process raft request' (duration: 205.313191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:06.503319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.613383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:36:06.503436Z","caller":"traceutil/trace.go:171","msg":"trace[331090823] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:18650; }","duration":"138.809258ms","start":"2026-10-17T11:36:06.364601Z","end":"2026-10-17T11:36:06.503411Z","steps":["trace[331090823] 'count revisions from in-memory index tree' (duration: 138.481849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:06.503579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.564222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mcr8n\" ","response":"range_response_count:1 size:20910"} {"level":"info","ts":"2026-10-17T11:36:06.50378Z","caller":"traceutil/trace.go:171","msg":"trace[1453015739] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-mcr8n; range_end:; response_count:1; response_revision:18650; }","duration":"114.786909ms","start":"2026-10-17T11:36:06.388899Z","end":"2026-10-17T11:36:06.503686Z","steps":["trace[1453015739] 'range keys from in-memory index tree' (duration: 114.280066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:06.503792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.176497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-17T11:36:06.503767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.362021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T11:36:06.503856Z","caller":"traceutil/trace.go:171","msg":"trace[823038501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18650; }","duration":"231.28741ms","start":"2026-10-17T11:36:06.272553Z","end":"2026-10-17T11:36:06.503841Z","steps":["trace[823038501] 'range keys from in-memory index tree' (duration: 230.87322ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:36:06.503881Z","caller":"traceutil/trace.go:171","msg":"trace[259614295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18650; }","duration":"229.552986ms","start":"2026-10-17T11:36:06.274301Z","end":"2026-10-17T11:36:06.503854Z","steps":["trace[259614295] 'range keys from in-memory index tree' (duration: 229.117004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:07.003242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.625244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:36:07.00363Z","caller":"traceutil/trace.go:171","msg":"trace[185034220] transaction","detail":"{read_only:false; response_revision:18655; number_of_response:1; }","duration":"402.04959ms","start":"2026-10-17T11:36:06.601561Z","end":"2026-10-17T11:36:07.003611Z","steps":["trace[185034220] 'process raft request' (duration: 402.009939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:07.003698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:36:06.601533Z","time spent":"402.129243ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:36:07.003902Z","caller":"traceutil/trace.go:171","msg":"trace[112171413] linearizableReadLoop","detail":"{readStateIndex:19294; appliedIndex:19293; }","duration":"493.117777ms","start":"2026-10-17T11:36:06.510773Z","end":"2026-10-17T11:36:07.003891Z","steps":["trace[112171413] 'read index received' (duration: 15.714424ms)","trace[112171413] 'applied index is now lower than readState.Index' (duration: 477.402253ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:36:07.003889Z","caller":"traceutil/trace.go:171","msg":"trace[1012349332] transaction","detail":"{read_only:false; response_revision:18653; number_of_response:1; }","duration":"481.548569ms","start":"2026-10-17T11:36:06.522259Z","end":"2026-10-17T11:36:07.003807Z","steps":["trace[1012349332] 'process raft request' (duration: 481.139008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:07.004058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.281581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-25rrj\" ","response":"range_response_count:1 size:9794"} {"level":"info","ts":"2026-10-17T11:36:07.004094Z","caller":"traceutil/trace.go:171","msg":"trace[701838661] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-25rrj; range_end:; response_count:1; response_revision:18655; }","duration":"493.337373ms","start":"2026-10-17T11:36:06.510748Z","end":"2026-10-17T11:36:07.004086Z","steps":["trace[701838661] 'agreement among raft nodes before linearized reading' (duration: 493.18555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:07.004121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:36:06.51074Z","time spent":"493.374384ms","remote":"127.0.0.1:49048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9817,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-25rrj\" "} {"level":"warn","ts":"2026-10-17T11:36:07.004167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:36:06.522237Z","time spent":"481.781166ms","remote":"127.0.0.1:49048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21025,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:36:07.004377Z","caller":"traceutil/trace.go:171","msg":"trace[942174566] transaction","detail":"{read_only:false; response_revision:18654; number_of_response:1; }","duration":"411.009632ms","start":"2026-10-17T11:36:06.593357Z","end":"2026-10-17T11:36:07.004366Z","steps":["trace[942174566] 'process raft request' (duration: 410.165719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:07.004444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:36:06.59332Z","time spent":"411.093264ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:36:07.004538Z","caller":"traceutil/trace.go:171","msg":"trace[1323049121] transaction","detail":"{read_only:false; response_revision:18652; number_of_response:1; }","duration":"495.356695ms","start":"2026-10-17T11:36:06.509124Z","end":"2026-10-17T11:36:07.004481Z","steps":["trace[1323049121] 'process raft request' (duration: 256.377606ms)","trace[1323049121] 'compare' (duration: 237.152132ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:36:07.00465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:36:06.509094Z","time spent":"495.504099ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:37:03.348815Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:37:03.358002Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T11:37:03.358223Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T11:37:29.309069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17038} {"level":"info","ts":"2026-10-17T11:37:29.426153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17038,"took":"113.980777ms","hash":2149709994,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":33636352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-17T11:37:29.426223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2149709994,"revision":17038,"compact-revision":14570} {"level":"info","ts":"2026-10-17T11:41:56.21408Z","caller":"traceutil/trace.go:171","msg":"trace[1653343907] linearizableReadLoop","detail":"{readStateIndex:22081; appliedIndex:22080; }","duration":"311.757561ms","start":"2026-10-17T11:41:55.902294Z","end":"2026-10-17T11:41:56.214051Z","steps":["trace[1653343907] 'read index received' (duration: 311.462444ms)","trace[1653343907] 'applied index is now lower than readState.Index' (duration: 293.867µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:56.214223Z","caller":"traceutil/trace.go:171","msg":"trace[897316754] transaction","detail":"{read_only:false; response_revision:21348; number_of_response:1; }","duration":"312.504402ms","start":"2026-10-17T11:41:55.901708Z","end":"2026-10-17T11:41:56.214212Z","steps":["trace[897316754] 'process raft request' (duration: 312.136432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.214336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:55.901689Z","time spent":"312.557792ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:41:56.21444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.174128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:41:56.214544Z","caller":"traceutil/trace.go:171","msg":"trace[383674294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21348; }","duration":"148.336752ms","start":"2026-10-17T11:41:56.066181Z","end":"2026-10-17T11:41:56.214517Z","steps":["trace[383674294] 'agreement among raft nodes before linearized reading' (duration: 148.160588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.214618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.749134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:41:56.214751Z","caller":"traceutil/trace.go:171","msg":"trace[245284893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21348; }","duration":"115.909488ms","start":"2026-10-17T11:41:56.098815Z","end":"2026-10-17T11:41:56.214724Z","steps":["trace[245284893] 'agreement among raft nodes before linearized reading' (duration: 115.623061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.214768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.396368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18577"} {"level":"info","ts":"2026-10-17T11:41:56.214931Z","caller":"traceutil/trace.go:171","msg":"trace[56315200] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21348; }","duration":"312.619354ms","start":"2026-10-17T11:41:55.902268Z","end":"2026-10-17T11:41:56.214887Z","steps":["trace[56315200] 'agreement among raft nodes before linearized reading' (duration: 312.09939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.215013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:55.902257Z","time spent":"312.743576ms","remote":"127.0.0.1:49044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18600,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-17T11:41:56.344494Z","caller":"traceutil/trace.go:171","msg":"trace[1214006729] transaction","detail":"{read_only:false; response_revision:21350; number_of_response:1; }","duration":"121.661307ms","start":"2026-10-17T11:41:56.222811Z","end":"2026-10-17T11:41:56.344473Z","steps":["trace[1214006729] 'process raft request' (duration: 121.342578ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:56.34456Z","caller":"traceutil/trace.go:171","msg":"trace[34409512] transaction","detail":"{read_only:false; response_revision:21349; number_of_response:1; }","duration":"124.518041ms","start":"2026-10-17T11:41:56.220006Z","end":"2026-10-17T11:41:56.344524Z","steps":["trace[34409512] 'process raft request' (duration: 102.891614ms)","trace[34409512] 'compare' (duration: 21.132833ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:56.671467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.716714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:41:56.671672Z","caller":"traceutil/trace.go:171","msg":"trace[1837884208] transaction","detail":"{read_only:false; response_revision:21352; number_of_response:1; }","duration":"206.465575ms","start":"2026-10-17T11:41:56.465188Z","end":"2026-10-17T11:41:56.671653Z","steps":["trace[1837884208] 'process raft request' (duration: 206.405914ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:56.672003Z","caller":"traceutil/trace.go:171","msg":"trace[665742237] transaction","detail":"{read_only:false; response_revision:21351; number_of_response:1; }","duration":"311.489655ms","start":"2026-10-17T11:41:56.360457Z","end":"2026-10-17T11:41:56.671947Z","steps":["trace[665742237] 'process raft request' (duration: 144.191176ms)","trace[665742237] 'compare' (duration: 166.54916ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:56.671997Z","caller":"traceutil/trace.go:171","msg":"trace[1519392317] linearizableReadLoop","detail":"{readStateIndex:22084; appliedIndex:22083; }","duration":"305.161351ms","start":"2026-10-17T11:41:56.36679Z","end":"2026-10-17T11:41:56.671951Z","steps":["trace[1519392317] 'read index received' (duration: 137.881643ms)","trace[1519392317] 'applied index is now lower than readState.Index' (duration: 167.275948ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:56.672223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:56.360436Z","time spent":"311.660239ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:41:56.672274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.461899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6012"} {"level":"info","ts":"2026-10-17T11:41:56.672322Z","caller":"traceutil/trace.go:171","msg":"trace[476484743] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21352; }","duration":"305.544763ms","start":"2026-10-17T11:41:56.366761Z","end":"2026-10-17T11:41:56.672306Z","steps":["trace[476484743] 'agreement among raft nodes before linearized reading' (duration: 305.307026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.672357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:56.366747Z","time spent":"305.599934ms","remote":"127.0.0.1:37804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":6035,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-17T11:41:56.976233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.369118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:41:56.976362Z","caller":"traceutil/trace.go:171","msg":"trace[774973683] transaction","detail":"{read_only:false; response_revision:21353; number_of_response:1; }","duration":"430.625077ms","start":"2026-10-17T11:41:56.545713Z","end":"2026-10-17T11:41:56.976338Z","steps":["trace[774973683] 'process raft request' (duration: 297.036333ms)","trace[774973683] 'compare' (duration: 133.210724ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:56.976433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:56.545676Z","time spent":"430.720839ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:41:56.976768Z","caller":"traceutil/trace.go:171","msg":"trace[428362838] linearizableReadLoop","detail":"{readStateIndex:22087; appliedIndex:22085; }","duration":"304.474744ms","start":"2026-10-17T11:41:56.672259Z","end":"2026-10-17T11:41:56.976734Z","steps":["trace[428362838] 'read index received' (duration: 170.50221ms)","trace[428362838] 'applied index is now lower than readState.Index' (duration: 133.971554ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:56.976839Z","caller":"traceutil/trace.go:171","msg":"trace[785248152] transaction","detail":"{read_only:false; response_revision:21354; number_of_response:1; }","duration":"429.205299ms","start":"2026-10-17T11:41:56.547602Z","end":"2026-10-17T11:41:56.976808Z","steps":["trace[785248152] 'process raft request' (duration: 428.989444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.977088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.447521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-17T11:41:56.977113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.528735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-17T11:41:56.977114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:56.54757Z","time spent":"429.431245ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:41:56.97718Z","caller":"traceutil/trace.go:171","msg":"trace[83741709] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:21354; }","duration":"118.647898ms","start":"2026-10-17T11:41:56.858515Z","end":"2026-10-17T11:41:56.977163Z","steps":["trace[83741709] 'agreement among raft nodes before linearized reading' (duration: 118.515365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.977198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.497709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418353"} {"level":"info","ts":"2026-10-17T11:41:56.977178Z","caller":"traceutil/trace.go:171","msg":"trace[660871024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21354; }","duration":"507.571664ms","start":"2026-10-17T11:41:56.469566Z","end":"2026-10-17T11:41:56.977138Z","steps":["trace[660871024] 'agreement among raft nodes before linearized reading' (duration: 507.365469ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:56.977288Z","caller":"traceutil/trace.go:171","msg":"trace[472332143] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:21354; }","duration":"496.606812ms","start":"2026-10-17T11:41:56.480638Z","end":"2026-10-17T11:41:56.977245Z","steps":["trace[472332143] 'agreement among raft nodes before linearized reading' (duration: 496.185631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:56.977322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:56.469547Z","time spent":"507.745658ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-17T11:41:56.977351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:56.480623Z","time spent":"496.713254ms","remote":"127.0.0.1:51836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418376,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-10-17T11:41:57.256018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.985786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:41:57.256181Z","caller":"traceutil/trace.go:171","msg":"trace[1388688993] transaction","detail":"{read_only:false; response_revision:21356; number_of_response:1; }","duration":"274.072263ms","start":"2026-10-17T11:41:56.982088Z","end":"2026-10-17T11:41:57.256161Z","steps":["trace[1388688993] 'process raft request' (duration: 135.862752ms)","trace[1388688993] 'compare' (duration: 137.701769ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:57.256328Z","caller":"traceutil/trace.go:171","msg":"trace[1068968046] linearizableReadLoop","detail":"{readStateIndex:22090; appliedIndex:22088; }","duration":"189.539461ms","start":"2026-10-17T11:41:57.066756Z","end":"2026-10-17T11:41:57.256296Z","steps":["trace[1068968046] 'read index received' (duration: 51.092283ms)","trace[1068968046] 'applied index is now lower than readState.Index' (duration: 138.444998ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:57.25636Z","caller":"traceutil/trace.go:171","msg":"trace[723240345] transaction","detail":"{read_only:false; response_revision:21357; number_of_response:1; }","duration":"274.245408ms","start":"2026-10-17T11:41:56.982086Z","end":"2026-10-17T11:41:57.256332Z","steps":["trace[723240345] 'process raft request' (duration: 274.025912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:57.256437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.676285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:41:57.256468Z","caller":"traceutil/trace.go:171","msg":"trace[177611252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21357; }","duration":"189.748457ms","start":"2026-10-17T11:41:57.06671Z","end":"2026-10-17T11:41:57.256459Z","steps":["trace[177611252] 'agreement among raft nodes before linearized reading' (duration: 189.688395ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:57.70108Z","caller":"traceutil/trace.go:171","msg":"trace[1236065886] linearizableReadLoop","detail":"{readStateIndex:22091; appliedIndex:22090; }","duration":"251.931044ms","start":"2026-10-17T11:41:57.44912Z","end":"2026-10-17T11:41:57.701051Z","steps":["trace[1236065886] 'read index received' (duration: 251.677258ms)","trace[1236065886] 'applied index is now lower than readState.Index' (duration: 252.396µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:57.701183Z","caller":"traceutil/trace.go:171","msg":"trace[153920534] transaction","detail":"{read_only:false; response_revision:21358; number_of_response:1; }","duration":"255.485436ms","start":"2026-10-17T11:41:57.445651Z","end":"2026-10-17T11:41:57.701136Z","steps":["trace[153920534] 'process raft request' (duration: 255.214029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:57.701256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.105579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:41:57.701315Z","caller":"traceutil/trace.go:171","msg":"trace[548607689] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:21358; }","duration":"252.213412ms","start":"2026-10-17T11:41:57.449082Z","end":"2026-10-17T11:41:57.701295Z","steps":["trace[548607689] 'agreement among raft nodes before linearized reading' (duration: 252.089148ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:29.315643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19528} {"level":"info","ts":"2026-10-17T11:42:29.453701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19528,"took":"133.495096ms","hash":2529167378,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":28856320,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-17T11:42:29.453787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2529167378,"revision":19528,"compact-revision":17038} {"level":"info","ts":"2026-10-17T11:45:56.686667Z","caller":"traceutil/trace.go:171","msg":"trace[472508074] linearizableReadLoop","detail":"{readStateIndex:24671; appliedIndex:24670; }","duration":"204.742356ms","start":"2026-10-17T11:45:56.481902Z","end":"2026-10-17T11:45:56.686644Z","steps":["trace[472508074] 'read index received' (duration: 105.940134ms)","trace[472508074] 'applied index is now lower than readState.Index' (duration: 98.801092ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:56.686706Z","caller":"traceutil/trace.go:171","msg":"trace[1078302000] transaction","detail":"{read_only:false; response_revision:23865; number_of_response:1; }","duration":"328.712475ms","start":"2026-10-17T11:45:56.357965Z","end":"2026-10-17T11:45:56.686677Z","steps":["trace[1078302000] 'process raft request' (duration: 229.923824ms)","trace[1078302000] 'compare' (duration: 98.575856ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:56.686916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:56.357947Z","time spent":"328.854899ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:45:56.687214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.739352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-17T11:45:56.687191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.022776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-17T11:45:56.687296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.18098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-17T11:45:56.687275Z","caller":"traceutil/trace.go:171","msg":"trace[1701008787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23866; }","duration":"149.856755ms","start":"2026-10-17T11:45:56.537403Z","end":"2026-10-17T11:45:56.68726Z","steps":["trace[1701008787] 'agreement among raft nodes before linearized reading' (duration: 149.589738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:56.687317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.405993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418534"} {"level":"info","ts":"2026-10-17T11:45:56.687384Z","caller":"traceutil/trace.go:171","msg":"trace[144999203] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:23866; }","duration":"139.273902ms","start":"2026-10-17T11:45:56.548085Z","end":"2026-10-17T11:45:56.687359Z","steps":["trace[144999203] 'agreement among raft nodes before linearized reading' (duration: 139.022626ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:56.68739Z","caller":"traceutil/trace.go:171","msg":"trace[68835202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23866; }","duration":"146.301813ms","start":"2026-10-17T11:45:56.541067Z","end":"2026-10-17T11:45:56.687369Z","steps":["trace[68835202] 'agreement among raft nodes before linearized reading' (duration: 146.054656ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:56.68744Z","caller":"traceutil/trace.go:171","msg":"trace[1987091646] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:23866; }","duration":"205.572867ms","start":"2026-10-17T11:45:56.481836Z","end":"2026-10-17T11:45:56.687409Z","steps":["trace[1987091646] 'agreement among raft nodes before linearized reading' (duration: 204.961931ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:47:29.326673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21606} {"level":"info","ts":"2026-10-17T11:47:29.446071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21606,"took":"115.283698ms","hash":1979130705,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":43307008,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-17T11:47:29.446162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1979130705,"revision":21606,"compact-revision":19528} {"level":"warn","ts":"2026-10-17T11:47:32.899707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.920623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:47:32.89981Z","caller":"traceutil/trace.go:171","msg":"trace[502293027] linearizableReadLoop","detail":"{readStateIndex:25676; appliedIndex:25675; }","duration":"199.794377ms","start":"2026-10-17T11:47:32.699996Z","end":"2026-10-17T11:47:32.899791Z","steps":["trace[502293027] 'read index received' (duration: 25.71455ms)","trace[502293027] 'applied index is now lower than readState.Index' (duration: 174.078477ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:47:32.899951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.95719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:47:32.900001Z","caller":"traceutil/trace.go:171","msg":"trace[1920840120] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24843; }","duration":"200.048023ms","start":"2026-10-17T11:47:32.699936Z","end":"2026-10-17T11:47:32.899984Z","steps":["trace[1920840120] 'agreement among raft nodes before linearized reading' (duration: 199.95403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:47:32.899982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.606506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:47:32.900045Z","caller":"traceutil/trace.go:171","msg":"trace[1765186802] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24843; }","duration":"198.725669ms","start":"2026-10-17T11:47:32.701307Z","end":"2026-10-17T11:47:32.900032Z","steps":["trace[1765186802] 'agreement among raft nodes before linearized reading' (duration: 198.606736ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:47:32.89999Z","caller":"traceutil/trace.go:171","msg":"trace[960422219] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"243.728373ms","start":"2026-10-17T11:47:32.656166Z","end":"2026-10-17T11:47:32.899894Z","steps":["trace[960422219] 'process raft request' (duration: 69.538533ms)","trace[960422219] 'compare' (duration: 173.776689ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:47:32.900387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.823687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-17T11:47:32.900468Z","caller":"traceutil/trace.go:171","msg":"trace[1292628357] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24843; }","duration":"107.94456ms","start":"2026-10-17T11:47:32.792501Z","end":"2026-10-17T11:47:32.900445Z","steps":["trace[1292628357] 'agreement among raft nodes before linearized reading' (duration: 107.686224ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:35.994759Z","caller":"traceutil/trace.go:171","msg":"trace[1108622033] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"386.184052ms","start":"2026-10-17T11:49:35.608543Z","end":"2026-10-17T11:49:35.994727Z","steps":["trace[1108622033] 'process raft request' (duration: 385.989197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:35.994934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:49:35.60852Z","time spent":"386.328416ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:49:36.0096Z","caller":"traceutil/trace.go:171","msg":"trace[1798443194] linearizableReadLoop","detail":"{readStateIndex:26827; appliedIndex:26825; }","duration":"166.92046ms","start":"2026-10-17T11:49:35.842654Z","end":"2026-10-17T11:49:36.009574Z","steps":["trace[1798443194] 'read index received' (duration: 151.751139ms)","trace[1798443194] 'applied index is now lower than readState.Index' (duration: 15.167931ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:49:36.009721Z","caller":"traceutil/trace.go:171","msg":"trace[2034159372] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"372.782399ms","start":"2026-10-17T11:49:35.636929Z","end":"2026-10-17T11:49:36.009711Z","steps":["trace[2034159372] 'process raft request' (duration: 372.496012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:36.009838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:49:35.636907Z","time spent":"372.849271ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:49:36.010095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.435813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-17T11:49:36.010176Z","caller":"traceutil/trace.go:171","msg":"trace[695827032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25964; }","duration":"167.546516ms","start":"2026-10-17T11:49:35.8426Z","end":"2026-10-17T11:49:36.010146Z","steps":["trace[695827032] 'agreement among raft nodes before linearized reading' (duration: 167.385752ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:36.198984Z","caller":"traceutil/trace.go:171","msg":"trace[562276780] transaction","detail":"{read_only:false; response_revision:25967; number_of_response:1; }","duration":"104.7102ms","start":"2026-10-17T11:49:36.094248Z","end":"2026-10-17T11:49:36.198959Z","steps":["trace[562276780] 'process raft request' (duration: 100.78646ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:51.31069Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006960.wal"} {"level":"warn","ts":"2026-10-17T11:50:37.37173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.378859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:50:37.371932Z","caller":"traceutil/trace.go:171","msg":"trace[1068774193] linearizableReadLoop","detail":"{readStateIndex:27424; appliedIndex:27423; }","duration":"300.916822ms","start":"2026-10-17T11:50:37.070987Z","end":"2026-10-17T11:50:37.371903Z","steps":["trace[1068774193] 'read index received' (duration: 72.302197ms)","trace[1068774193] 'applied index is now lower than readState.Index' (duration: 228.611705ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:50:37.371979Z","caller":"traceutil/trace.go:171","msg":"trace[836766952] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"265.103543ms","start":"2026-10-17T11:50:37.106859Z","end":"2026-10-17T11:50:37.371963Z","steps":["trace[836766952] 'process raft request' (duration: 265.064962ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:50:37.372096Z","caller":"traceutil/trace.go:171","msg":"trace[1553549603] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"265.365979ms","start":"2026-10-17T11:50:37.106719Z","end":"2026-10-17T11:50:37.372085Z","steps":["trace[1553549603] 'process raft request' (duration: 265.110293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:50:37.372115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.105437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:50:37.372146Z","caller":"traceutil/trace.go:171","msg":"trace[1959460842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26550; }","duration":"301.187989ms","start":"2026-10-17T11:50:37.070949Z","end":"2026-10-17T11:50:37.372137Z","steps":["trace[1959460842] 'agreement among raft nodes before linearized reading' (duration: 301.092957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:50:37.372211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:50:37.07093Z","time spent":"301.24922ms","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-17T11:50:37.372214Z","caller":"traceutil/trace.go:171","msg":"trace[522482669] transaction","detail":"{read_only:false; response_revision:26548; number_of_response:1; }","duration":"301.670802ms","start":"2026-10-17T11:50:37.070499Z","end":"2026-10-17T11:50:37.37217Z","steps":["trace[522482669] 'process raft request' (duration: 72.77513ms)","trace[522482669] 'compare' (duration: 228.01033ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:50:37.372295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:50:37.070476Z","time spent":"301.776915ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":931,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:50:37.372725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.182627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:50:37.372766Z","caller":"traceutil/trace.go:171","msg":"trace[427524326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26550; }","duration":"176.262409ms","start":"2026-10-17T11:50:37.196493Z","end":"2026-10-17T11:50:37.372756Z","steps":["trace[427524326] 'agreement among raft nodes before linearized reading' (duration: 176.143876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:50:37.373562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.560209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-17T11:50:37.373601Z","caller":"traceutil/trace.go:171","msg":"trace[560241209] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26550; }","duration":"301.622871ms","start":"2026-10-17T11:50:37.071969Z","end":"2026-10-17T11:50:37.373592Z","steps":["trace[560241209] 'agreement among raft nodes before linearized reading' (duration: 300.4243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:50:37.373629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:50:37.071961Z","time spent":"301.657962ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2621,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:50:37.373802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.609187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:50:37.373833Z","caller":"traceutil/trace.go:171","msg":"trace[2041032269] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:26550; }","duration":"253.678919ms","start":"2026-10-17T11:50:37.120146Z","end":"2026-10-17T11:50:37.373825Z","steps":["trace[2041032269] 'agreement among raft nodes before linearized reading' (duration: 253.629258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:50:38.118999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.2054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-17T11:50:38.119107Z","caller":"traceutil/trace.go:171","msg":"trace[871465988] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26552; }","duration":"108.369345ms","start":"2026-10-17T11:50:38.010713Z","end":"2026-10-17T11:50:38.119082Z","steps":["trace[871465988] 'count revisions from in-memory index tree' (duration: 108.099328ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:50:48.061515Z","caller":"traceutil/trace.go:171","msg":"trace[1389053403] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"106.946027ms","start":"2026-10-17T11:50:47.954538Z","end":"2026-10-17T11:50:48.061484Z","steps":["trace[1389053403] 'process raft request' (duration: 106.741322ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:50:48.192736Z","caller":"traceutil/trace.go:171","msg":"trace[739933940] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"123.891454ms","start":"2026-10-17T11:50:48.068819Z","end":"2026-10-17T11:50:48.192711Z","steps":["trace[739933940] 'process raft request' (duration: 123.641487ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:52:29.333564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24819} {"level":"info","ts":"2026-10-17T11:52:29.504646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24819,"took":"166.930057ms","hash":2622153143,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":36253696,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-17T11:52:29.504718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2622153143,"revision":24819,"compact-revision":21606} {"level":"info","ts":"2026-10-17T11:54:53.928974Z","caller":"traceutil/trace.go:171","msg":"trace[2038557478] transaction","detail":"{read_only:false; response_revision:29054; number_of_response:1; }","duration":"146.768347ms","start":"2026-10-17T11:54:53.782168Z","end":"2026-10-17T11:54:53.928936Z","steps":["trace[2038557478] 'process raft request' (duration: 109.131509ms)","trace[2038557478] 'compare' (duration: 37.491745ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:54:53.933395Z","caller":"traceutil/trace.go:171","msg":"trace[1525968598] transaction","detail":"{read_only:false; response_revision:29055; number_of_response:1; }","duration":"140.005744ms","start":"2026-10-17T11:54:53.793357Z","end":"2026-10-17T11:54:53.933363Z","steps":["trace[1525968598] 'process raft request' (duration: 139.615144ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:54:54.326388Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:54:54.332071Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T11:54:54.332162Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T11:57:29.341028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27632} {"level":"info","ts":"2026-10-17T11:57:29.46747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27632,"took":"122.156393ms","hash":2149128823,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":36548608,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-17T11:57:29.467549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2149128823,"revision":27632,"compact-revision":24819} {"level":"info","ts":"2026-10-17T12:02:29.347813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30248} {"level":"info","ts":"2026-10-17T12:02:29.506362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30248,"took":"153.86807ms","hash":1591711406,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T12:02:29.506441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1591711406,"revision":30248,"compact-revision":27632} {"level":"info","ts":"2026-10-17T12:07:29.361453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32599} {"level":"info","ts":"2026-10-17T12:07:29.537357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32599,"took":"170.194695ms","hash":463381655,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":30466048,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T12:07:29.53743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":463381655,"revision":32599,"compact-revision":30248} {"level":"info","ts":"2026-10-17T12:12:29.370435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34863} {"level":"info","ts":"2026-10-17T12:12:29.51145Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34863,"took":"137.392001ms","hash":4205473349,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:12:29.511505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4205473349,"revision":34863,"compact-revision":32599} {"level":"info","ts":"2026-10-17T12:16:01.324654Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:16:01.330659Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T12:16:01.330817Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T12:17:29.377834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37159} {"level":"info","ts":"2026-10-17T12:17:29.520041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37159,"took":"136.429954ms","hash":2119420293,"current-db-size-bytes":61591552,"current-db-size":"62 MB","current-db-size-in-use-bytes":32665600,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T12:17:29.520101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2119420293,"revision":37159,"compact-revision":34863} {"level":"info","ts":"2026-10-17T12:22:29.389055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39436} {"level":"info","ts":"2026-10-17T12:22:29.524927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39436,"took":"131.527348ms","hash":3483137987,"current-db-size-bytes":62017536,"current-db-size":"62 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:22:29.524994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3483137987,"revision":39436,"compact-revision":37159}