{"level":"warn","ts":"2026-10-26T19:21:39.017178Z","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-26T19:21:39.017291Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.126: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.126:2380","--initial-cluster=instance=https://162.253.55.126:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.126:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.126: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-26T19:21:39.017384Z","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-26T19:21:39.017429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.126:2380"]} {"level":"info","ts":"2026-10-26T19:21:39.017455Z","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-26T19:21:39.018263Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"]} {"level":"info","ts":"2026-10-26T19:21:39.01837Z","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.126:2380"],"listen-peer-urls":["https://162.253.55.126:2380"],"advertise-client-urls":["https://162.253.55.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.126: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-26T19:21:39.024694Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.949424ms"} {"level":"info","ts":"2026-10-26T19:21:39.031565Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"85ebf4ab7bdf9269","cluster-id":"f923bca632e7887a"} {"level":"info","ts":"2026-10-26T19:21:39.031661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:21:39.031687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became follower at term 0"} {"level":"info","ts":"2026-10-26T19:21:39.031711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 85ebf4ab7bdf9269 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:21:39.031723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became follower at term 1"} {"level":"info","ts":"2026-10-26T19:21:39.031774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=(9650075643926254185)"} {"level":"warn","ts":"2026-10-26T19:21:39.040293Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:21:39.042025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:21:39.044095Z","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-26T19:21:39.047161Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"85ebf4ab7bdf9269","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:21:39.047353Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"85ebf4ab7bdf9269","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:21:39.047637Z","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-26T19:21:39.047738Z","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-26T19:21:39.047754Z","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-26T19:21:39.048922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=(9650075643926254185)"} {"level":"info","ts":"2026-10-26T19:21:39.050794Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f923bca632e7887a","local-member-id":"85ebf4ab7bdf9269","added-peer-id":"85ebf4ab7bdf9269","added-peer-peer-urls":["https://162.253.55.126:2380"]} {"level":"info","ts":"2026-10-26T19:21:39.052383Z","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-26T19:21:39.052562Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.126:2380"} {"level":"info","ts":"2026-10-26T19:21:39.052601Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.126:2380"} {"level":"info","ts":"2026-10-26T19:21:39.05287Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"85ebf4ab7bdf9269","initial-advertise-peer-urls":["https://162.253.55.126:2380"],"listen-peer-urls":["https://162.253.55.126:2380"],"advertise-client-urls":["https://162.253.55.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:21:39.052926Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:21:39.733053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:21:39.733114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:21:39.733154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 received MsgPreVoteResp from 85ebf4ab7bdf9269 at term 1"} {"level":"info","ts":"2026-10-26T19:21:39.733172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:21:39.733183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 received MsgVoteResp from 85ebf4ab7bdf9269 at term 2"} {"level":"info","ts":"2026-10-26T19:21:39.733205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became leader at term 2"} {"level":"info","ts":"2026-10-26T19:21:39.733223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 85ebf4ab7bdf9269 elected leader 85ebf4ab7bdf9269 at term 2"} {"level":"info","ts":"2026-10-26T19:21:39.734791Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"85ebf4ab7bdf9269","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.126:2379]}","request-path":"/0/members/85ebf4ab7bdf9269/attributes","cluster-id":"f923bca632e7887a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:21:39.73488Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:21:39.73498Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:21:39.735057Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:21:39.736054Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f923bca632e7887a","local-member-id":"85ebf4ab7bdf9269","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:21:39.736234Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:21:39.736271Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:21:39.737124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:21:39.737901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.126:2379"} {"level":"info","ts":"2026-10-26T19:21:39.7488Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:21:39.74887Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:21:56.768658Z","caller":"traceutil/trace.go:171","msg":"trace[827304672] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"283.876338ms","start":"2026-10-26T19:21:56.484753Z","end":"2026-10-26T19:21:56.76863Z","steps":["trace[827304672] 'process raft request' (duration: 283.623353ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:10.624166Z","caller":"traceutil/trace.go:171","msg":"trace[1053038186] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"105.2517ms","start":"2026-10-26T19:22:10.518888Z","end":"2026-10-26T19:22:10.624139Z","steps":["trace[1053038186] 'read index received' (duration: 47.730501ms)","trace[1053038186] 'applied index is now lower than readState.Index' (duration: 57.520109ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:10.624239Z","caller":"traceutil/trace.go:171","msg":"trace[469222822] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"115.797453ms","start":"2026-10-26T19:22:10.508393Z","end":"2026-10-26T19:22:10.624191Z","steps":["trace[469222822] 'process raft request' (duration: 58.301775ms)","trace[469222822] 'compare' (duration: 57.316365ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:22:10.624347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.419054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-10-26T19:22:10.624441Z","caller":"traceutil/trace.go:171","msg":"trace[1193474626] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:436; }","duration":"105.537116ms","start":"2026-10-26T19:22:10.518866Z","end":"2026-10-26T19:22:10.624404Z","steps":["trace[1193474626] 'agreement among raft nodes before linearized reading' (duration: 105.399663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:32.43051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.2467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qn6kb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-26T19:22:32.430622Z","caller":"traceutil/trace.go:171","msg":"trace[1007639983] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qn6kb; range_end:; response_count:1; response_revision:613; }","duration":"232.407834ms","start":"2026-10-26T19:22:32.198188Z","end":"2026-10-26T19:22:32.430596Z","steps":["trace[1007639983] 'range keys from in-memory index tree' (duration: 232.005726ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:32.566618Z","caller":"traceutil/trace.go:171","msg":"trace[842428273] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"251.286899ms","start":"2026-10-26T19:22:32.315311Z","end":"2026-10-26T19:22:32.566598Z","steps":["trace[842428273] 'process raft request' (duration: 251.164358ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:32.868819Z","caller":"traceutil/trace.go:171","msg":"trace[1995164868] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"525.301382ms","start":"2026-10-26T19:22:32.343482Z","end":"2026-10-26T19:22:32.868784Z","steps":["trace[1995164868] 'process raft request' (duration: 518.333356ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:32.868907Z","caller":"traceutil/trace.go:171","msg":"trace[2092535991] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:635; }","duration":"368.17109ms","start":"2026-10-26T19:22:32.500693Z","end":"2026-10-26T19:22:32.868864Z","steps":["trace[2092535991] 'read index received' (duration: 65.789768ms)","trace[2092535991] 'applied index is now lower than readState.Index' (duration: 302.380412ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:32.868979Z","caller":"traceutil/trace.go:171","msg":"trace[189119189] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"419.245731ms","start":"2026-10-26T19:22:32.449704Z","end":"2026-10-26T19:22:32.86895Z","steps":["trace[189119189] 'process raft request' (duration: 418.275883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:32.869004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.302082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:22:32.869043Z","caller":"traceutil/trace.go:171","msg":"trace[1929597730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"368.377694ms","start":"2026-10-26T19:22:32.500654Z","end":"2026-10-26T19:22:32.869032Z","steps":["trace[1929597730] 'agreement among raft nodes before linearized reading' (duration: 368.301692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:32.86908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:32.500637Z","time spent":"368.435915ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T19:22:32.869978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:32.343451Z","time spent":"525.458304ms","remote":"127.0.0.1:55714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:22:32.870079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:32.449677Z","time spent":"419.358214ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:22:33.526202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.813451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:22:33.526392Z","caller":"traceutil/trace.go:171","msg":"trace[1983036510] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"589.084121ms","start":"2026-10-26T19:22:32.937227Z","end":"2026-10-26T19:22:33.526312Z","steps":["trace[1983036510] 'process raft request' (duration: 371.882672ms)","trace[1983036510] 'compare' (duration: 216.597487ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:33.526325Z","caller":"traceutil/trace.go:171","msg":"trace[1874445569] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"478.537172ms","start":"2026-10-26T19:22:33.047767Z","end":"2026-10-26T19:22:33.526304Z","steps":["trace[1874445569] 'read index received' (duration: 261.262413ms)","trace[1874445569] 'applied index is now lower than readState.Index' (duration: 217.273319ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:22:33.526617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.828569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.126\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-26T19:22:33.526703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:32.937203Z","time spent":"589.376176ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:22:33.52676Z","caller":"traceutil/trace.go:171","msg":"trace[118422678] range","detail":"{range_begin:/registry/masterleases/162.253.55.126; range_end:; response_count:1; response_revision:617; }","duration":"479.018932ms","start":"2026-10-26T19:22:33.047712Z","end":"2026-10-26T19:22:33.526731Z","steps":["trace[118422678] 'agreement among raft nodes before linearized reading' (duration: 478.742106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:33.526816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:22:33.04769Z","time spent":"479.112864ms","remote":"127.0.0.1:55652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.126\" "} {"level":"info","ts":"2026-10-26T19:22:35.405549Z","caller":"traceutil/trace.go:171","msg":"trace[1262290326] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"133.140615ms","start":"2026-10-26T19:22:35.272384Z","end":"2026-10-26T19:22:35.405525Z","steps":["trace[1262290326] 'read index received' (duration: 19.765544ms)","trace[1262290326] 'applied index is now lower than readState.Index' (duration: 113.373971ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:35.405629Z","caller":"traceutil/trace.go:171","msg":"trace[1129815399] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"142.459766ms","start":"2026-10-26T19:22:35.263135Z","end":"2026-10-26T19:22:35.405595Z","steps":["trace[1129815399] 'process raft request' (duration: 97.665816ms)","trace[1129815399] 'compare' (duration: 44.552825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:22:35.405665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.271097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:22:35.405695Z","caller":"traceutil/trace.go:171","msg":"trace[1607191566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:639; }","duration":"133.349229ms","start":"2026-10-26T19:22:35.272337Z","end":"2026-10-26T19:22:35.405686Z","steps":["trace[1607191566] 'agreement among raft nodes before linearized reading' (duration: 133.278217ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:35.830956Z","caller":"traceutil/trace.go:171","msg":"trace[797216375] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"234.464463ms","start":"2026-10-26T19:22:35.59647Z","end":"2026-10-26T19:22:35.830935Z","steps":["trace[797216375] 'process raft request' (duration: 234.333561ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:35.838023Z","caller":"traceutil/trace.go:171","msg":"trace[759195389] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"240.606182ms","start":"2026-10-26T19:22:35.597366Z","end":"2026-10-26T19:22:35.837972Z","steps":["trace[759195389] 'process raft request' (duration: 240.368368ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:35.838168Z","caller":"traceutil/trace.go:171","msg":"trace[158190645] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"203.95149ms","start":"2026-10-26T19:22:35.634181Z","end":"2026-10-26T19:22:35.838132Z","steps":["trace[158190645] 'process raft request' (duration: 203.693585ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:36.441331Z","caller":"traceutil/trace.go:171","msg":"trace[1637894926] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"134.790187ms","start":"2026-10-26T19:22:36.306517Z","end":"2026-10-26T19:22:36.441307Z","steps":["trace[1637894926] 'process raft request' (duration: 93.858562ms)","trace[1637894926] 'compare' (duration: 40.840003ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:22:36.455014Z","caller":"traceutil/trace.go:171","msg":"trace[1386268792] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"147.595757ms","start":"2026-10-26T19:22:36.307395Z","end":"2026-10-26T19:22:36.45499Z","steps":["trace[1386268792] 'process raft request' (duration: 147.475225ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:23:17.893628Z","caller":"traceutil/trace.go:171","msg":"trace[36922371] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1214; }","duration":"110.273088ms","start":"2026-10-26T19:23:17.783332Z","end":"2026-10-26T19:23:17.893605Z","steps":["trace[36922371] 'read index received' (duration: 110.094424ms)","trace[36922371] 'applied index is now lower than readState.Index' (duration: 177.694µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:23:17.893673Z","caller":"traceutil/trace.go:171","msg":"trace[112955293] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"202.509064ms","start":"2026-10-26T19:23:17.691141Z","end":"2026-10-26T19:23:17.89365Z","steps":["trace[112955293] 'process raft request' (duration: 202.33371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:23:17.89378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.39268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:23:17.894234Z","caller":"traceutil/trace.go:171","msg":"trace[725145193] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1181; }","duration":"110.881219ms","start":"2026-10-26T19:23:17.783328Z","end":"2026-10-26T19:23:17.894209Z","steps":["trace[725145193] 'agreement among raft nodes before linearized reading' (duration: 110.37137ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:23:22.280049Z","caller":"traceutil/trace.go:171","msg":"trace[1364376526] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"103.887653ms","start":"2026-10-26T19:23:22.176137Z","end":"2026-10-26T19:23:22.280025Z","steps":["trace[1364376526] 'process raft request' (duration: 103.798441ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:24:08.050188Z","caller":"traceutil/trace.go:171","msg":"trace[1752567019] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"358.643934ms","start":"2026-10-26T19:24:07.691519Z","end":"2026-10-26T19:24:08.050163Z","steps":["trace[1752567019] 'read index received' (duration: 358.46003ms)","trace[1752567019] 'applied index is now lower than readState.Index' (duration: 183.034µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:24:08.050426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.883848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-362d67b0-aaf4-4139-92d0-c0ea0217413b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-26T19:24:08.050462Z","caller":"traceutil/trace.go:171","msg":"trace[2015690072] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-362d67b0-aaf4-4139-92d0-c0ea0217413b; range_end:; response_count:1; response_revision:1866; }","duration":"359.011669ms","start":"2026-10-26T19:24:07.691441Z","end":"2026-10-26T19:24:08.050453Z","steps":["trace[2015690072] 'agreement among raft nodes before linearized reading' (duration: 358.820037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:08.050513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:24:07.691424Z","time spent":"359.05984ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-362d67b0-aaf4-4139-92d0-c0ea0217413b\" "} {"level":"info","ts":"2026-10-26T19:24:08.050508Z","caller":"traceutil/trace.go:171","msg":"trace[1718554523] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"426.2866ms","start":"2026-10-26T19:24:07.624175Z","end":"2026-10-26T19:24:08.050461Z","steps":["trace[1718554523] 'process raft request' (duration: 425.853382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:08.050664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:24:07.624148Z","time spent":"426.430943ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:24:08.452744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.673543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:24:08.452938Z","caller":"traceutil/trace.go:171","msg":"trace[1003887759] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"394.790987ms","start":"2026-10-26T19:24:08.058065Z","end":"2026-10-26T19:24:08.452856Z","steps":["trace[1003887759] 'process raft request' (duration: 107.895431ms)","trace[1003887759] 'compare' (duration: 286.500238ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:24:08.453108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:24:08.058044Z","time spent":"394.993531ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:24:08.453251Z","caller":"traceutil/trace.go:171","msg":"trace[499952493] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"281.262157ms","start":"2026-10-26T19:24:08.171967Z","end":"2026-10-26T19:24:08.453229Z","steps":["trace[499952493] 'read index received' (duration: 281.128074ms)","trace[499952493] 'applied index is now lower than readState.Index' (duration: 132.772µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:24:08.453367Z","caller":"traceutil/trace.go:171","msg":"trace[212161495] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"327.65346ms","start":"2026-10-26T19:24:08.125684Z","end":"2026-10-26T19:24:08.453338Z","steps":["trace[212161495] 'process raft request' (duration: 327.427555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:08.453468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.499412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-26T19:24:08.453545Z","caller":"traceutil/trace.go:171","msg":"trace[1218499965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1868; }","duration":"281.620553ms","start":"2026-10-26T19:24:08.171909Z","end":"2026-10-26T19:24:08.45353Z","steps":["trace[1218499965] 'agreement among raft nodes before linearized reading' (duration: 281.43307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:08.45362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:24:08.125659Z","time spent":"327.807803ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:24:08.489047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.622252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T19:24:08.489065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.984685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-362d67b0-aaf4-4139-92d0-c0ea0217413b\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-26T19:24:08.489116Z","caller":"traceutil/trace.go:171","msg":"trace[736849368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"132.720875ms","start":"2026-10-26T19:24:08.356378Z","end":"2026-10-26T19:24:08.489099Z","steps":["trace[736849368] 'agreement among raft nodes before linearized reading' (duration: 132.569612ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:24:08.489144Z","caller":"traceutil/trace.go:171","msg":"trace[732563921] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-362d67b0-aaf4-4139-92d0-c0ea0217413b; range_end:; response_count:1; response_revision:1870; }","duration":"254.099027ms","start":"2026-10-26T19:24:08.235029Z","end":"2026-10-26T19:24:08.489129Z","steps":["trace[732563921] 'agreement among raft nodes before linearized reading' (duration: 253.941634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:25.782174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.089597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:24:25.782269Z","caller":"traceutil/trace.go:171","msg":"trace[1420508541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2016; }","duration":"277.227609ms","start":"2026-10-26T19:24:25.505017Z","end":"2026-10-26T19:24:25.782244Z","steps":["trace[1420508541] 'range keys from in-memory index tree' (duration: 276.970025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:25.782274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.77601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T19:24:25.782405Z","caller":"traceutil/trace.go:171","msg":"trace[1382445284] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2016; }","duration":"169.004554ms","start":"2026-10-26T19:24:25.613368Z","end":"2026-10-26T19:24:25.782373Z","steps":["trace[1382445284] 'range keys from in-memory index tree' (duration: 168.480944ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:24:58.126812Z","caller":"traceutil/trace.go:171","msg":"trace[2078579726] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"130.836583ms","start":"2026-10-26T19:24:57.995951Z","end":"2026-10-26T19:24:58.126787Z","steps":["trace[2078579726] 'read index received' (duration: 130.711101ms)","trace[2078579726] 'applied index is now lower than readState.Index' (duration: 124.432µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:24:58.126871Z","caller":"traceutil/trace.go:171","msg":"trace[752840878] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"168.466713ms","start":"2026-10-26T19:24:57.958352Z","end":"2026-10-26T19:24:58.126819Z","steps":["trace[752840878] 'process raft request' (duration: 168.268509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:24:58.127165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.197401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T19:24:58.127262Z","caller":"traceutil/trace.go:171","msg":"trace[1744661451] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2194; }","duration":"131.329223ms","start":"2026-10-26T19:24:57.995901Z","end":"2026-10-26T19:24:58.12723Z","steps":["trace[1744661451] 'agreement among raft nodes before linearized reading' (duration: 131.022577ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:04.563099Z","caller":"traceutil/trace.go:171","msg":"trace[1573412618] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"265.408541ms","start":"2026-10-26T19:25:04.297664Z","end":"2026-10-26T19:25:04.563072Z","steps":["trace[1573412618] 'process raft request' (duration: 265.242868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:04.952593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.675152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:25:04.952726Z","caller":"traceutil/trace.go:171","msg":"trace[993829440] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"277.450224ms","start":"2026-10-26T19:25:04.675255Z","end":"2026-10-26T19:25:04.952706Z","steps":["trace[993829440] 'process raft request' (duration: 100.547407ms)","trace[993829440] 'compare' (duration: 176.53897ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:04.991133Z","caller":"traceutil/trace.go:171","msg":"trace[1822671648] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2309; }","duration":"222.750174ms","start":"2026-10-26T19:25:04.768353Z","end":"2026-10-26T19:25:04.991103Z","steps":["trace[1822671648] 'read index received' (duration: 7.457685ms)","trace[1822671648] 'applied index is now lower than readState.Index' (duration: 215.291289ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:04.991173Z","caller":"traceutil/trace.go:171","msg":"trace[517493270] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"245.594636ms","start":"2026-10-26T19:25:04.745552Z","end":"2026-10-26T19:25:04.991147Z","steps":["trace[517493270] 'process raft request' (duration: 245.409003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:04.991378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.0229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T19:25:04.99141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.850862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T19:25:04.991434Z","caller":"traceutil/trace.go:171","msg":"trace[1548167717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2227; }","duration":"223.137332ms","start":"2026-10-26T19:25:04.76828Z","end":"2026-10-26T19:25:04.991417Z","steps":["trace[1548167717] 'agreement among raft nodes before linearized reading' (duration: 222.973318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:04.991422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.515933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T19:25:04.991441Z","caller":"traceutil/trace.go:171","msg":"trace[74644832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2227; }","duration":"213.929304ms","start":"2026-10-26T19:25:04.777501Z","end":"2026-10-26T19:25:04.991431Z","steps":["trace[74644832] 'agreement among raft nodes before linearized reading' (duration: 213.821852ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:04.991483Z","caller":"traceutil/trace.go:171","msg":"trace[1401406014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2227; }","duration":"218.617505ms","start":"2026-10-26T19:25:04.772848Z","end":"2026-10-26T19:25:04.991465Z","steps":["trace[1401406014] 'agreement among raft nodes before linearized reading' (duration: 218.420951ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:09.602632Z","caller":"traceutil/trace.go:171","msg":"trace[233454395] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"274.727296ms","start":"2026-10-26T19:26:09.327878Z","end":"2026-10-26T19:26:09.602605Z","steps":["trace[233454395] 'process raft request' (duration: 274.537552ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:09.603724Z","caller":"traceutil/trace.go:171","msg":"trace[728989353] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2845; }","duration":"257.974691ms","start":"2026-10-26T19:26:09.345721Z","end":"2026-10-26T19:26:09.603696Z","steps":["trace[728989353] 'read index received' (duration: 256.722447ms)","trace[728989353] 'applied index is now lower than readState.Index' (duration: 1.251244ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:26:09.604121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.056177ms","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-26T19:26:09.604193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.563424ms","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-26T19:26:09.604274Z","caller":"traceutil/trace.go:171","msg":"trace[2043434779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2743; }","duration":"206.705917ms","start":"2026-10-26T19:26:09.397555Z","end":"2026-10-26T19:26:09.604261Z","steps":["trace[2043434779] 'agreement among raft nodes before linearized reading' (duration: 206.484862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:09.604286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.535592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T19:26:09.60437Z","caller":"traceutil/trace.go:171","msg":"trace[1401654488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2743; }","duration":"258.656694ms","start":"2026-10-26T19:26:09.345695Z","end":"2026-10-26T19:26:09.604352Z","steps":["trace[1401654488] 'agreement among raft nodes before linearized reading' (duration: 258.385298ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:09.604194Z","caller":"traceutil/trace.go:171","msg":"trace[1272189339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2743; }","duration":"252.179359ms","start":"2026-10-26T19:26:09.351998Z","end":"2026-10-26T19:26:09.604177Z","steps":["trace[1272189339] 'agreement among raft nodes before linearized reading' (duration: 251.984875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:09.604147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.87608ms","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-26T19:26:09.60451Z","caller":"traceutil/trace.go:171","msg":"trace[1882219848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2743; }","duration":"207.282028ms","start":"2026-10-26T19:26:09.397214Z","end":"2026-10-26T19:26:09.604496Z","steps":["trace[1882219848] 'agreement among raft nodes before linearized reading' (duration: 206.882001ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:09.603993Z","caller":"traceutil/trace.go:171","msg":"trace[1435095397] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"272.594614ms","start":"2026-10-26T19:26:09.331385Z","end":"2026-10-26T19:26:09.603979Z","steps":["trace[1435095397] 'process raft request' (duration: 272.174696ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:41.888176Z","caller":"traceutil/trace.go:171","msg":"trace[87419910] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"127.487817ms","start":"2026-10-26T19:26:41.760644Z","end":"2026-10-26T19:26:41.888132Z","steps":["trace[87419910] 'process raft request' (duration: 67.815913ms)","trace[87419910] 'compare' (duration: 59.524531ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:26:42.072644Z","caller":"traceutil/trace.go:171","msg":"trace[1462074676] linearizableReadLoop","detail":"{readStateIndex:3053; appliedIndex:3051; }","duration":"306.553672ms","start":"2026-10-26T19:26:41.766067Z","end":"2026-10-26T19:26:42.072621Z","steps":["trace[1462074676] 'read index received' (duration: 62.443729ms)","trace[1462074676] 'applied index is now lower than readState.Index' (duration: 244.109123ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:26:42.072843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.762436ms","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-26T19:26:42.072878Z","caller":"traceutil/trace.go:171","msg":"trace[1956542676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2943; }","duration":"306.834158ms","start":"2026-10-26T19:26:41.766035Z","end":"2026-10-26T19:26:42.072869Z","steps":["trace[1956542676] 'agreement among raft nodes before linearized reading' (duration: 306.683675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:42.072908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:26:41.766025Z","time spent":"306.875698ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T19:26:42.073147Z","caller":"traceutil/trace.go:171","msg":"trace[286619522] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"309.838386ms","start":"2026-10-26T19:26:41.763279Z","end":"2026-10-26T19:26:42.073118Z","steps":["trace[286619522] 'process raft request' (duration: 309.158863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:42.073283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:26:41.763263Z","time spent":"309.950358ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:26:42.073308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.12422ms","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-26T19:26:42.073385Z","caller":"traceutil/trace.go:171","msg":"trace[485796115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2943; }","duration":"297.259123ms","start":"2026-10-26T19:26:41.776109Z","end":"2026-10-26T19:26:42.073368Z","steps":["trace[485796115] 'agreement among raft nodes before linearized reading' (duration: 297.031168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:42.073556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.1202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T19:26:42.073705Z","caller":"traceutil/trace.go:171","msg":"trace[519400319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2943; }","duration":"296.318604ms","start":"2026-10-26T19:26:41.777362Z","end":"2026-10-26T19:26:42.073681Z","steps":["trace[519400319] 'agreement among raft nodes before linearized reading' (duration: 295.921196ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:54.251135Z","caller":"traceutil/trace.go:171","msg":"trace[1742340493] transaction","detail":"{read_only:false; response_revision:4155; number_of_response:1; }","duration":"150.770253ms","start":"2026-10-26T19:29:54.100341Z","end":"2026-10-26T19:29:54.251111Z","steps":["trace[1742340493] 'process raft request' (duration: 150.61383ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:54.25296Z","caller":"traceutil/trace.go:171","msg":"trace[193298321] transaction","detail":"{read_only:false; response_revision:4156; number_of_response:1; }","duration":"145.096234ms","start":"2026-10-26T19:29:54.107835Z","end":"2026-10-26T19:29:54.252932Z","steps":["trace[193298321] 'process raft request' (duration: 144.957071ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:15.918101Z","caller":"traceutil/trace.go:171","msg":"trace[17962286] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"130.44711ms","start":"2026-10-26T19:31:15.787631Z","end":"2026-10-26T19:31:15.918078Z","steps":["trace[17962286] 'process raft request' (duration: 126.972993ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:22.792854Z","caller":"traceutil/trace.go:171","msg":"trace[1035057790] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"216.630105ms","start":"2026-10-26T19:31:22.576201Z","end":"2026-10-26T19:31:22.792831Z","steps":["trace[1035057790] 'process raft request' (duration: 216.470692ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:22.831568Z","caller":"traceutil/trace.go:171","msg":"trace[1538683439] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"253.768182ms","start":"2026-10-26T19:31:22.577777Z","end":"2026-10-26T19:31:22.831545Z","steps":["trace[1538683439] 'process raft request' (duration: 244.120265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:25.437515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.782117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17438"} {"level":"info","ts":"2026-10-26T19:31:25.437574Z","caller":"traceutil/trace.go:171","msg":"trace[1353215347] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5370; }","duration":"114.90167ms","start":"2026-10-26T19:31:25.32266Z","end":"2026-10-26T19:31:25.437561Z","steps":["trace[1353215347] 'range keys from in-memory index tree' (duration: 114.608014ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:38.233078Z","caller":"traceutil/trace.go:171","msg":"trace[221829245] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"123.578376ms","start":"2026-10-26T19:31:38.109466Z","end":"2026-10-26T19:31:38.233045Z","steps":["trace[221829245] 'process raft request' (duration: 70.533301ms)","trace[221829245] 'compare' (duration: 52.813752ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:31:38.233581Z","caller":"traceutil/trace.go:171","msg":"trace[564184124] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"124.067807ms","start":"2026-10-26T19:31:38.109499Z","end":"2026-10-26T19:31:38.233567Z","steps":["trace[564184124] 'process raft request' (duration: 123.474535ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:38.23358Z","caller":"traceutil/trace.go:171","msg":"trace[1914903425] linearizableReadLoop","detail":"{readStateIndex:5816; appliedIndex:5812; }","duration":"122.904144ms","start":"2026-10-26T19:31:38.110647Z","end":"2026-10-26T19:31:38.233552Z","steps":["trace[1914903425] 'read index received' (duration: 12.90004ms)","trace[1914903425] 'applied index is now lower than readState.Index' (duration: 110.001244ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:31:38.2339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.22671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-mhmzr\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-10-26T19:31:38.233962Z","caller":"traceutil/trace.go:171","msg":"trace[529058078] range","detail":"{range_begin:/registry/pods/monitoring/vector-mhmzr; range_end:; response_count:1; response_revision:5609; }","duration":"123.338663ms","start":"2026-10-26T19:31:38.110607Z","end":"2026-10-26T19:31:38.233946Z","steps":["trace[529058078] 'agreement among raft nodes before linearized reading' (duration: 123.059967ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:38.233715Z","caller":"traceutil/trace.go:171","msg":"trace[1657619506] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"124.095617ms","start":"2026-10-26T19:31:38.109601Z","end":"2026-10-26T19:31:38.233696Z","steps":["trace[1657619506] 'process raft request' (duration: 123.646498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:38.561144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.296895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:31:38.561788Z","caller":"traceutil/trace.go:171","msg":"trace[2073662253] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"318.80773ms","start":"2026-10-26T19:31:38.242868Z","end":"2026-10-26T19:31:38.561676Z","steps":["trace[2073662253] 'process raft request' (duration: 199.880762ms)","trace[2073662253] 'compare' (duration: 118.099581ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:31:38.562168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:31:38.242837Z","time spent":"319.137705ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5964,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:31:39.770655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2931} {"level":"info","ts":"2026-10-26T19:31:39.84568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2931,"took":"73.757864ms","hash":185804430,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":31244288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T19:31:39.845787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":185804430,"revision":2931,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:31:46.031865Z","caller":"traceutil/trace.go:171","msg":"trace[1804408769] linearizableReadLoop","detail":"{readStateIndex:5989; appliedIndex:5988; }","duration":"160.248986ms","start":"2026-10-26T19:31:45.871587Z","end":"2026-10-26T19:31:46.031836Z","steps":["trace[1804408769] 'read index received' (duration: 100.468331ms)","trace[1804408769] 'applied index is now lower than readState.Index' (duration: 59.779335ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:31:46.032244Z","caller":"traceutil/trace.go:171","msg":"trace[2031997278] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"232.351199ms","start":"2026-10-26T19:31:45.799844Z","end":"2026-10-26T19:31:46.032195Z","steps":["trace[2031997278] 'process raft request' (duration: 172.307949ms)","trace[2031997278] 'compare' (duration: 59.51665ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:31:46.03227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.995557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:31:46.032356Z","caller":"traceutil/trace.go:171","msg":"trace[562386517] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:5776; }","duration":"107.138049ms","start":"2026-10-26T19:31:45.925198Z","end":"2026-10-26T19:31:46.032336Z","steps":["trace[562386517] 'agreement among raft nodes before linearized reading' (duration: 106.977647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:46.032396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.809656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T19:31:46.033117Z","caller":"traceutil/trace.go:171","msg":"trace[1738857277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5776; }","duration":"160.929639ms","start":"2026-10-26T19:31:45.871513Z","end":"2026-10-26T19:31:46.032443Z","steps":["trace[1738857277] 'agreement among raft nodes before linearized reading' (duration: 160.670125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:46.040606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.806032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-26T19:31:46.040747Z","caller":"traceutil/trace.go:171","msg":"trace[1009146179] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5776; }","duration":"111.002426ms","start":"2026-10-26T19:31:45.929696Z","end":"2026-10-26T19:31:46.040698Z","steps":["trace[1009146179] 'agreement among raft nodes before linearized reading' (duration: 102.781676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:46.279661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.18886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:31:46.279965Z","caller":"traceutil/trace.go:171","msg":"trace[2053268622] linearizableReadLoop","detail":"{readStateIndex:5990; appliedIndex:5989; }","duration":"194.008398ms","start":"2026-10-26T19:31:46.08593Z","end":"2026-10-26T19:31:46.279938Z","steps":["trace[2053268622] 'read index received' (duration: 84.265038ms)","trace[2053268622] 'applied index is now lower than readState.Index' (duration: 109.74119ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:31:46.280005Z","caller":"traceutil/trace.go:171","msg":"trace[1466391879] transaction","detail":"{read_only:false; response_revision:5777; number_of_response:1; }","duration":"240.816012ms","start":"2026-10-26T19:31:46.039138Z","end":"2026-10-26T19:31:46.279954Z","steps":["trace[1466391879] 'process raft request' (duration: 131.157534ms)","trace[1466391879] 'compare' (duration: 108.992905ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:31:46.280265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.272374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-26T19:31:46.280385Z","caller":"traceutil/trace.go:171","msg":"trace[951363026] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5777; }","duration":"194.487857ms","start":"2026-10-26T19:31:46.085867Z","end":"2026-10-26T19:31:46.280354Z","steps":["trace[951363026] 'agreement among raft nodes before linearized reading' (duration: 194.143301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:46.280412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.208481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7a9e3c85-c1a6-4ed4-8e4c-bcd8d06d6672\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-26T19:31:46.280517Z","caller":"traceutil/trace.go:171","msg":"trace[730589010] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7a9e3c85-c1a6-4ed4-8e4c-bcd8d06d6672; range_end:; response_count:1; response_revision:5777; }","duration":"152.417245ms","start":"2026-10-26T19:31:46.128083Z","end":"2026-10-26T19:31:46.2805Z","steps":["trace[730589010] 'agreement among raft nodes before linearized reading' (duration: 152.066008ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:46.654522Z","caller":"traceutil/trace.go:171","msg":"trace[662513748] transaction","detail":"{read_only:false; response_revision:5778; number_of_response:1; }","duration":"365.478541ms","start":"2026-10-26T19:31:46.289024Z","end":"2026-10-26T19:31:46.654502Z","steps":["trace[662513748] 'process raft request' (duration: 365.276507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:46.6547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.153117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"warn","ts":"2026-10-26T19:31:46.654671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:31:46.288993Z","time spent":"365.592103ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:31:46.654782Z","caller":"traceutil/trace.go:171","msg":"trace[569598214] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5778; }","duration":"347.28769ms","start":"2026-10-26T19:31:46.307477Z","end":"2026-10-26T19:31:46.654765Z","steps":["trace[569598214] 'agreement among raft nodes before linearized reading' (duration: 347.080856ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:46.654497Z","caller":"traceutil/trace.go:171","msg":"trace[88399631] linearizableReadLoop","detail":"{readStateIndex:5991; appliedIndex:5990; }","duration":"346.922472ms","start":"2026-10-26T19:31:46.307545Z","end":"2026-10-26T19:31:46.654467Z","steps":["trace[88399631] 'read index received' (duration: 346.867381ms)","trace[88399631] 'applied index is now lower than readState.Index' (duration: 53.951µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:31:46.654834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:31:46.307457Z","time spent":"347.364341ms","remote":"127.0.0.1:55714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3708,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-10-26T19:31:46.686691Z","caller":"traceutil/trace.go:171","msg":"trace[1613723328] transaction","detail":"{read_only:false; response_revision:5779; number_of_response:1; }","duration":"363.963611ms","start":"2026-10-26T19:31:46.322696Z","end":"2026-10-26T19:31:46.686659Z","steps":["trace[1613723328] 'process raft request' (duration: 361.108356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:46.686979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:31:46.322679Z","time spent":"364.212657ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:31:46.687005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.721339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:31:46.687106Z","caller":"traceutil/trace.go:171","msg":"trace[879952788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5779; }","duration":"185.859201ms","start":"2026-10-26T19:31:46.50123Z","end":"2026-10-26T19:31:46.687089Z","steps":["trace[879952788] 'agreement among raft nodes before linearized reading' (duration: 185.590776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:31:46.686799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.579944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:31:46.687421Z","caller":"traceutil/trace.go:171","msg":"trace[1534859725] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5779; }","duration":"276.227257ms","start":"2026-10-26T19:31:46.411179Z","end":"2026-10-26T19:31:46.687406Z","steps":["trace[1534859725] 'agreement among raft nodes before linearized reading' (duration: 275.499593ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:40.111098Z","caller":"traceutil/trace.go:171","msg":"trace[2050912815] transaction","detail":"{read_only:false; response_revision:6292; number_of_response:1; }","duration":"107.196588ms","start":"2026-10-26T19:32:40.003861Z","end":"2026-10-26T19:32:40.111058Z","steps":["trace[2050912815] 'process raft request' (duration: 46.519097ms)","trace[2050912815] 'compare' (duration: 60.519089ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:36:39.77887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5627} {"level":"info","ts":"2026-10-26T19:36:39.865056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5627,"took":"84.284792ms","hash":638493082,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":27119616,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T19:36:39.865121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":638493082,"revision":5627,"compact-revision":2931} {"level":"info","ts":"2026-10-26T19:38:01.505134Z","caller":"traceutil/trace.go:171","msg":"trace[842898428] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"167.646327ms","start":"2026-10-26T19:38:01.337446Z","end":"2026-10-26T19:38:01.505093Z","steps":["trace[842898428] 'process raft request' (duration: 167.441783ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:13.283207Z","caller":"traceutil/trace.go:171","msg":"trace[2140883839] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"162.668141ms","start":"2026-10-26T19:38:13.12049Z","end":"2026-10-26T19:38:13.283158Z","steps":["trace[2140883839] 'process raft request' (duration: 127.30611ms)","trace[2140883839] 'compare' (duration: 35.125387ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:38:13.990437Z","caller":"traceutil/trace.go:171","msg":"trace[1052238862] linearizableReadLoop","detail":"{readStateIndex:9030; appliedIndex:9029; }","duration":"223.242957ms","start":"2026-10-26T19:38:13.767168Z","end":"2026-10-26T19:38:13.990411Z","steps":["trace[1052238862] 'read index received' (duration: 223.001573ms)","trace[1052238862] 'applied index is now lower than readState.Index' (duration: 240.064µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:38:13.990504Z","caller":"traceutil/trace.go:171","msg":"trace[688543165] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"224.37013ms","start":"2026-10-26T19:38:13.766082Z","end":"2026-10-26T19:38:13.990452Z","steps":["trace[688543165] 'process raft request' (duration: 224.178706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:13.990713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.520354ms","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-26T19:38:13.990811Z","caller":"traceutil/trace.go:171","msg":"trace[1641312490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8723; }","duration":"223.675926ms","start":"2026-10-26T19:38:13.767114Z","end":"2026-10-26T19:38:13.99079Z","steps":["trace[1641312490] 'agreement among raft nodes before linearized reading' (duration: 223.39157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:14.308577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.74172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:38:14.308794Z","caller":"traceutil/trace.go:171","msg":"trace[364451329] linearizableReadLoop","detail":"{readStateIndex:9032; appliedIndex:9030; }","duration":"155.471672ms","start":"2026-10-26T19:38:14.153305Z","end":"2026-10-26T19:38:14.308777Z","steps":["trace[364451329] 'read index received' (duration: 1.415196ms)","trace[364451329] 'applied index is now lower than readState.Index' (duration: 154.055096ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:38:14.308814Z","caller":"traceutil/trace.go:171","msg":"trace[768096754] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"313.76484ms","start":"2026-10-26T19:38:13.995017Z","end":"2026-10-26T19:38:14.308782Z","steps":["trace[768096754] 'process raft request' (duration: 159.719995ms)","trace[768096754] 'compare' (duration: 153.530476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:38:14.309032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:38:13.994994Z","time spent":"313.933773ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:38:14.309016Z","caller":"traceutil/trace.go:171","msg":"trace[683311177] transaction","detail":"{read_only:false; response_revision:8725; number_of_response:1; }","duration":"277.884179ms","start":"2026-10-26T19:38:14.031094Z","end":"2026-10-26T19:38:14.308978Z","steps":["trace[683311177] 'process raft request' (duration: 277.594664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:14.309145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.82673ms","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-26T19:38:14.309241Z","caller":"traceutil/trace.go:171","msg":"trace[1222095735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8725; }","duration":"155.964532ms","start":"2026-10-26T19:38:14.153251Z","end":"2026-10-26T19:38:14.309216Z","steps":["trace[1222095735] 'agreement among raft nodes before linearized reading' (duration: 155.722457ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:14.340052Z","caller":"traceutil/trace.go:171","msg":"trace[160640174] transaction","detail":"{read_only:false; response_revision:8726; number_of_response:1; }","duration":"104.851478ms","start":"2026-10-26T19:38:14.235181Z","end":"2026-10-26T19:38:14.340032Z","steps":["trace[160640174] 'process raft request' (duration: 104.512662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:14.342905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.424852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T19:38:14.343002Z","caller":"traceutil/trace.go:171","msg":"trace[1382597304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8727; }","duration":"127.627137ms","start":"2026-10-26T19:38:14.215351Z","end":"2026-10-26T19:38:14.342979Z","steps":["trace[1382597304] 'agreement among raft nodes before linearized reading' (duration: 124.894054ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:14.466829Z","caller":"traceutil/trace.go:171","msg":"trace[1211980010] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"152.72589ms","start":"2026-10-26T19:38:14.314063Z","end":"2026-10-26T19:38:14.466789Z","steps":["trace[1211980010] 'process raft request' (duration: 101.891661ms)","trace[1211980010] 'compare' (duration: 50.571104ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:38:14.466847Z","caller":"traceutil/trace.go:171","msg":"trace[1294826893] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"123.871095ms","start":"2026-10-26T19:38:14.342952Z","end":"2026-10-26T19:38:14.466823Z","steps":["trace[1294826893] 'process raft request' (duration: 123.730422ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:14.467098Z","caller":"traceutil/trace.go:171","msg":"trace[671533094] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"115.629797ms","start":"2026-10-26T19:38:14.351448Z","end":"2026-10-26T19:38:14.467078Z","steps":["trace[671533094] 'process raft request' (duration: 115.336211ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:14.467165Z","caller":"traceutil/trace.go:171","msg":"trace[370347007] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"114.220379ms","start":"2026-10-26T19:38:14.352925Z","end":"2026-10-26T19:38:14.467145Z","steps":["trace[370347007] 'process raft request' (duration: 114.047155ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:14.467255Z","caller":"traceutil/trace.go:171","msg":"trace[1523760788] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"101.571606ms","start":"2026-10-26T19:38:14.365668Z","end":"2026-10-26T19:38:14.46724Z","steps":["trace[1523760788] 'process raft request' (duration: 101.427783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:15.717434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.310351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h9xpp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:38:15.717514Z","caller":"traceutil/trace.go:171","msg":"trace[26220459] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h9xpp; range_end:; response_count:1; response_revision:8733; }","duration":"422.433632ms","start":"2026-10-26T19:38:15.295063Z","end":"2026-10-26T19:38:15.717497Z","steps":["trace[26220459] 'range keys from in-memory index tree' (duration: 422.157518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:15.717552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:38:15.295047Z","time spent":"422.497144ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-h9xpp\" "} {"level":"warn","ts":"2026-10-26T19:38:15.717593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.479407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:38:15.71765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.515102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-26T19:38:15.717686Z","caller":"traceutil/trace.go:171","msg":"trace[2097170653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8733; }","duration":"216.63302ms","start":"2026-10-26T19:38:15.501033Z","end":"2026-10-26T19:38:15.717666Z","steps":["trace[2097170653] 'range keys from in-memory index tree' (duration: 216.383635ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:15.717719Z","caller":"traceutil/trace.go:171","msg":"trace[12984712] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8733; }","duration":"278.630643ms","start":"2026-10-26T19:38:15.439071Z","end":"2026-10-26T19:38:15.717702Z","steps":["trace[12984712] 'range keys from in-memory index tree' (duration: 278.294428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:15.717617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.730734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:38:15.717797Z","caller":"traceutil/trace.go:171","msg":"trace[2074927333] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8733; }","duration":"440.939689ms","start":"2026-10-26T19:38:15.27683Z","end":"2026-10-26T19:38:15.71777Z","steps":["trace[2074927333] 'count revisions from in-memory index tree' (duration: 440.592182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:38:15.717909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:38:15.276812Z","time spent":"441.04127ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:38:16.122572Z","caller":"traceutil/trace.go:171","msg":"trace[1286931047] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"123.411266ms","start":"2026-10-26T19:38:15.999128Z","end":"2026-10-26T19:38:16.122539Z","steps":["trace[1286931047] 'process raft request' (duration: 123.215052ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:47.68949Z","caller":"traceutil/trace.go:171","msg":"trace[1862798377] linearizableReadLoop","detail":"{readStateIndex:9274; appliedIndex:9273; }","duration":"188.019229ms","start":"2026-10-26T19:38:47.501447Z","end":"2026-10-26T19:38:47.689466Z","steps":["trace[1862798377] 'read index received' (duration: 187.794464ms)","trace[1862798377] 'applied index is now lower than readState.Index' (duration: 223.715µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:38:47.68962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.160161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:38:47.689609Z","caller":"traceutil/trace.go:171","msg":"trace[1202866938] transaction","detail":"{read_only:false; response_revision:8958; number_of_response:1; }","duration":"224.424599ms","start":"2026-10-26T19:38:47.46515Z","end":"2026-10-26T19:38:47.689574Z","steps":["trace[1202866938] 'process raft request' (duration: 224.174174ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:47.689667Z","caller":"traceutil/trace.go:171","msg":"trace[891069362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8958; }","duration":"188.251463ms","start":"2026-10-26T19:38:47.5014Z","end":"2026-10-26T19:38:47.689652Z","steps":["trace[891069362] 'agreement among raft nodes before linearized reading' (duration: 188.162762ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:54.824878Z","caller":"traceutil/trace.go:171","msg":"trace[1575786384] transaction","detail":"{read_only:false; response_revision:8998; number_of_response:1; }","duration":"102.557614ms","start":"2026-10-26T19:38:54.722293Z","end":"2026-10-26T19:38:54.824851Z","steps":["trace[1575786384] 'process raft request' (duration: 56.358915ms)","trace[1575786384] 'compare' (duration: 45.934743ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:38:56.642225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.184218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:38:56.642308Z","caller":"traceutil/trace.go:171","msg":"trace[1435595423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9003; }","duration":"141.32185ms","start":"2026-10-26T19:38:56.500966Z","end":"2026-10-26T19:38:56.642288Z","steps":["trace[1435595423] 'range keys from in-memory index tree' (duration: 141.061545ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:56.642403Z","caller":"traceutil/trace.go:171","msg":"trace[1639883070] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"133.285306ms","start":"2026-10-26T19:38:56.509106Z","end":"2026-10-26T19:38:56.642391Z","steps":["trace[1639883070] 'process raft request' (duration: 71.190511ms)","trace[1639883070] 'compare' (duration: 61.874051ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:38:56.709405Z","caller":"traceutil/trace.go:171","msg":"trace[678659589] linearizableReadLoop","detail":"{readStateIndex:9323; appliedIndex:9321; }","duration":"178.457573ms","start":"2026-10-26T19:38:56.530922Z","end":"2026-10-26T19:38:56.70938Z","steps":["trace[678659589] 'read index received' (duration: 49.38789ms)","trace[678659589] 'applied index is now lower than readState.Index' (duration: 129.064993ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:38:56.709615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.679048ms","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-26T19:38:56.709654Z","caller":"traceutil/trace.go:171","msg":"trace[1374755511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9005; }","duration":"178.784481ms","start":"2026-10-26T19:38:56.53086Z","end":"2026-10-26T19:38:56.709645Z","steps":["trace[1374755511] 'agreement among raft nodes before linearized reading' (duration: 178.642068ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:38:56.709591Z","caller":"traceutil/trace.go:171","msg":"trace[1147387457] transaction","detail":"{read_only:false; response_revision:9005; number_of_response:1; }","duration":"196.431381ms","start":"2026-10-26T19:38:56.513015Z","end":"2026-10-26T19:38:56.709446Z","steps":["trace[1147387457] 'process raft request' (duration: 196.224117ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:39:01.898135Z","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-26T19:39:58.807005Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:39:58.814137Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:39:58.814365Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T19:40:27.195333Z","caller":"traceutil/trace.go:171","msg":"trace[1716181708] transaction","detail":"{read_only:false; response_revision:9868; number_of_response:1; }","duration":"205.643007ms","start":"2026-10-26T19:40:26.989648Z","end":"2026-10-26T19:40:27.195291Z","steps":["trace[1716181708] 'process raft request' (duration: 105.851097ms)","trace[1716181708] 'compare' (duration: 99.485164ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:40:27.593652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.366588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:40:27.593834Z","caller":"traceutil/trace.go:171","msg":"trace[1062188683] linearizableReadLoop","detail":"{readStateIndex:10214; appliedIndex:10211; }","duration":"332.10196ms","start":"2026-10-26T19:40:27.261715Z","end":"2026-10-26T19:40:27.593817Z","steps":["trace[1062188683] 'read index received' (duration: 175.498388ms)","trace[1062188683] 'applied index is now lower than readState.Index' (duration: 156.602512ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:40:27.594182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.406837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T19:40:27.594223Z","caller":"traceutil/trace.go:171","msg":"trace[1331972499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9871; }","duration":"265.446948ms","start":"2026-10-26T19:40:27.328763Z","end":"2026-10-26T19:40:27.59421Z","steps":["trace[1331972499] 'agreement among raft nodes before linearized reading' (duration: 265.321715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:40:27.594248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.396757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:40:27.594269Z","caller":"traceutil/trace.go:171","msg":"trace[723964012] transaction","detail":"{read_only:false; response_revision:9870; number_of_response:1; }","duration":"397.789984ms","start":"2026-10-26T19:40:27.196418Z","end":"2026-10-26T19:40:27.594208Z","steps":["trace[723964012] 'process raft request' (duration: 397.322685ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:27.594307Z","caller":"traceutil/trace.go:171","msg":"trace[1084321739] transaction","detail":"{read_only:false; response_revision:9871; number_of_response:1; }","duration":"397.504789ms","start":"2026-10-26T19:40:27.196741Z","end":"2026-10-26T19:40:27.594246Z","steps":["trace[1084321739] 'process raft request' (duration: 397.0437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:40:27.594401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.736326ms","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-26T19:40:27.594472Z","caller":"traceutil/trace.go:171","msg":"trace[696602401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9871; }","duration":"260.829639ms","start":"2026-10-26T19:40:27.333625Z","end":"2026-10-26T19:40:27.594454Z","steps":["trace[696602401] 'agreement among raft nodes before linearized reading' (duration: 260.612274ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:27.59433Z","caller":"traceutil/trace.go:171","msg":"trace[1802173874] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:9871; }","duration":"172.495299ms","start":"2026-10-26T19:40:27.421816Z","end":"2026-10-26T19:40:27.594311Z","steps":["trace[1802173874] 'agreement among raft nodes before linearized reading' (duration: 172.352746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:40:27.594493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:40:27.196392Z","time spent":"397.992167ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:40:27.594502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:40:27.196732Z","time spent":"397.672052ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:40:27.594316Z","caller":"traceutil/trace.go:171","msg":"trace[2060023199] transaction","detail":"{read_only:false; response_revision:9869; number_of_response:1; }","duration":"513.600272ms","start":"2026-10-26T19:40:27.080678Z","end":"2026-10-26T19:40:27.594278Z","steps":["trace[2060023199] 'process raft request' (duration: 356.52268ms)","trace[2060023199] 'compare' (duration: 156.248276ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:40:27.594708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:40:27.080653Z","time spent":"514.01226ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:40:27.595536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.817442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T19:40:27.595604Z","caller":"traceutil/trace.go:171","msg":"trace[1334927567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9871; }","duration":"333.924825ms","start":"2026-10-26T19:40:27.261661Z","end":"2026-10-26T19:40:27.595585Z","steps":["trace[1334927567] 'agreement among raft nodes before linearized reading' (duration: 332.383246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:40:27.595647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:40:27.26164Z","time spent":"333.996827ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T19:40:27.762198Z","caller":"traceutil/trace.go:171","msg":"trace[1348847382] transaction","detail":"{read_only:false; response_revision:9873; number_of_response:1; }","duration":"161.542168ms","start":"2026-10-26T19:40:27.600632Z","end":"2026-10-26T19:40:27.762174Z","steps":["trace[1348847382] 'process raft request' (duration: 161.345234ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:27.762458Z","caller":"traceutil/trace.go:171","msg":"trace[1603189217] transaction","detail":"{read_only:false; response_revision:9874; number_of_response:1; }","duration":"161.340334ms","start":"2026-10-26T19:40:27.60107Z","end":"2026-10-26T19:40:27.76241Z","steps":["trace[1603189217] 'process raft request' (duration: 161.062559ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:39.79008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7996} {"level":"info","ts":"2026-10-26T19:41:39.874987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7996,"took":"82.938756ms","hash":414042429,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":24788992,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-26T19:41:39.875137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":414042429,"revision":7996,"compact-revision":5627} {"level":"warn","ts":"2026-10-26T19:46:07.177133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.577674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:07.177255Z","caller":"traceutil/trace.go:171","msg":"trace[76936302] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12307; }","duration":"255.735617ms","start":"2026-10-26T19:46:06.921497Z","end":"2026-10-26T19:46:07.177232Z","steps":["trace[76936302] 'range keys from in-memory index tree' (duration: 255.513683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:07.17725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.529383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:46:07.177262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.231437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:46:07.177287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.001565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-26T19:46:07.177349Z","caller":"traceutil/trace.go:171","msg":"trace[2057039700] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12307; }","duration":"256.382429ms","start":"2026-10-26T19:46:06.920946Z","end":"2026-10-26T19:46:07.177329Z","steps":["trace[2057039700] 'range keys from in-memory index tree' (duration: 256.139195ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:07.17738Z","caller":"traceutil/trace.go:171","msg":"trace[1208599880] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12307; }","duration":"253.165498ms","start":"2026-10-26T19:46:06.924191Z","end":"2026-10-26T19:46:07.177357Z","steps":["trace[1208599880] 'range keys from in-memory index tree' (duration: 252.78421ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:07.177325Z","caller":"traceutil/trace.go:171","msg":"trace[1168553612] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12307; }","duration":"255.629635ms","start":"2026-10-26T19:46:06.921677Z","end":"2026-10-26T19:46:07.177307Z","steps":["trace[1168553612] 'range keys from in-memory index tree' (duration: 255.484112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:07.17801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.096634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:07.178261Z","caller":"traceutil/trace.go:171","msg":"trace[69400291] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12307; }","duration":"255.460182ms","start":"2026-10-26T19:46:06.922756Z","end":"2026-10-26T19:46:07.178217Z","steps":["trace[69400291] 'range keys from in-memory index tree' (duration: 254.990693ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:07.19108Z","caller":"traceutil/trace.go:171","msg":"trace[915208296] transaction","detail":"{read_only:false; response_revision:12308; number_of_response:1; }","duration":"260.62253ms","start":"2026-10-26T19:46:06.930443Z","end":"2026-10-26T19:46:07.191065Z","steps":["trace[915208296] 'process raft request' (duration: 260.484808ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:07.196162Z","caller":"traceutil/trace.go:171","msg":"trace[901993608] transaction","detail":"{read_only:false; response_revision:12309; number_of_response:1; }","duration":"260.5861ms","start":"2026-10-26T19:46:06.935555Z","end":"2026-10-26T19:46:07.196141Z","steps":["trace[901993608] 'process raft request' (duration: 260.438907ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:39.797325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10361} {"level":"info","ts":"2026-10-26T19:46:39.899538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10361,"took":"99.88297ms","hash":2003791777,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":25534464,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:46:39.89961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2003791777,"revision":10361,"compact-revision":7996} {"level":"info","ts":"2026-10-26T19:51:39.806555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12535} {"level":"info","ts":"2026-10-26T19:51:39.89085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12535,"took":"82.063847ms","hash":3068313833,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":27717632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T19:51:39.890917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3068313833,"revision":12535,"compact-revision":10361} {"level":"info","ts":"2026-10-26T19:52:26.878726Z","caller":"traceutil/trace.go:171","msg":"trace[2052268581] linearizableReadLoop","detail":"{readStateIndex:15371; appliedIndex:15370; }","duration":"273.968143ms","start":"2026-10-26T19:52:26.604727Z","end":"2026-10-26T19:52:26.878695Z","steps":["trace[2052268581] 'read index received' (duration: 187.42442ms)","trace[2052268581] 'applied index is now lower than readState.Index' (duration: 86.542673ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:52:26.878868Z","caller":"traceutil/trace.go:171","msg":"trace[1631148354] transaction","detail":"{read_only:false; response_revision:14859; number_of_response:1; }","duration":"277.528731ms","start":"2026-10-26T19:52:26.601327Z","end":"2026-10-26T19:52:26.878855Z","steps":["trace[1631148354] 'process raft request' (duration: 190.864056ms)","trace[1631148354] 'compare' (duration: 86.389839ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:52:26.879118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.968223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-26T19:52:26.879219Z","caller":"traceutil/trace.go:171","msg":"trace[388669350] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14859; }","duration":"197.122276ms","start":"2026-10-26T19:52:26.682073Z","end":"2026-10-26T19:52:26.879195Z","steps":["trace[388669350] 'agreement among raft nodes before linearized reading' (duration: 196.862121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:26.882306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.568772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:585057"} {"level":"info","ts":"2026-10-26T19:52:26.882394Z","caller":"traceutil/trace.go:171","msg":"trace[1844800172] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:14859; }","duration":"277.705514ms","start":"2026-10-26T19:52:26.60466Z","end":"2026-10-26T19:52:26.882365Z","steps":["trace[1844800172] 'agreement among raft nodes before linearized reading' (duration: 274.593105ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:35.646616Z","caller":"traceutil/trace.go:171","msg":"trace[350306565] transaction","detail":"{read_only:false; response_revision:14903; number_of_response:1; }","duration":"105.986716ms","start":"2026-10-26T19:52:35.540581Z","end":"2026-10-26T19:52:35.646567Z","steps":["trace[350306565] 'process raft request' (duration: 105.637909ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:45.080179Z","caller":"traceutil/trace.go:171","msg":"trace[1662727449] linearizableReadLoop","detail":"{readStateIndex:15486; appliedIndex:15485; }","duration":"259.25741ms","start":"2026-10-26T19:52:44.820895Z","end":"2026-10-26T19:52:45.080153Z","steps":["trace[1662727449] 'read index received' (duration: 259.190658ms)","trace[1662727449] 'applied index is now lower than readState.Index' (duration: 65.352µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:52:45.0803Z","caller":"traceutil/trace.go:171","msg":"trace[1375834506] transaction","detail":"{read_only:false; response_revision:14969; number_of_response:1; }","duration":"405.430657ms","start":"2026-10-26T19:52:44.674838Z","end":"2026-10-26T19:52:45.080269Z","steps":["trace[1375834506] 'process raft request' (duration: 405.154981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:45.080457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:44.674808Z","time spent":"405.56317ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:52:45.080532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.620466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-26T19:52:45.080635Z","caller":"traceutil/trace.go:171","msg":"trace[360594225] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14969; }","duration":"259.77522ms","start":"2026-10-26T19:52:44.820842Z","end":"2026-10-26T19:52:45.080617Z","steps":["trace[360594225] 'agreement among raft nodes before linearized reading' (duration: 259.520235ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:45.114799Z","caller":"traceutil/trace.go:171","msg":"trace[1265829106] transaction","detail":"{read_only:false; response_revision:14970; number_of_response:1; }","duration":"168.69801ms","start":"2026-10-26T19:52:44.946078Z","end":"2026-10-26T19:52:45.114776Z","steps":["trace[1265829106] 'process raft request' (duration: 168.386864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:45.115016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.398511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:52:45.115092Z","caller":"traceutil/trace.go:171","msg":"trace[1059212072] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14970; }","duration":"249.561224ms","start":"2026-10-26T19:52:44.865512Z","end":"2026-10-26T19:52:45.115074Z","steps":["trace[1059212072] 'agreement among raft nodes before linearized reading' (duration: 249.396971ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:55.48459Z","caller":"traceutil/trace.go:171","msg":"trace[1765142778] transaction","detail":"{read_only:false; response_revision:15139; number_of_response:1; }","duration":"374.619485ms","start":"2026-10-26T19:52:55.109945Z","end":"2026-10-26T19:52:55.484565Z","steps":["trace[1765142778] 'process raft request' (duration: 374.445482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:55.484746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.109925Z","time spent":"374.741908ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:52:55.741749Z","caller":"traceutil/trace.go:171","msg":"trace[958248040] linearizableReadLoop","detail":"{readStateIndex:15660; appliedIndex:15658; }","duration":"566.654734ms","start":"2026-10-26T19:52:55.175074Z","end":"2026-10-26T19:52:55.741728Z","steps":["trace[958248040] 'read index received' (duration: 309.405593ms)","trace[958248040] 'applied index is now lower than readState.Index' (duration: 257.248181ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:52:55.742039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.9396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-26T19:52:55.742125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.583111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:52:55.742148Z","caller":"traceutil/trace.go:171","msg":"trace[2025138545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15140; }","duration":"567.073703ms","start":"2026-10-26T19:52:55.175044Z","end":"2026-10-26T19:52:55.742117Z","steps":["trace[2025138545] 'agreement among raft nodes before linearized reading' (duration: 566.843759ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:55.742184Z","caller":"traceutil/trace.go:171","msg":"trace[1206263048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15140; }","duration":"240.710964ms","start":"2026-10-26T19:52:55.501456Z","end":"2026-10-26T19:52:55.742167Z","steps":["trace[1206263048] 'agreement among raft nodes before linearized reading' (duration: 240.52294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:55.742204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.175029Z","time spent":"567.161774ms","remote":"127.0.0.1:54754","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-26T19:52:55.742421Z","caller":"traceutil/trace.go:171","msg":"trace[815743232] transaction","detail":"{read_only:false; response_revision:15140; number_of_response:1; }","duration":"581.718295ms","start":"2026-10-26T19:52:55.16068Z","end":"2026-10-26T19:52:55.742399Z","steps":["trace[815743232] 'process raft request' (duration: 547.885625ms)","trace[815743232] 'compare' (duration: 32.965153ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:52:55.742433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.655372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-26T19:52:55.742495Z","caller":"traceutil/trace.go:171","msg":"trace[2081272766] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15140; }","duration":"405.765384ms","start":"2026-10-26T19:52:55.336712Z","end":"2026-10-26T19:52:55.742478Z","steps":["trace[2081272766] 'agreement among raft nodes before linearized reading' (duration: 405.627291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:55.742571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.336688Z","time spent":"405.841945ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12453,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-26T19:52:55.742569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.160649Z","time spent":"581.821127ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:52:55.742588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.175913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b12b01f-bc18-459e-b91e-dcab2ccfc069\" ","response":"range_response_count:1 size:3607"} {"level":"warn","ts":"2026-10-26T19:52:55.742607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.669595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-4gsw7.18e22c5ba6277f40\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-10-26T19:52:55.742626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.885483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h9xpp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:52:55.742643Z","caller":"traceutil/trace.go:171","msg":"trace[1610994955] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b12b01f-bc18-459e-b91e-dcab2ccfc069; range_end:; response_count:1; response_revision:15140; }","duration":"392.228824ms","start":"2026-10-26T19:52:55.350395Z","end":"2026-10-26T19:52:55.742624Z","steps":["trace[1610994955] 'agreement among raft nodes before linearized reading' (duration: 392.03835ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:55.74267Z","caller":"traceutil/trace.go:171","msg":"trace[366005036] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-4gsw7.18e22c5ba6277f40; range_end:; response_count:1; response_revision:15140; }","duration":"427.774958ms","start":"2026-10-26T19:52:55.314879Z","end":"2026-10-26T19:52:55.742654Z","steps":["trace[366005036] 'agreement among raft nodes before linearized reading' (duration: 427.652235ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:55.742676Z","caller":"traceutil/trace.go:171","msg":"trace[730802723] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h9xpp; range_end:; response_count:1; response_revision:15140; }","duration":"523.965615ms","start":"2026-10-26T19:52:55.218697Z","end":"2026-10-26T19:52:55.742662Z","steps":["trace[730802723] 'agreement among raft nodes before linearized reading' (duration: 523.262531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:55.742694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.31486Z","time spent":"427.829929ms","remote":"127.0.0.1:55686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":807,"request content":"key:\"/registry/events/openstack/ovn-controller-default-4gsw7.18e22c5ba6277f40\" "} {"level":"warn","ts":"2026-10-26T19:52:55.742691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.350371Z","time spent":"392.306206ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3631,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b12b01f-bc18-459e-b91e-dcab2ccfc069\" "} {"level":"warn","ts":"2026-10-26T19:52:55.742706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.218674Z","time spent":"524.024665ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-h9xpp\" "} {"level":"warn","ts":"2026-10-26T19:52:55.742751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.982283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:52:55.742888Z","caller":"traceutil/trace.go:171","msg":"trace[1031110196] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:15140; }","duration":"488.135906ms","start":"2026-10-26T19:52:55.254697Z","end":"2026-10-26T19:52:55.742833Z","steps":["trace[1031110196] 'agreement among raft nodes before linearized reading' (duration: 487.891252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:52:55.742959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:52:55.254687Z","time spent":"488.254108ms","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:53:00.336099Z","caller":"traceutil/trace.go:171","msg":"trace[194655893] transaction","detail":"{read_only:false; response_revision:15224; number_of_response:1; }","duration":"112.803207ms","start":"2026-10-26T19:53:00.223273Z","end":"2026-10-26T19:53:00.336077Z","steps":["trace[194655893] 'process raft request' (duration: 81.922234ms)","trace[194655893] 'compare' (duration: 30.645008ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:26.786717Z","caller":"traceutil/trace.go:171","msg":"trace[2146435067] transaction","detail":"{read_only:false; response_revision:16185; number_of_response:1; }","duration":"186.125264ms","start":"2026-10-26T19:54:26.60057Z","end":"2026-10-26T19:54:26.786695Z","steps":["trace[2146435067] 'process raft request' (duration: 185.979142ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:26.825509Z","caller":"traceutil/trace.go:171","msg":"trace[1847904408] transaction","detail":"{read_only:false; response_revision:16186; number_of_response:1; }","duration":"104.687565ms","start":"2026-10-26T19:54:26.720768Z","end":"2026-10-26T19:54:26.825456Z","steps":["trace[1847904408] 'process raft request' (duration: 104.595773ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:27.934902Z","caller":"traceutil/trace.go:171","msg":"trace[793068634] transaction","detail":"{read_only:false; response_revision:16189; number_of_response:1; }","duration":"343.943766ms","start":"2026-10-26T19:54:27.59092Z","end":"2026-10-26T19:54:27.934864Z","steps":["trace[793068634] 'process raft request' (duration: 343.682321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:27.935075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:27.590898Z","time spent":"344.079258ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7824,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:54:28.220453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.759989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:54:28.220582Z","caller":"traceutil/trace.go:171","msg":"trace[309820296] linearizableReadLoop","detail":"{readStateIndex:16741; appliedIndex:16740; }","duration":"282.225994ms","start":"2026-10-26T19:54:27.938339Z","end":"2026-10-26T19:54:28.220565Z","steps":["trace[309820296] 'read index received' (duration: 40.22376ms)","trace[309820296] 'applied index is now lower than readState.Index' (duration: 242.000914ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:28.220642Z","caller":"traceutil/trace.go:171","msg":"trace[595464724] transaction","detail":"{read_only:false; response_revision:16190; number_of_response:1; }","duration":"372.295939ms","start":"2026-10-26T19:54:27.848316Z","end":"2026-10-26T19:54:28.220612Z","steps":["trace[595464724] 'process raft request' (duration: 130.285695ms)","trace[595464724] 'compare' (duration: 241.641596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:54:28.22079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.439128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-26T19:54:28.22106Z","caller":"traceutil/trace.go:171","msg":"trace[288168756] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16190; }","duration":"282.749254ms","start":"2026-10-26T19:54:27.938299Z","end":"2026-10-26T19:54:28.221048Z","steps":["trace[288168756] 'agreement among raft nodes before linearized reading' (duration: 282.330356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:28.220815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:27.848291Z","time spent":"372.442472ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:54:28.254019Z","caller":"traceutil/trace.go:171","msg":"trace[2035274206] transaction","detail":"{read_only:false; response_revision:16191; number_of_response:1; }","duration":"169.560907ms","start":"2026-10-26T19:54:28.084427Z","end":"2026-10-26T19:54:28.253988Z","steps":["trace[2035274206] 'process raft request' (duration: 169.361313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:28.254085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.119568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:54:28.254145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.080912ms","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-26T19:54:28.254216Z","caller":"traceutil/trace.go:171","msg":"trace[913018864] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16191; }","duration":"176.192184ms","start":"2026-10-26T19:54:28.078009Z","end":"2026-10-26T19:54:28.254201Z","steps":["trace[913018864] 'agreement among raft nodes before linearized reading' (duration: 175.99213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:28.254255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.307112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19040"} {"level":"info","ts":"2026-10-26T19:54:28.254321Z","caller":"traceutil/trace.go:171","msg":"trace[117661264] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:16191; }","duration":"310.416754ms","start":"2026-10-26T19:54:27.943886Z","end":"2026-10-26T19:54:28.254303Z","steps":["trace[117661264] 'agreement among raft nodes before linearized reading' (duration: 310.257671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:28.254371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:27.943864Z","time spent":"310.494165ms","remote":"127.0.0.1:55800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":19064,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-10-26T19:54:28.254425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.686879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-10-26T19:54:28.254468Z","caller":"traceutil/trace.go:171","msg":"trace[579484992] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16191; }","duration":"193.74115ms","start":"2026-10-26T19:54:28.060716Z","end":"2026-10-26T19:54:28.254457Z","steps":["trace[579484992] 'agreement among raft nodes before linearized reading' (duration: 193.623138ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:28.254161Z","caller":"traceutil/trace.go:171","msg":"trace[1536093506] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16191; }","duration":"193.274991ms","start":"2026-10-26T19:54:28.060867Z","end":"2026-10-26T19:54:28.254142Z","steps":["trace[1536093506] 'agreement among raft nodes before linearized reading' (duration: 193.091917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:28.254627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.28505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:54:28.254672Z","caller":"traceutil/trace.go:171","msg":"trace[1886101676] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16191; }","duration":"194.381922ms","start":"2026-10-26T19:54:28.060276Z","end":"2026-10-26T19:54:28.254658Z","steps":["trace[1886101676] 'agreement among raft nodes before linearized reading' (duration: 194.31078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:28.254711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.322951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-26T19:54:28.254778Z","caller":"traceutil/trace.go:171","msg":"trace[387162653] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16191; }","duration":"193.413284ms","start":"2026-10-26T19:54:28.061346Z","end":"2026-10-26T19:54:28.254759Z","steps":["trace[387162653] 'agreement among raft nodes before linearized reading' (duration: 192.592348ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:56:39.815024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14557} {"level":"info","ts":"2026-10-26T19:56:39.902475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14557,"took":"84.906265ms","hash":341105175,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":32055296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:56:39.902548Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":341105175,"revision":14557,"compact-revision":12535} {"level":"warn","ts":"2026-10-26T20:00:21.722107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.194308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-10-26T20:00:21.722215Z","caller":"traceutil/trace.go:171","msg":"trace[292858143] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:18873; }","duration":"101.359841ms","start":"2026-10-26T20:00:21.620833Z","end":"2026-10-26T20:00:21.722193Z","steps":["trace[292858143] 'range keys from in-memory index tree' (duration: 100.104206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:22.022304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.111606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7528"} {"level":"info","ts":"2026-10-26T20:00:22.022412Z","caller":"traceutil/trace.go:171","msg":"trace[1878925986] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:18874; }","duration":"186.244538ms","start":"2026-10-26T20:00:21.836145Z","end":"2026-10-26T20:00:22.02239Z","steps":["trace[1878925986] 'range keys from in-memory index tree' (duration: 185.718798ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:24.190815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.165052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89ce9a23-33cb-4ef9-8fcd-097121a9e99b\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-10-26T20:00:24.190931Z","caller":"traceutil/trace.go:171","msg":"trace[478327295] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89ce9a23-33cb-4ef9-8fcd-097121a9e99b; range_end:; response_count:1; response_revision:18894; }","duration":"158.333146ms","start":"2026-10-26T20:00:24.032568Z","end":"2026-10-26T20:00:24.190901Z","steps":["trace[478327295] 'range keys from in-memory index tree' (duration: 157.939238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:24.191194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.565028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:00:24.191273Z","caller":"traceutil/trace.go:171","msg":"trace[786230766] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:18894; }","duration":"108.693691ms","start":"2026-10-26T20:00:24.082562Z","end":"2026-10-26T20:00:24.191256Z","steps":["trace[786230766] 'count revisions from in-memory index tree' (duration: 108.501276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:24.191363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.150146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:00:24.19143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.186277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-dkp7g\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-10-26T20:00:24.191455Z","caller":"traceutil/trace.go:171","msg":"trace[798681554] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:18894; }","duration":"155.277648ms","start":"2026-10-26T20:00:24.036157Z","end":"2026-10-26T20:00:24.191435Z","steps":["trace[798681554] 'count revisions from in-memory index tree' (duration: 155.011033ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:24.191472Z","caller":"traceutil/trace.go:171","msg":"trace[769319416] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-dkp7g; range_end:; response_count:1; response_revision:18894; }","duration":"113.259848ms","start":"2026-10-26T20:00:24.0782Z","end":"2026-10-26T20:00:24.191459Z","steps":["trace[769319416] 'range keys from in-memory index tree' (duration: 113.050484ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:24.363179Z","caller":"traceutil/trace.go:171","msg":"trace[1558843656] linearizableReadLoop","detail":"{readStateIndex:19534; appliedIndex:19533; }","duration":"166.372578ms","start":"2026-10-26T20:00:24.196729Z","end":"2026-10-26T20:00:24.363102Z","steps":["trace[1558843656] 'read index received' (duration: 114.928779ms)","trace[1558843656] 'applied index is now lower than readState.Index' (duration: 51.442899ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:00:24.363675Z","caller":"traceutil/trace.go:171","msg":"trace[510662149] transaction","detail":"{read_only:false; response_revision:18896; number_of_response:1; }","duration":"174.030046ms","start":"2026-10-26T20:00:24.18955Z","end":"2026-10-26T20:00:24.36358Z","steps":["trace[510662149] 'process raft request' (duration: 122.044576ms)","trace[510662149] 'compare' (duration: 50.991391ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:00:24.364143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.381018ms","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-26T20:00:24.36433Z","caller":"traceutil/trace.go:171","msg":"trace[1324674832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18896; }","duration":"167.589633ms","start":"2026-10-26T20:00:24.196702Z","end":"2026-10-26T20:00:24.364291Z","steps":["trace[1324674832] 'agreement among raft nodes before linearized reading' (duration: 167.264886ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:24.425174Z","caller":"traceutil/trace.go:171","msg":"trace[2040234263] transaction","detail":"{read_only:false; response_revision:18897; number_of_response:1; }","duration":"202.880314ms","start":"2026-10-26T20:00:24.222256Z","end":"2026-10-26T20:00:24.425137Z","steps":["trace[2040234263] 'process raft request' (duration: 202.410195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:24.425708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.654354ms","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-26T20:00:24.42581Z","caller":"traceutil/trace.go:171","msg":"trace[784235260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18897; }","duration":"208.812147ms","start":"2026-10-26T20:00:24.216974Z","end":"2026-10-26T20:00:24.425786Z","steps":["trace[784235260] 'agreement among raft nodes before linearized reading' (duration: 208.521481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:24.426026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.047327ms","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":"warn","ts":"2026-10-26T20:00:24.425728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.821807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T20:00:24.42611Z","caller":"traceutil/trace.go:171","msg":"trace[1786615977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18897; }","duration":"220.252695ms","start":"2026-10-26T20:00:24.205845Z","end":"2026-10-26T20:00:24.426098Z","steps":["trace[1786615977] 'agreement among raft nodes before linearized reading' (duration: 219.735995ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:24.426091Z","caller":"traceutil/trace.go:171","msg":"trace[1862486198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18897; }","duration":"203.13751ms","start":"2026-10-26T20:00:24.222934Z","end":"2026-10-26T20:00:24.426071Z","steps":["trace[1862486198] 'agreement among raft nodes before linearized reading' (duration: 202.842634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:24.426861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.214101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:98 size:970278"} {"level":"info","ts":"2026-10-26T20:00:24.426993Z","caller":"traceutil/trace.go:171","msg":"trace[25640030] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:98; response_revision:18897; }","duration":"224.392255ms","start":"2026-10-26T20:00:24.202582Z","end":"2026-10-26T20:00:24.426975Z","steps":["trace[25640030] 'agreement among raft nodes before linearized reading' (duration: 222.862945ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:58.230287Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:00:58.238077Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T20:00:58.238335Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T20:01:16.938942Z","caller":"traceutil/trace.go:171","msg":"trace[748774672] transaction","detail":"{read_only:false; response_revision:19512; number_of_response:1; }","duration":"151.036597ms","start":"2026-10-26T20:01:16.787873Z","end":"2026-10-26T20:01:16.93891Z","steps":["trace[748774672] 'process raft request' (duration: 128.438796ms)","trace[748774672] 'compare' (duration: 22.430687ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:01:16.939108Z","caller":"traceutil/trace.go:171","msg":"trace[1006869308] linearizableReadLoop","detail":"{readStateIndex:20168; appliedIndex:20166; }","duration":"150.542777ms","start":"2026-10-26T20:01:16.788538Z","end":"2026-10-26T20:01:16.939081Z","steps":["trace[1006869308] 'read index received' (duration: 127.788463ms)","trace[1006869308] 'applied index is now lower than readState.Index' (duration: 22.753104ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:01:16.93931Z","caller":"traceutil/trace.go:171","msg":"trace[1631221917] transaction","detail":"{read_only:false; response_revision:19513; number_of_response:1; }","duration":"150.806232ms","start":"2026-10-26T20:01:16.788489Z","end":"2026-10-26T20:01:16.939296Z","steps":["trace[1631221917] 'process raft request' (duration: 150.382154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:01:16.939712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.132069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:01:16.939803Z","caller":"traceutil/trace.go:171","msg":"trace[1432738939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19513; }","duration":"151.255911ms","start":"2026-10-26T20:01:16.788524Z","end":"2026-10-26T20:01:16.93978Z","steps":["trace[1432738939] 'agreement among raft nodes before linearized reading' (duration: 150.972366ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:01:39.82668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17081} {"level":"info","ts":"2026-10-26T20:01:39.929306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17081,"took":"99.587946ms","hash":1786136777,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":34471936,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T20:01:39.929401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1786136777,"revision":17081,"compact-revision":14557} {"level":"warn","ts":"2026-10-26T20:05:53.446598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.078887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:05:53.446759Z","caller":"traceutil/trace.go:171","msg":"trace[1672131319] transaction","detail":"{read_only:false; response_revision:21490; number_of_response:1; }","duration":"210.565429ms","start":"2026-10-26T20:05:53.23616Z","end":"2026-10-26T20:05:53.446726Z","steps":["trace[1672131319] 'process raft request' (duration: 29.231567ms)","trace[1672131319] 'compare' (duration: 180.823612ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:05:53.67327Z","caller":"traceutil/trace.go:171","msg":"trace[1862706074] transaction","detail":"{read_only:false; response_revision:21491; number_of_response:1; }","duration":"137.734552ms","start":"2026-10-26T20:05:53.535488Z","end":"2026-10-26T20:05:53.673222Z","steps":["trace[1862706074] 'process raft request' (duration: 98.556946ms)","trace[1862706074] 'compare' (duration: 38.730827ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:05:53.913519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.302208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:05:53.913703Z","caller":"traceutil/trace.go:171","msg":"trace[1454328591] transaction","detail":"{read_only:false; response_revision:21492; number_of_response:1; }","duration":"291.58414ms","start":"2026-10-26T20:05:53.622063Z","end":"2026-10-26T20:05:53.913647Z","steps":["trace[1454328591] 'process raft request' (duration: 170.059847ms)","trace[1454328591] 'compare' (duration: 121.034094ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:05:53.913835Z","caller":"traceutil/trace.go:171","msg":"trace[270984426] linearizableReadLoop","detail":"{readStateIndex:22211; appliedIndex:22210; }","duration":"212.674009ms","start":"2026-10-26T20:05:53.701131Z","end":"2026-10-26T20:05:53.913805Z","steps":["trace[270984426] 'read index received' (duration: 90.965703ms)","trace[270984426] 'applied index is now lower than readState.Index' (duration: 121.705476ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:05:53.913902Z","caller":"traceutil/trace.go:171","msg":"trace[1539990287] transaction","detail":"{read_only:false; response_revision:21493; number_of_response:1; }","duration":"207.47383ms","start":"2026-10-26T20:05:53.706243Z","end":"2026-10-26T20:05:53.913717Z","steps":["trace[1539990287] 'process raft request' (duration: 207.400779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:05:53.914049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.693712ms","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-26T20:05:53.914111Z","caller":"traceutil/trace.go:171","msg":"trace[246975135] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:21493; }","duration":"135.855726ms","start":"2026-10-26T20:05:53.778233Z","end":"2026-10-26T20:05:53.914089Z","steps":["trace[246975135] 'agreement among raft nodes before linearized reading' (duration: 135.725974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:05:53.914091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.910973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:05:53.914205Z","caller":"traceutil/trace.go:171","msg":"trace[1381488722] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:21493; }","duration":"213.106697ms","start":"2026-10-26T20:05:53.701066Z","end":"2026-10-26T20:05:53.914173Z","steps":["trace[1381488722] 'agreement among raft nodes before linearized reading' (duration: 212.823411ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:09.823157Z","caller":"traceutil/trace.go:171","msg":"trace[410546178] transaction","detail":"{read_only:false; response_revision:21573; number_of_response:1; }","duration":"317.076096ms","start":"2026-10-26T20:06:09.506051Z","end":"2026-10-26T20:06:09.823127Z","steps":["trace[410546178] 'process raft request' (duration: 316.836712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:09.82335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:09.506032Z","time spent":"317.204768ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:06:10.150371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.207924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:06:10.151019Z","caller":"traceutil/trace.go:171","msg":"trace[2042693354] transaction","detail":"{read_only:false; response_revision:21575; number_of_response:1; }","duration":"183.613395ms","start":"2026-10-26T20:06:09.967375Z","end":"2026-10-26T20:06:10.150989Z","steps":["trace[2042693354] 'process raft request' (duration: 183.167608ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:10.151057Z","caller":"traceutil/trace.go:171","msg":"trace[162049882] transaction","detail":"{read_only:false; response_revision:21574; number_of_response:1; }","duration":"424.760336ms","start":"2026-10-26T20:06:09.726261Z","end":"2026-10-26T20:06:10.151021Z","steps":["trace[162049882] 'process raft request' (duration: 236.724657ms)","trace[162049882] 'compare' (duration: 186.933799ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:06:10.151299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:09.726224Z","time spent":"424.94875ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:06:10.687113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.924751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:06:10.687246Z","caller":"traceutil/trace.go:171","msg":"trace[1221856678] linearizableReadLoop","detail":"{readStateIndex:22300; appliedIndex:22299; }","duration":"199.145761ms","start":"2026-10-26T20:06:10.488079Z","end":"2026-10-26T20:06:10.687224Z","steps":["trace[1221856678] 'read index received' (duration: 24.936074ms)","trace[1221856678] 'applied index is now lower than readState.Index' (duration: 174.208217ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:06:10.687365Z","caller":"traceutil/trace.go:171","msg":"trace[1949763034] transaction","detail":"{read_only:false; response_revision:21577; number_of_response:1; }","duration":"308.806619ms","start":"2026-10-26T20:06:10.378512Z","end":"2026-10-26T20:06:10.687319Z","steps":["trace[1949763034] 'process raft request' (duration: 134.569112ms)","trace[1949763034] 'compare' (duration: 173.763578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:06:10.687504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.431097ms","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-26T20:06:10.687536Z","caller":"traceutil/trace.go:171","msg":"trace[1639991275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21577; }","duration":"199.504418ms","start":"2026-10-26T20:06:10.488021Z","end":"2026-10-26T20:06:10.687525Z","steps":["trace[1639991275] 'agreement among raft nodes before linearized reading' (duration: 199.352515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:10.687603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:10.378458Z","time spent":"309.039754ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:06:10.687615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.041332ms","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-26T20:06:10.687729Z","caller":"traceutil/trace.go:171","msg":"trace[1193615562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21577; }","duration":"196.258976ms","start":"2026-10-26T20:06:10.491433Z","end":"2026-10-26T20:06:10.687692Z","steps":["trace[1193615562] 'agreement among raft nodes before linearized reading' (duration: 196.050952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:10.687871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.395498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:06:10.687897Z","caller":"traceutil/trace.go:171","msg":"trace[1365563203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21577; }","duration":"187.439278ms","start":"2026-10-26T20:06:10.50045Z","end":"2026-10-26T20:06:10.687889Z","steps":["trace[1365563203] 'agreement among raft nodes before linearized reading' (duration: 187.394707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:10.687897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.538101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T20:06:10.687953Z","caller":"traceutil/trace.go:171","msg":"trace[1482951643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21577; }","duration":"155.607843ms","start":"2026-10-26T20:06:10.53232Z","end":"2026-10-26T20:06:10.687928Z","steps":["trace[1482951643] 'agreement among raft nodes before linearized reading' (duration: 155.481661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:10.688155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.400919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:06:10.68834Z","caller":"traceutil/trace.go:171","msg":"trace[1737156575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21577; }","duration":"154.615312ms","start":"2026-10-26T20:06:10.533693Z","end":"2026-10-26T20:06:10.688308Z","steps":["trace[1737156575] 'agreement among raft nodes before linearized reading' (duration: 154.155964ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:10.913199Z","caller":"traceutil/trace.go:171","msg":"trace[1113528229] transaction","detail":"{read_only:false; response_revision:21579; number_of_response:1; }","duration":"220.774272ms","start":"2026-10-26T20:06:10.692393Z","end":"2026-10-26T20:06:10.913168Z","steps":["trace[1113528229] 'process raft request' (duration: 220.472407ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:10.913242Z","caller":"traceutil/trace.go:171","msg":"trace[211370676] transaction","detail":"{read_only:false; response_revision:21580; number_of_response:1; }","duration":"220.756882ms","start":"2026-10-26T20:06:10.692444Z","end":"2026-10-26T20:06:10.913201Z","steps":["trace[211370676] 'process raft request' (duration: 220.612569ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:10.913309Z","caller":"traceutil/trace.go:171","msg":"trace[164901913] linearizableReadLoop","detail":"{readStateIndex:22304; appliedIndex:22301; }","duration":"119.802971ms","start":"2026-10-26T20:06:10.793336Z","end":"2026-10-26T20:06:10.913139Z","steps":["trace[164901913] 'read index received' (duration: 119.570196ms)","trace[164901913] 'applied index is now lower than readState.Index' (duration: 231.675µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:06:10.913315Z","caller":"traceutil/trace.go:171","msg":"trace[1578452983] transaction","detail":"{read_only:false; response_revision:21581; number_of_response:1; }","duration":"220.6193ms","start":"2026-10-26T20:06:10.692635Z","end":"2026-10-26T20:06:10.913255Z","steps":["trace[1578452983] 'process raft request' (duration: 220.460837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:10.913478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.116417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:06:10.913526Z","caller":"traceutil/trace.go:171","msg":"trace[561681968] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21581; }","duration":"120.196748ms","start":"2026-10-26T20:06:10.793317Z","end":"2026-10-26T20:06:10.913513Z","steps":["trace[561681968] 'agreement among raft nodes before linearized reading' (duration: 120.045235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:11.254143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.906812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-10-26T20:06:11.254562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.159526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-10-26T20:06:11.254615Z","caller":"traceutil/trace.go:171","msg":"trace[783220704] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:21582; }","duration":"100.250018ms","start":"2026-10-26T20:06:11.154344Z","end":"2026-10-26T20:06:11.254594Z","steps":["trace[783220704] 'agreement among raft nodes before linearized reading' (duration: 99.899021ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:11.254622Z","caller":"traceutil/trace.go:171","msg":"trace[1884730276] transaction","detail":"{read_only:false; response_revision:21582; number_of_response:1; }","duration":"408.614399ms","start":"2026-10-26T20:06:10.845868Z","end":"2026-10-26T20:06:11.254482Z","steps":["trace[1884730276] 'process raft request' (duration: 257.271218ms)","trace[1884730276] 'compare' (duration: 150.630677ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:06:11.254816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:10.845849Z","time spent":"408.863214ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:06:39.835868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19693} {"level":"info","ts":"2026-10-26T20:06:39.963295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19693,"took":"123.737187ms","hash":1335776326,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":30052352,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:06:39.963376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1335776326,"revision":19693,"compact-revision":17081} {"level":"warn","ts":"2026-10-26T20:10:03.472282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.252807ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-10-26T20:10:03.472397Z","caller":"traceutil/trace.go:171","msg":"trace[1238481045] linearizableReadLoop","detail":"{readStateIndex:24510; appliedIndex:24509; }","duration":"205.778361ms","start":"2026-10-26T20:10:03.266603Z","end":"2026-10-26T20:10:03.472381Z","steps":["trace[1238481045] 'read index received' (duration: 5.324801ms)","trace[1238481045] 'applied index is now lower than readState.Index' (duration: 200.45217ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:10:03.472555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.943234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-10-26T20:10:03.472581Z","caller":"traceutil/trace.go:171","msg":"trace[47990235] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:23719; }","duration":"206.000616ms","start":"2026-10-26T20:10:03.266571Z","end":"2026-10-26T20:10:03.472572Z","steps":["trace[47990235] 'agreement among raft nodes before linearized reading' (duration: 205.873064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:03.475636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.610935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-serving-cert\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:03.475693Z","caller":"traceutil/trace.go:171","msg":"trace[990044605] range","detail":"{range_begin:/registry/cert-manager.io/certificates/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-serving-cert; range_end:; response_count:0; response_revision:23720; }","duration":"198.710747ms","start":"2026-10-26T20:10:03.276969Z","end":"2026-10-26T20:10:03.47568Z","steps":["trace[990044605] 'agreement among raft nodes before linearized reading' (duration: 198.607785ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:10:03.476135Z","caller":"traceutil/trace.go:171","msg":"trace[2099909430] transaction","detail":"{read_only:false; response_revision:23720; number_of_response:1; }","duration":"197.932112ms","start":"2026-10-26T20:10:03.278172Z","end":"2026-10-26T20:10:03.476104Z","steps":["trace[2099909430] 'process raft request' (duration: 197.249448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:03.476835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.723495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machinehealthchecks.cluster.x-k8s.io\" ","response":"range_response_count:1 size:36077"} {"level":"info","ts":"2026-10-26T20:10:03.476892Z","caller":"traceutil/trace.go:171","msg":"trace[561076893] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machinehealthchecks.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23721; }","duration":"190.823827ms","start":"2026-10-26T20:10:03.286052Z","end":"2026-10-26T20:10:03.476876Z","steps":["trace[561076893] 'agreement among raft nodes before linearized reading' (duration: 190.609162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:03.477088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.234363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:03.477153Z","caller":"traceutil/trace.go:171","msg":"trace[67593270] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:23721; }","duration":"183.309265ms","start":"2026-10-26T20:10:03.293828Z","end":"2026-10-26T20:10:03.477138Z","steps":["trace[67593270] 'agreement among raft nodes before linearized reading' (duration: 183.220593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:03.477208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.614712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-10-26T20:10:03.477279Z","caller":"traceutil/trace.go:171","msg":"trace[1143678262] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:23720; }","duration":"181.698093ms","start":"2026-10-26T20:10:03.295567Z","end":"2026-10-26T20:10:03.477265Z","steps":["trace[1143678262] 'agreement among raft nodes before linearized reading' (duration: 180.294266ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:10:08.097881Z","caller":"traceutil/trace.go:171","msg":"trace[1793650849] transaction","detail":"{read_only:false; response_revision:23964; number_of_response:1; }","duration":"127.402408ms","start":"2026-10-26T20:10:07.970454Z","end":"2026-10-26T20:10:08.097856Z","steps":["trace[1793650849] 'process raft request' (duration: 127.142203ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:10:08.185218Z","caller":"traceutil/trace.go:171","msg":"trace[665074855] linearizableReadLoop","detail":"{readStateIndex:24759; appliedIndex:24757; }","duration":"197.340411ms","start":"2026-10-26T20:10:07.987855Z","end":"2026-10-26T20:10:08.185196Z","steps":["trace[665074855] 'read index received' (duration: 109.760242ms)","trace[665074855] 'applied index is now lower than readState.Index' (duration: 87.578989ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:10:08.185358Z","caller":"traceutil/trace.go:171","msg":"trace[159719226] transaction","detail":"{read_only:false; response_revision:23965; number_of_response:1; }","duration":"197.868311ms","start":"2026-10-26T20:10:07.987439Z","end":"2026-10-26T20:10:08.185307Z","steps":["trace[159719226] 'process raft request' (duration: 197.618826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:08.185403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.508784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:08.185557Z","caller":"traceutil/trace.go:171","msg":"trace[440431904] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:23965; }","duration":"197.695848ms","start":"2026-10-26T20:10:07.987848Z","end":"2026-10-26T20:10:08.185544Z","steps":["trace[440431904] 'agreement among raft nodes before linearized reading' (duration: 197.474013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:08.185531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.199132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:10:08.185558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.397803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:08.185622Z","caller":"traceutil/trace.go:171","msg":"trace[1276335139] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23965; }","duration":"150.325325ms","start":"2026-10-26T20:10:08.035276Z","end":"2026-10-26T20:10:08.185601Z","steps":["trace[1276335139] 'agreement among raft nodes before linearized reading' (duration: 150.169692ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:10:08.185648Z","caller":"traceutil/trace.go:171","msg":"trace[459444719] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:23965; }","duration":"197.513655ms","start":"2026-10-26T20:10:07.988114Z","end":"2026-10-26T20:10:08.185627Z","steps":["trace[459444719] 'agreement among raft nodes before linearized reading' (duration: 197.335672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:08.185656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.985155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/cert-manager-test/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials/cert-manager-test0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:10:08.185714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.345524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:08.185745Z","caller":"traceutil/trace.go:171","msg":"trace[2016858539] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/cert-manager-test/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials/cert-manager-test0; response_count:0; response_revision:23965; }","duration":"197.106657ms","start":"2026-10-26T20:10:07.988617Z","end":"2026-10-26T20:10:08.185724Z","steps":["trace[2016858539] 'agreement among raft nodes before linearized reading' (duration: 196.949944ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:10:08.185787Z","caller":"traceutil/trace.go:171","msg":"trace[86095100] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23965; }","duration":"119.453396ms","start":"2026-10-26T20:10:08.066314Z","end":"2026-10-26T20:10:08.185767Z","steps":["trace[86095100] 'agreement among raft nodes before linearized reading' (duration: 119.313383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:08.185822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.745002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:08.185897Z","caller":"traceutil/trace.go:171","msg":"trace[1322843966] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23965; }","duration":"150.879725ms","start":"2026-10-26T20:10:08.034997Z","end":"2026-10-26T20:10:08.185877Z","steps":["trace[1322843966] 'agreement among raft nodes before linearized reading' (duration: 150.697652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:08.186083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.986887ms","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-26T20:10:08.186499Z","caller":"traceutil/trace.go:171","msg":"trace[737472192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23965; }","duration":"163.421025ms","start":"2026-10-26T20:10:08.023065Z","end":"2026-10-26T20:10:08.186486Z","steps":["trace[737472192] 'agreement among raft nodes before linearized reading' (duration: 162.446656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:08.186176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.492517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:08.186763Z","caller":"traceutil/trace.go:171","msg":"trace[1632941426] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23965; }","duration":"121.131609ms","start":"2026-10-26T20:10:08.065617Z","end":"2026-10-26T20:10:08.186749Z","steps":["trace[1632941426] 'agreement among raft nodes before linearized reading' (duration: 120.469526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:08.186172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.784042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:08.186911Z","caller":"traceutil/trace.go:171","msg":"trace[1765698192] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23965; }","duration":"121.551296ms","start":"2026-10-26T20:10:08.06535Z","end":"2026-10-26T20:10:08.186901Z","steps":["trace[1765698192] 'agreement among raft nodes before linearized reading' (duration: 120.726741ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:39.842989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21730} {"level":"info","ts":"2026-10-26T20:11:39.958568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21730,"took":"112.017029ms","hash":564185140,"current-db-size-bytes":55111680,"current-db-size":"55 MB","current-db-size-in-use-bytes":43409408,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-26T20:11:39.958652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":564185140,"revision":21730,"compact-revision":19693} {"level":"warn","ts":"2026-10-26T20:13:43.646328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.940923ms","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:8"} {"level":"info","ts":"2026-10-26T20:13:43.64644Z","caller":"traceutil/trace.go:171","msg":"trace[1974121121] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26115; }","duration":"174.122935ms","start":"2026-10-26T20:13:43.472294Z","end":"2026-10-26T20:13:43.646417Z","steps":["trace[1974121121] 'count revisions from in-memory index tree' (duration: 173.82312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:43.646488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.88656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:13:43.646633Z","caller":"traceutil/trace.go:171","msg":"trace[1812046090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26115; }","duration":"143.073433ms","start":"2026-10-26T20:13:43.503534Z","end":"2026-10-26T20:13:43.646607Z","steps":["trace[1812046090] 'range keys from in-memory index tree' (duration: 142.707916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:43.646654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.54232ms","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-26T20:13:43.646726Z","caller":"traceutil/trace.go:171","msg":"trace[1679618699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26115; }","duration":"244.648323ms","start":"2026-10-26T20:13:43.402059Z","end":"2026-10-26T20:13:43.646707Z","steps":["trace[1679618699] 'range keys from in-memory index tree' (duration: 244.378047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:43.646724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.245213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-26T20:13:43.646752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.853965ms","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-26T20:13:43.646807Z","caller":"traceutil/trace.go:171","msg":"trace[1431653074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26115; }","duration":"279.391556ms","start":"2026-10-26T20:13:43.367395Z","end":"2026-10-26T20:13:43.646787Z","steps":["trace[1431653074] 'range keys from in-memory index tree' (duration: 279.01545ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:43.646811Z","caller":"traceutil/trace.go:171","msg":"trace[104485633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26115; }","duration":"312.934756ms","start":"2026-10-26T20:13:43.333859Z","end":"2026-10-26T20:13:43.646794Z","steps":["trace[104485633] 'range keys from in-memory index tree' (duration: 312.455547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:43.647038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.333846Z","time spent":"313.15463ms","remote":"127.0.0.1:54754","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":"info","ts":"2026-10-26T20:13:44.121643Z","caller":"traceutil/trace.go:171","msg":"trace[1657115050] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"470.101429ms","start":"2026-10-26T20:13:43.651517Z","end":"2026-10-26T20:13:44.121618Z","steps":["trace[1657115050] 'process raft request' (duration: 469.936825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.121805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.651489Z","time spent":"470.230332ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:13:44.498131Z","caller":"traceutil/trace.go:171","msg":"trace[1323732937] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"844.844907ms","start":"2026-10-26T20:13:43.653229Z","end":"2026-10-26T20:13:44.498074Z","steps":["trace[1323732937] 'process raft request' (duration: 844.47995ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:44.498183Z","caller":"traceutil/trace.go:171","msg":"trace[784991076] linearizableReadLoop","detail":"{readStateIndex:26969; appliedIndex:26964; }","duration":"671.077159ms","start":"2026-10-26T20:13:43.827078Z","end":"2026-10-26T20:13:44.498155Z","steps":["trace[784991076] 'read index received' (duration: 294.403434ms)","trace[784991076] 'applied index is now lower than readState.Index' (duration: 376.672655ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:13:44.498288Z","caller":"traceutil/trace.go:171","msg":"trace[701988793] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"844.9975ms","start":"2026-10-26T20:13:43.653279Z","end":"2026-10-26T20:13:44.498276Z","steps":["trace[701988793] 'process raft request' (duration: 844.572352ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:44.498333Z","caller":"traceutil/trace.go:171","msg":"trace[1021266487] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"385.038095ms","start":"2026-10-26T20:13:44.113276Z","end":"2026-10-26T20:13:44.498315Z","steps":["trace[1021266487] 'process raft request' (duration: 384.839271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.498392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.293303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:13:44.498414Z","caller":"traceutil/trace.go:171","msg":"trace[1542153529] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"844.033281ms","start":"2026-10-26T20:13:43.65435Z","end":"2026-10-26T20:13:44.498383Z","steps":["trace[1542153529] 'process raft request' (duration: 843.607023ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:44.498444Z","caller":"traceutil/trace.go:171","msg":"trace[1724154990] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26123; }","duration":"671.405785ms","start":"2026-10-26T20:13:43.827027Z","end":"2026-10-26T20:13:44.498432Z","steps":["trace[1724154990] 'agreement among raft nodes before linearized reading' (duration: 671.276753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.498449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.653261Z","time spent":"845.078852ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:13:44.498485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.827008Z","time spent":"671.465456ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:13:44.498526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.654326Z","time spent":"844.132204ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:13:44.498551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:44.113252Z","time spent":"385.164258ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:13:44.498183Z","caller":"traceutil/trace.go:171","msg":"trace[995515159] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"844.749126ms","start":"2026-10-26T20:13:43.653394Z","end":"2026-10-26T20:13:44.498143Z","steps":["trace[995515159] 'process raft request' (duration: 844.531461ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:44.498736Z","caller":"traceutil/trace.go:171","msg":"trace[542904360] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"388.770856ms","start":"2026-10-26T20:13:44.109954Z","end":"2026-10-26T20:13:44.498725Z","steps":["trace[542904360] 'process raft request' (duration: 388.131895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.49873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.653365Z","time spent":"845.315885ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:13:44.498326Z","caller":"traceutil/trace.go:171","msg":"trace[539756202] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"516.936675ms","start":"2026-10-26T20:13:43.981352Z","end":"2026-10-26T20:13:44.498288Z","steps":["trace[539756202] 'process raft request' (duration: 516.69265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.498839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:44.109933Z","time spent":"388.846527ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:13:44.498859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.98132Z","time spent":"517.470903ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:13:44.499324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:43.653199Z","time spent":"845.048182ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:13:44.541449Z","caller":"traceutil/trace.go:171","msg":"trace[607726829] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"142.555763ms","start":"2026-10-26T20:13:44.398826Z","end":"2026-10-26T20:13:44.541382Z","steps":["trace[607726829] 'process raft request' (duration: 142.462421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.541543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.662335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-10-26T20:13:44.541617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.438076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-26T20:13:44.541632Z","caller":"traceutil/trace.go:171","msg":"trace[898393673] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26125; }","duration":"197.783807ms","start":"2026-10-26T20:13:44.343825Z","end":"2026-10-26T20:13:44.541609Z","steps":["trace[898393673] 'agreement among raft nodes before linearized reading' (duration: 197.540533ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:44.541677Z","caller":"traceutil/trace.go:171","msg":"trace[1654771503] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26125; }","duration":"481.557828ms","start":"2026-10-26T20:13:44.060099Z","end":"2026-10-26T20:13:44.541657Z","steps":["trace[1654771503] 'agreement among raft nodes before linearized reading' (duration: 481.347154ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:44.541677Z","caller":"traceutil/trace.go:171","msg":"trace[1144574628] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"208.556763ms","start":"2026-10-26T20:13:44.333101Z","end":"2026-10-26T20:13:44.541658Z","steps":["trace[1144574628] 'process raft request' (duration: 208.037463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.541715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:44.060076Z","time spent":"481.628279ms","remote":"127.0.0.1:55786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1890,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-10-26T20:13:44.541787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.452906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:13:44.541832Z","caller":"traceutil/trace.go:171","msg":"trace[2018159212] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:26125; }","duration":"447.552968ms","start":"2026-10-26T20:13:44.094265Z","end":"2026-10-26T20:13:44.541818Z","steps":["trace[2018159212] 'agreement among raft nodes before linearized reading' (duration: 447.471557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:44.541864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:44.094238Z","time spent":"447.615419ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:13:44.748657Z","caller":"traceutil/trace.go:171","msg":"trace[556985705] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"200.618311ms","start":"2026-10-26T20:13:44.548006Z","end":"2026-10-26T20:13:44.748624Z","steps":["trace[556985705] 'process raft request' (duration: 131.041893ms)","trace[556985705] 'compare' (duration: 69.439226ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:14:02.061562Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069eb.wal"} {"level":"info","ts":"2026-10-26T20:14:42.863314Z","caller":"traceutil/trace.go:171","msg":"trace[1198184246] transaction","detail":"{read_only:false; response_revision:26650; number_of_response:1; }","duration":"112.935937ms","start":"2026-10-26T20:14:42.750351Z","end":"2026-10-26T20:14:42.863287Z","steps":["trace[1198184246] 'process raft request' (duration: 112.753313ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:42.867823Z","caller":"traceutil/trace.go:171","msg":"trace[1798732797] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"102.486557ms","start":"2026-10-26T20:14:42.765295Z","end":"2026-10-26T20:14:42.867782Z","steps":["trace[1798732797] 'process raft request' (duration: 102.267043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:48.86653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.275858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-26T20:14:48.866652Z","caller":"traceutil/trace.go:171","msg":"trace[2136032058] linearizableReadLoop","detail":"{readStateIndex:27557; appliedIndex:27556; }","duration":"361.819901ms","start":"2026-10-26T20:14:48.504813Z","end":"2026-10-26T20:14:48.866633Z","steps":["trace[2136032058] 'read index received' (duration: 50.065366ms)","trace[2136032058] 'applied index is now lower than readState.Index' (duration: 311.753075ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:14:48.866768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.928323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:14:48.866821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.910942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:14:48.866864Z","caller":"traceutil/trace.go:171","msg":"trace[1857223824] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:26694; }","duration":"362.062895ms","start":"2026-10-26T20:14:48.504778Z","end":"2026-10-26T20:14:48.866841Z","steps":["trace[1857223824] 'agreement among raft nodes before linearized reading' (duration: 361.917382ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:48.866906Z","caller":"traceutil/trace.go:171","msg":"trace[1426807142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26694; }","duration":"362.032175ms","start":"2026-10-26T20:14:48.504854Z","end":"2026-10-26T20:14:48.866886Z","steps":["trace[1426807142] 'agreement among raft nodes before linearized reading' (duration: 361.889192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:48.866946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.504835Z","time spent":"362.102226ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T20:14:48.866952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.504759Z","time spent":"362.174848ms","remote":"127.0.0.1:56994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:14:48.866968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.375261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:14:48.867053Z","caller":"traceutil/trace.go:171","msg":"trace[981214937] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26694; }","duration":"140.468812ms","start":"2026-10-26T20:14:48.726539Z","end":"2026-10-26T20:14:48.867008Z","steps":["trace[981214937] 'agreement among raft nodes before linearized reading' (duration: 140.205737ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:49.134701Z","caller":"traceutil/trace.go:171","msg":"trace[1747801786] transaction","detail":"{read_only:false; response_revision:26695; number_of_response:1; }","duration":"249.061097ms","start":"2026-10-26T20:14:48.88562Z","end":"2026-10-26T20:14:49.134681Z","steps":["trace[1747801786] 'process raft request' (duration: 248.857563ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:49.640644Z","caller":"traceutil/trace.go:171","msg":"trace[115062573] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"743.759807ms","start":"2026-10-26T20:14:48.896858Z","end":"2026-10-26T20:14:49.640617Z","steps":["trace[115062573] 'process raft request' (duration: 743.693746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.640801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.896845Z","time spent":"743.872809ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:14:49.640859Z","caller":"traceutil/trace.go:171","msg":"trace[776147914] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"745.371827ms","start":"2026-10-26T20:14:48.895444Z","end":"2026-10-26T20:14:49.640816Z","steps":["trace[776147914] 'process raft request' (duration: 745.03247ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:49.64096Z","caller":"traceutil/trace.go:171","msg":"trace[1936115229] transaction","detail":"{read_only:false; response_revision:26696; number_of_response:1; }","duration":"748.395224ms","start":"2026-10-26T20:14:48.892549Z","end":"2026-10-26T20:14:49.640944Z","steps":["trace[1936115229] 'process raft request' (duration: 747.754242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.641037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.892526Z","time spent":"748.466986ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:14:49.641038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.895427Z","time spent":"745.517879ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:14:49.641149Z","caller":"traceutil/trace.go:171","msg":"trace[1378473746] linearizableReadLoop","detail":"{readStateIndex:27563; appliedIndex:27557; }","duration":"741.311509ms","start":"2026-10-26T20:14:48.899825Z","end":"2026-10-26T20:14:49.641137Z","steps":["trace[1378473746] 'read index received' (duration: 234.761704ms)","trace[1378473746] 'applied index is now lower than readState.Index' (duration: 506.548555ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:14:49.641156Z","caller":"traceutil/trace.go:171","msg":"trace[1649172042] transaction","detail":"{read_only:false; response_revision:26702; number_of_response:1; }","duration":"250.388492ms","start":"2026-10-26T20:14:49.390749Z","end":"2026-10-26T20:14:49.641138Z","steps":["trace[1649172042] 'process raft request' (duration: 250.352081ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:49.641172Z","caller":"traceutil/trace.go:171","msg":"trace[1941557898] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"599.601201ms","start":"2026-10-26T20:14:49.041548Z","end":"2026-10-26T20:14:49.641149Z","steps":["trace[1941557898] 'process raft request' (duration: 599.484959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.641312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:49.041525Z","time spent":"599.717854ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:14:49.641343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.617359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:14:49.641366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.319637ms","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-26T20:14:49.641401Z","caller":"traceutil/trace.go:171","msg":"trace[1633384847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26702; }","duration":"721.379088ms","start":"2026-10-26T20:14:48.920011Z","end":"2026-10-26T20:14:49.64139Z","steps":["trace[1633384847] 'agreement among raft nodes before linearized reading' (duration: 721.242295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.6426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.919999Z","time spent":"722.585451ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T20:14:49.641398Z","caller":"traceutil/trace.go:171","msg":"trace[312167284] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:26702; }","duration":"648.714501ms","start":"2026-10-26T20:14:48.992666Z","end":"2026-10-26T20:14:49.641381Z","steps":["trace[312167284] 'agreement among raft nodes before linearized reading' (duration: 648.605529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.642952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.992644Z","time spent":"650.285241ms","remote":"127.0.0.1:41708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:14:49.64148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.625955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T20:14:49.643134Z","caller":"traceutil/trace.go:171","msg":"trace[888999075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26702; }","duration":"743.329609ms","start":"2026-10-26T20:14:48.899792Z","end":"2026-10-26T20:14:49.643122Z","steps":["trace[888999075] 'agreement among raft nodes before linearized reading' (duration: 741.450492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.643182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.89978Z","time spent":"743.376109ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-26T20:14:49.641594Z","caller":"traceutil/trace.go:171","msg":"trace[1193189715] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"745.169753ms","start":"2026-10-26T20:14:48.896412Z","end":"2026-10-26T20:14:49.641582Z","steps":["trace[1193189715] 'process raft request' (duration: 744.106913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.643405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.896401Z","time spent":"746.961667ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:14:49.641593Z","caller":"traceutil/trace.go:171","msg":"trace[572975589] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"743.558712ms","start":"2026-10-26T20:14:48.897613Z","end":"2026-10-26T20:14:49.641172Z","steps":["trace[572975589] 'process raft request' (duration: 742.969941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.641673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.260967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:14:49.643669Z","caller":"traceutil/trace.go:171","msg":"trace[170232630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26702; }","duration":"722.296936ms","start":"2026-10-26T20:14:48.921356Z","end":"2026-10-26T20:14:49.643653Z","steps":["trace[170232630] 'agreement among raft nodes before linearized reading' (duration: 719.94435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:49.643724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.921348Z","time spent":"722.365277ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T20:14:49.643513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:14:48.897587Z","time spent":"745.898387ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:15:34.029544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.676865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:15:34.029661Z","caller":"traceutil/trace.go:171","msg":"trace[1624335979] transaction","detail":"{read_only:false; response_revision:27228; number_of_response:1; }","duration":"175.280101ms","start":"2026-10-26T20:15:33.85436Z","end":"2026-10-26T20:15:34.02964Z","steps":["trace[1624335979] 'process raft request' (duration: 52.412532ms)","trace[1624335979] 'compare' (duration: 122.169465ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:15:34.041826Z","caller":"traceutil/trace.go:171","msg":"trace[583150470] linearizableReadLoop","detail":"{readStateIndex:28103; appliedIndex:28101; }","duration":"149.893984ms","start":"2026-10-26T20:15:33.8919Z","end":"2026-10-26T20:15:34.041794Z","steps":["trace[583150470] 'read index received' (duration: 14.887495ms)","trace[583150470] 'applied index is now lower than readState.Index' (duration: 135.005489ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:15:34.041937Z","caller":"traceutil/trace.go:171","msg":"trace[592623420] transaction","detail":"{read_only:false; response_revision:27229; number_of_response:1; }","duration":"185.556856ms","start":"2026-10-26T20:15:33.856346Z","end":"2026-10-26T20:15:34.041903Z","steps":["trace[592623420] 'process raft request' (duration: 185.300782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:15:34.042022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.234556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:15:34.04207Z","caller":"traceutil/trace.go:171","msg":"trace[1616486837] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:27229; }","duration":"144.360199ms","start":"2026-10-26T20:15:33.897695Z","end":"2026-10-26T20:15:34.042055Z","steps":["trace[1616486837] 'agreement among raft nodes before linearized reading' (duration: 144.258907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:15:34.042102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.18999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T20:15:34.042169Z","caller":"traceutil/trace.go:171","msg":"trace[607964586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27229; }","duration":"150.313282ms","start":"2026-10-26T20:15:33.891841Z","end":"2026-10-26T20:15:34.042154Z","steps":["trace[607964586] 'agreement among raft nodes before linearized reading' (duration: 150.096388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:15:34.042394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.221063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:15:34.04243Z","caller":"traceutil/trace.go:171","msg":"trace[1440997010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27229; }","duration":"115.277524ms","start":"2026-10-26T20:15:33.927143Z","end":"2026-10-26T20:15:34.042421Z","steps":["trace[1440997010] 'agreement among raft nodes before linearized reading' (duration: 115.161021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:15:34.042446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.591705ms","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-26T20:15:34.042469Z","caller":"traceutil/trace.go:171","msg":"trace[1035266314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27229; }","duration":"120.653076ms","start":"2026-10-26T20:15:33.921809Z","end":"2026-10-26T20:15:34.042462Z","steps":["trace[1035266314] 'agreement among raft nodes before linearized reading' (duration: 120.588415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:15:34.042495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.554902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T20:15:34.04256Z","caller":"traceutil/trace.go:171","msg":"trace[1106561487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27229; }","duration":"134.654914ms","start":"2026-10-26T20:15:33.907893Z","end":"2026-10-26T20:15:34.042548Z","steps":["trace[1106561487] 'agreement among raft nodes before linearized reading' (duration: 134.42482ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:15:34.273909Z","caller":"traceutil/trace.go:171","msg":"trace[506092124] transaction","detail":"{read_only:false; response_revision:27231; number_of_response:1; }","duration":"226.713063ms","start":"2026-10-26T20:15:34.047155Z","end":"2026-10-26T20:15:34.273868Z","steps":["trace[506092124] 'process raft request' (duration: 226.273635ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:15:34.273954Z","caller":"traceutil/trace.go:171","msg":"trace[1212414824] transaction","detail":"{read_only:false; response_revision:27232; number_of_response:1; }","duration":"226.634913ms","start":"2026-10-26T20:15:34.047268Z","end":"2026-10-26T20:15:34.273902Z","steps":["trace[1212414824] 'process raft request' (duration: 226.48942ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:15:34.274087Z","caller":"traceutil/trace.go:171","msg":"trace[506261402] transaction","detail":"{read_only:false; response_revision:27233; number_of_response:1; }","duration":"226.729784ms","start":"2026-10-26T20:15:34.047327Z","end":"2026-10-26T20:15:34.274057Z","steps":["trace[506261402] 'process raft request' (duration: 226.535311ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:16:39.855046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25003} {"level":"info","ts":"2026-10-26T20:16:40.016394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25003,"took":"156.44088ms","hash":1088104167,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":36446208,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T20:16:40.01647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1088104167,"revision":25003,"compact-revision":21730} {"level":"info","ts":"2026-10-26T20:18:16.71405Z","caller":"traceutil/trace.go:171","msg":"trace[716436893] transaction","detail":"{read_only:false; response_revision:28751; number_of_response:1; }","duration":"112.702945ms","start":"2026-10-26T20:18:16.601332Z","end":"2026-10-26T20:18:16.714035Z","steps":["trace[716436893] 'process raft request' (duration: 109.414712ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:18:16.715468Z","caller":"traceutil/trace.go:171","msg":"trace[1481549575] transaction","detail":"{read_only:false; response_revision:28752; number_of_response:1; }","duration":"110.44077ms","start":"2026-10-26T20:18:16.605001Z","end":"2026-10-26T20:18:16.715441Z","steps":["trace[1481549575] 'process raft request' (duration: 110.210655ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:18:16.715478Z","caller":"traceutil/trace.go:171","msg":"trace[1264681937] transaction","detail":"{read_only:false; response_revision:28753; number_of_response:1; }","duration":"108.688057ms","start":"2026-10-26T20:18:16.606772Z","end":"2026-10-26T20:18:16.71546Z","steps":["trace[1264681937] 'process raft request' (duration: 108.538784ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:18:46.902352Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:18:46.909985Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:18:46.910121Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:21:39.862215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27772} {"level":"info","ts":"2026-10-26T20:21:40.020822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27772,"took":"152.332862ms","hash":4167170478,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":33787904,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T20:21:40.020937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4167170478,"revision":27772,"compact-revision":25003} {"level":"info","ts":"2026-10-26T20:26:39.869107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30409} {"level":"info","ts":"2026-10-26T20:26:40.027604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30409,"took":"154.994736ms","hash":1186716796,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:26:40.027674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1186716796,"revision":30409,"compact-revision":27772} {"level":"info","ts":"2026-10-26T20:31:39.876545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32656} {"level":"info","ts":"2026-10-26T20:31:40.034558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32656,"took":"152.684121ms","hash":339447000,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":30162944,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:31:40.03466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":339447000,"revision":32656,"compact-revision":30409} {"level":"info","ts":"2026-10-26T20:36:39.88587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34892} {"level":"info","ts":"2026-10-26T20:36:40.036909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34892,"took":"146.80161ms","hash":3518594965,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":31256576,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:36:40.036975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3518594965,"revision":34892,"compact-revision":32656} {"level":"info","ts":"2026-10-26T20:40:11.676953Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:40:11.684441Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:40:11.684661Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:41:39.892886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37184} {"level":"info","ts":"2026-10-26T20:41:40.025341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37184,"took":"128.279426ms","hash":3102397721,"current-db-size-bytes":57675776,"current-db-size":"58 MB","current-db-size-in-use-bytes":33079296,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:41:40.025412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3102397721,"revision":37184,"compact-revision":34892} {"level":"info","ts":"2026-10-26T20:46:39.900035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39459} {"level":"info","ts":"2026-10-26T20:46:40.033282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39459,"took":"129.54116ms","hash":2951982093,"current-db-size-bytes":57675776,"current-db-size":"58 MB","current-db-size-in-use-bytes":30707712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:46:40.033351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2951982093,"revision":39459,"compact-revision":37184}