{"level":"warn","ts":"2026-10-26T19:18:28.968274Z","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:18:28.968441Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.239: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.239:2380","--initial-cluster=instance=https://162.253.55.239:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.239:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.239: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:18:28.968552Z","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:18:28.968567Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-10-26T19:18:28.968614Z","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:18:28.969903Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"]} {"level":"info","ts":"2026-10-26T19:18:28.97003Z","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.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.239: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:18:29.006103Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"35.711673ms"} {"level":"info","ts":"2026-10-26T19:18:29.048291Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"548b318b3528a14c","cluster-id":"8bf5e58b9331730a"} {"level":"info","ts":"2026-10-26T19:18:29.048457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:18:29.048493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 0"} {"level":"info","ts":"2026-10-26T19:18:29.048515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 548b318b3528a14c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:18:29.048526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 1"} {"level":"info","ts":"2026-10-26T19:18:29.048584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"warn","ts":"2026-10-26T19:18:29.065124Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:18:29.06716Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:18:29.069149Z","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:18:29.072386Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"548b318b3528a14c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:18:29.073733Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"548b318b3528a14c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:18:29.073843Z","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:18:29.075358Z","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:18:29.075601Z","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:18:29.07664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"info","ts":"2026-10-26T19:18:29.077637Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","added-peer-id":"548b318b3528a14c","added-peer-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-10-26T19:18:29.079586Z","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:18:29.079747Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-10-26T19:18:29.07979Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-10-26T19:18:29.079995Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"548b318b3528a14c","initial-advertise-peer-urls":["https://162.253.55.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:18:29.080032Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:18:29.549145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:18:29.549229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:18:29.54927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgPreVoteResp from 548b318b3528a14c at term 1"} {"level":"info","ts":"2026-10-26T19:18:29.549291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:18:29.549302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgVoteResp from 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-10-26T19:18:29.54932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became leader at term 2"} {"level":"info","ts":"2026-10-26T19:18:29.549347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 548b318b3528a14c elected leader 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-10-26T19:18:29.550786Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:29.551728Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"548b318b3528a14c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.239:2379]}","request-path":"/0/members/548b318b3528a14c/attributes","cluster-id":"8bf5e58b9331730a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:18:29.551727Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:18:29.551866Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:18:29.552236Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:18:29.552329Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:18:29.552657Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:29.55282Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:29.552866Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:29.553592Z","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:18:29.559273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.239:2379"} {"level":"warn","ts":"2026-10-26T19:18:46.79403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.902196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:18:46.794318Z","caller":"traceutil/trace.go:171","msg":"trace[1730872486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"129.551343ms","start":"2026-10-26T19:18:46.664716Z","end":"2026-10-26T19:18:46.794267Z","steps":["trace[1730872486] 'range keys from in-memory index tree' (duration: 128.788203ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:17.884985Z","caller":"traceutil/trace.go:171","msg":"trace[1625586389] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"516.424428ms","start":"2026-10-26T19:19:17.368534Z","end":"2026-10-26T19:19:17.884959Z","steps":["trace[1625586389] 'process raft request' (duration: 516.251683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.885466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.368392Z","time spent":"516.674955ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.982031Z","caller":"traceutil/trace.go:171","msg":"trace[1029455965] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"610.632088ms","start":"2026-10-26T19:19:17.371351Z","end":"2026-10-26T19:19:17.981983Z","steps":["trace[1029455965] 'process raft request' (duration: 610.34159ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:17.982126Z","caller":"traceutil/trace.go:171","msg":"trace[1765348483] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"595.545767ms","start":"2026-10-26T19:19:17.386534Z","end":"2026-10-26T19:19:17.98208Z","steps":["trace[1765348483] 'process raft request' (duration: 595.425543ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:17.982195Z","caller":"traceutil/trace.go:171","msg":"trace[955662856] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"594.392914ms","start":"2026-10-26T19:19:17.387749Z","end":"2026-10-26T19:19:17.982141Z","steps":["trace[955662856] 'process raft request' (duration: 594.260831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.982233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.371322Z","time spent":"610.818343ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:19:17.982264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.386488Z","time spent":"595.695182ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.982129Z","caller":"traceutil/trace.go:171","msg":"trace[696144303] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"608.641193ms","start":"2026-10-26T19:19:17.373405Z","end":"2026-10-26T19:19:17.982046Z","steps":["trace[696144303] 'process raft request' (duration: 608.486338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.982316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.387715Z","time spent":"594.538749ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.982269Z","caller":"traceutil/trace.go:171","msg":"trace[1094017497] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"594.345594ms","start":"2026-10-26T19:19:17.387871Z","end":"2026-10-26T19:19:17.982217Z","steps":["trace[1094017497] 'process raft request' (duration: 594.192149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.982469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.387845Z","time spent":"594.543849ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.98248Z","caller":"traceutil/trace.go:171","msg":"trace[1605670132] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"590.232128ms","start":"2026-10-26T19:19:17.392228Z","end":"2026-10-26T19:19:17.98246Z","steps":["trace[1605670132] 'process raft request' (duration: 589.94741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.982477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.373367Z","time spent":"608.996393ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:19:17.982569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.392205Z","time spent":"590.318441ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.982526Z","caller":"traceutil/trace.go:171","msg":"trace[950948318] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"591.810943ms","start":"2026-10-26T19:19:17.390557Z","end":"2026-10-26T19:19:17.982368Z","steps":["trace[950948318] 'process raft request' (duration: 591.557166ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:17.982651Z","caller":"traceutil/trace.go:171","msg":"trace[237401553] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"578.695316ms","start":"2026-10-26T19:19:17.403938Z","end":"2026-10-26T19:19:17.982633Z","steps":["trace[237401553] 'process raft request' (duration: 578.394678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.982727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.403904Z","time spent":"578.790079ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:19:17.982777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.390507Z","time spent":"592.154432ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.983107Z","caller":"traceutil/trace.go:171","msg":"trace[1435726146] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"551.058955ms","start":"2026-10-26T19:19:17.431709Z","end":"2026-10-26T19:19:17.982768Z","steps":["trace[1435726146] 'process raft request' (duration: 550.839189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.983208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.431673Z","time spent":"551.500737ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.983229Z","caller":"traceutil/trace.go:171","msg":"trace[1600742572] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"533.514235ms","start":"2026-10-26T19:19:17.449702Z","end":"2026-10-26T19:19:17.983216Z","steps":["trace[1600742572] 'process raft request' (duration: 533.061883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.983291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.449659Z","time spent":"533.604748ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.983446Z","caller":"traceutil/trace.go:171","msg":"trace[1440366809] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"414.354039ms","start":"2026-10-26T19:19:17.569083Z","end":"2026-10-26T19:19:17.983437Z","steps":["trace[1440366809] 'process raft request' (duration: 414.141083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.983481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.569046Z","time spent":"414.41738ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.98346Z","caller":"traceutil/trace.go:171","msg":"trace[332009924] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:571; }","duration":"461.174545ms","start":"2026-10-26T19:19:17.522247Z","end":"2026-10-26T19:19:17.983422Z","steps":["trace[332009924] 'read index received' (duration: 362.627654ms)","trace[332009924] 'applied index is now lower than readState.Index' (duration: 98.545891ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:19:17.983774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.504235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T19:19:17.983854Z","caller":"traceutil/trace.go:171","msg":"trace[605971311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"461.655579ms","start":"2026-10-26T19:19:17.522182Z","end":"2026-10-26T19:19:17.983838Z","steps":["trace[605971311] 'agreement among raft nodes before linearized reading' (duration: 461.394182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.983916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.522136Z","time spent":"461.761382ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T19:19:17.990144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.848741ms","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:19:17.990299Z","caller":"traceutil/trace.go:171","msg":"trace[90023776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"425.003406ms","start":"2026-10-26T19:19:17.565269Z","end":"2026-10-26T19:19:17.990272Z","steps":["trace[90023776] 'agreement among raft nodes before linearized reading' (duration: 424.176033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.990351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.565224Z","time spent":"425.11658ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T19:19:17.990598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.221019ms","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:19:17.990634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.348733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:19:17.990723Z","caller":"traceutil/trace.go:171","msg":"trace[1199620985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"325.462347ms","start":"2026-10-26T19:19:17.665232Z","end":"2026-10-26T19:19:17.990694Z","steps":["trace[1199620985] 'agreement among raft nodes before linearized reading' (duration: 325.263931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.991161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.665215Z","time spent":"325.93297ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:19:17.992483Z","caller":"traceutil/trace.go:171","msg":"trace[752776094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"138.342713ms","start":"2026-10-26T19:19:17.852316Z","end":"2026-10-26T19:19:17.990659Z","steps":["trace[752776094] 'agreement among raft nodes before linearized reading' (duration: 137.764496ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:24.383169Z","caller":"traceutil/trace.go:171","msg":"trace[1039350454] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"193.401179ms","start":"2026-10-26T19:19:24.189728Z","end":"2026-10-26T19:19:24.383129Z","steps":["trace[1039350454] 'process raft request' (duration: 193.169393ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:24.902201Z","caller":"traceutil/trace.go:171","msg":"trace[554688895] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"379.471464ms","start":"2026-10-26T19:19:24.522692Z","end":"2026-10-26T19:19:24.902163Z","steps":["trace[554688895] 'process raft request' (duration: 375.703869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:24.902381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.989219ms","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:19:24.902454Z","caller":"traceutil/trace.go:171","msg":"trace[376183499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"298.121593ms","start":"2026-10-26T19:19:24.604314Z","end":"2026-10-26T19:19:24.902436Z","steps":["trace[376183499] 'agreement among raft nodes before linearized reading' (duration: 297.874456ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:24.902249Z","caller":"traceutil/trace.go:171","msg":"trace[1643051370] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"297.769833ms","start":"2026-10-26T19:19:24.604383Z","end":"2026-10-26T19:19:24.902153Z","steps":["trace[1643051370] 'read index received' (duration: 293.697899ms)","trace[1643051370] 'applied index is now lower than readState.Index' (duration: 4.070254ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:19:24.902726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:24.522654Z","time spent":"379.804723ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:19:26.078937Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074276572s","expected-duration":"1s"} {"level":"info","ts":"2026-10-26T19:19:26.080722Z","caller":"traceutil/trace.go:171","msg":"trace[288224536] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"415.529351ms","start":"2026-10-26T19:19:25.665175Z","end":"2026-10-26T19:19:26.080704Z","steps":["trace[288224536] 'read index received' (duration: 413.969557ms)","trace[288224536] 'applied index is now lower than readState.Index' (duration: 1.559074ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:19:26.08081Z","caller":"traceutil/trace.go:171","msg":"trace[699565859] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.076256347s","start":"2026-10-26T19:19:25.004503Z","end":"2026-10-26T19:19:26.08076Z","steps":["trace[699565859] 'process raft request' (duration: 1.074693094s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:26.080935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:25.004483Z","time spent":"1.076395962s","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:19:26.080853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.665085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:19:26.081065Z","caller":"traceutil/trace.go:171","msg":"trace[1509002387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"415.923112ms","start":"2026-10-26T19:19:25.665126Z","end":"2026-10-26T19:19:26.081049Z","steps":["trace[1509002387] 'agreement among raft nodes before linearized reading' (duration: 415.677626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:26.081112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:25.665104Z","time spent":"415.992214ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:19:27.474678Z","caller":"traceutil/trace.go:171","msg":"trace[1036667523] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"136.394168ms","start":"2026-10-26T19:19:27.338256Z","end":"2026-10-26T19:19:27.47465Z","steps":["trace[1036667523] 'process raft request' (duration: 131.842511ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:27.482345Z","caller":"traceutil/trace.go:171","msg":"trace[254506644] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"138.489507ms","start":"2026-10-26T19:19:27.343821Z","end":"2026-10-26T19:19:27.482311Z","steps":["trace[254506644] 'process raft request' (duration: 138.331622ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:38.430176Z","caller":"traceutil/trace.go:171","msg":"trace[1224548621] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"140.036979ms","start":"2026-10-26T19:19:38.290111Z","end":"2026-10-26T19:19:38.430148Z","steps":["trace[1224548621] 'process raft request' (duration: 103.193661ms)","trace[1224548621] 'compare' (duration: 36.592561ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:10.728631Z","caller":"traceutil/trace.go:171","msg":"trace[477344782] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"277.487157ms","start":"2026-10-26T19:20:10.451108Z","end":"2026-10-26T19:20:10.728595Z","steps":["trace[477344782] 'process raft request' (duration: 262.407666ms)","trace[477344782] 'compare' (duration: 14.702421ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:48.942728Z","caller":"traceutil/trace.go:171","msg":"trace[1244952668] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"299.77438ms","start":"2026-10-26T19:20:48.642925Z","end":"2026-10-26T19:20:48.9427Z","steps":["trace[1244952668] 'process raft request' (duration: 254.914774ms)","trace[1244952668] 'compare' (duration: 44.740492ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:48.991942Z","caller":"traceutil/trace.go:171","msg":"trace[89203802] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"102.518019ms","start":"2026-10-26T19:20:48.889389Z","end":"2026-10-26T19:20:48.991907Z","steps":["trace[89203802] 'process raft request' (duration: 102.437707ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:48.992162Z","caller":"traceutil/trace.go:171","msg":"trace[290904427] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"348.970807ms","start":"2026-10-26T19:20:48.643154Z","end":"2026-10-26T19:20:48.992125Z","steps":["trace[290904427] 'process raft request' (duration: 348.475843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:48.99232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:20:48.643142Z","time spent":"349.097151ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:20:57.050897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.538354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:20:57.050998Z","caller":"traceutil/trace.go:171","msg":"trace[99248147] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"138.115695ms","start":"2026-10-26T19:20:56.912869Z","end":"2026-10-26T19:20:57.050985Z","steps":["trace[99248147] 'read index received' (duration: 71.492µs)","trace[99248147] 'applied index is now lower than readState.Index' (duration: 138.041213ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:57.051076Z","caller":"traceutil/trace.go:171","msg":"trace[1600126643] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"367.843815ms","start":"2026-10-26T19:20:56.683184Z","end":"2026-10-26T19:20:57.051028Z","steps":["trace[1600126643] 'process raft request' (duration: 72.925891ms)","trace[1600126643] 'compare' (duration: 294.338838ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:20:57.051214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.311061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abb16e2f-e9a1-450c-8fec-0d10a77ebb37\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-10-26T19:20:57.0513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:20:56.68313Z","time spent":"368.056272ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:20:57.051315Z","caller":"traceutil/trace.go:171","msg":"trace[773356203] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abb16e2f-e9a1-450c-8fec-0d10a77ebb37; range_end:; response_count:1; response_revision:1888; }","duration":"138.494186ms","start":"2026-10-26T19:20:56.912798Z","end":"2026-10-26T19:20:57.051292Z","steps":["trace[773356203] 'agreement among raft nodes before linearized reading' (duration: 138.310771ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:57.329357Z","caller":"traceutil/trace.go:171","msg":"trace[825507979] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"268.452704ms","start":"2026-10-26T19:20:57.060863Z","end":"2026-10-26T19:20:57.329316Z","steps":["trace[825507979] 'process raft request' (duration: 238.253369ms)","trace[825507979] 'compare' (duration: 29.930218ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:57.335908Z","caller":"traceutil/trace.go:171","msg":"trace[110137146] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"274.616077ms","start":"2026-10-26T19:20:57.061228Z","end":"2026-10-26T19:20:57.335844Z","steps":["trace[110137146] 'process raft request' (duration: 274.399521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:57.706235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.915405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:20:57.70648Z","caller":"traceutil/trace.go:171","msg":"trace[1121269691] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"254.794182ms","start":"2026-10-26T19:20:57.451666Z","end":"2026-10-26T19:20:57.70646Z","steps":["trace[1121269691] 'read index received' (duration: 9.416503ms)","trace[1121269691] 'applied index is now lower than readState.Index' (duration: 245.376739ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:57.706495Z","caller":"traceutil/trace.go:171","msg":"trace[731354890] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"257.295022ms","start":"2026-10-26T19:20:57.449145Z","end":"2026-10-26T19:20:57.70644Z","steps":["trace[731354890] 'process raft request' (duration: 12.040457ms)","trace[731354890] 'compare' (duration: 244.73042ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:20:57.70684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.832222ms","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:20:57.706885Z","caller":"traceutil/trace.go:171","msg":"trace[2138588785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1893; }","duration":"130.892044ms","start":"2026-10-26T19:20:57.575973Z","end":"2026-10-26T19:20:57.706865Z","steps":["trace[2138588785] 'agreement among raft nodes before linearized reading' (duration: 130.703489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:57.706917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.212944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T19:20:57.706991Z","caller":"traceutil/trace.go:171","msg":"trace[1903390164] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"255.366488ms","start":"2026-10-26T19:20:57.451605Z","end":"2026-10-26T19:20:57.706971Z","steps":["trace[1903390164] 'agreement among raft nodes before linearized reading' (duration: 255.011208ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:41.741977Z","caller":"traceutil/trace.go:171","msg":"trace[1442224691] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"146.255513ms","start":"2026-10-26T19:21:41.595695Z","end":"2026-10-26T19:21:41.74195Z","steps":["trace[1442224691] 'read index received' (duration: 146.021066ms)","trace[1442224691] 'applied index is now lower than readState.Index' (duration: 233.297µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:41.742184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.476788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T19:21:41.742224Z","caller":"traceutil/trace.go:171","msg":"trace[1244055337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2187; }","duration":"146.575162ms","start":"2026-10-26T19:21:41.595638Z","end":"2026-10-26T19:21:41.742213Z","steps":["trace[1244055337] 'agreement among raft nodes before linearized reading' (duration: 146.435078ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:41.742413Z","caller":"traceutil/trace.go:171","msg":"trace[575864805] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"147.562419ms","start":"2026-10-26T19:21:41.594506Z","end":"2026-10-26T19:21:41.742068Z","steps":["trace[575864805] 'process raft request' (duration: 147.299132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:21:47.572265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.828288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:21:47.572437Z","caller":"traceutil/trace.go:171","msg":"trace[86423743] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"327.601004ms","start":"2026-10-26T19:21:47.244769Z","end":"2026-10-26T19:21:47.57237Z","steps":["trace[86423743] 'process raft request' (duration: 60.57656ms)","trace[86423743] 'compare' (duration: 266.609342ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:47.572694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:21:47.244746Z","time spent":"327.832279ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:21:47.805386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.853738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:21:47.805539Z","caller":"traceutil/trace.go:171","msg":"trace[2093155175] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2218; }","duration":"106.100725ms","start":"2026-10-26T19:21:47.699411Z","end":"2026-10-26T19:21:47.805512Z","steps":["trace[2093155175] 'count revisions from in-memory index tree' (duration: 105.630963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:22:36.517922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.728516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:22:36.517998Z","caller":"traceutil/trace.go:171","msg":"trace[1481286011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2656; }","duration":"104.87296ms","start":"2026-10-26T19:22:36.41311Z","end":"2026-10-26T19:22:36.517983Z","steps":["trace[1481286011] 'range keys from in-memory index tree' (duration: 104.582762ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:36.933768Z","caller":"traceutil/trace.go:171","msg":"trace[528526277] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"265.560993ms","start":"2026-10-26T19:22:36.66817Z","end":"2026-10-26T19:22:36.933731Z","steps":["trace[528526277] 'process raft request' (duration: 265.293206ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:22:36.934601Z","caller":"traceutil/trace.go:171","msg":"trace[751176972] transaction","detail":"{read_only:false; response_revision:2660; number_of_response:1; }","duration":"123.288926ms","start":"2026-10-26T19:22:36.811279Z","end":"2026-10-26T19:22:36.934568Z","steps":["trace[751176972] 'process raft request' (duration: 123.05784ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:23:10.009581Z","caller":"traceutil/trace.go:171","msg":"trace[819036241] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"101.536118ms","start":"2026-10-26T19:23:09.908017Z","end":"2026-10-26T19:23:10.009553Z","steps":["trace[819036241] 'process raft request' (duration: 100.735936ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:23:15.404486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.683932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-26T19:23:15.404567Z","caller":"traceutil/trace.go:171","msg":"trace[1879960010] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2888; }","duration":"251.817406ms","start":"2026-10-26T19:23:15.152728Z","end":"2026-10-26T19:23:15.404546Z","steps":["trace[1879960010] 'range keys from in-memory index tree' (duration: 250.90043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:31.132592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.923505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:25:31.132816Z","caller":"traceutil/trace.go:171","msg":"trace[296687767] transaction","detail":"{read_only:false; response_revision:3751; number_of_response:1; }","duration":"138.89432ms","start":"2026-10-26T19:25:30.993889Z","end":"2026-10-26T19:25:31.132784Z","steps":["trace[296687767] 'compare' (duration: 128.613656ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:31.132862Z","caller":"traceutil/trace.go:171","msg":"trace[1946658952] transaction","detail":"{read_only:false; response_revision:3752; number_of_response:1; }","duration":"128.328809ms","start":"2026-10-26T19:25:31.00451Z","end":"2026-10-26T19:25:31.132839Z","steps":["trace[1946658952] 'process raft request' (duration: 128.236496ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:31.132899Z","caller":"traceutil/trace.go:171","msg":"trace[979775188] linearizableReadLoop","detail":"{readStateIndex:3894; appliedIndex:3892; }","duration":"136.466135ms","start":"2026-10-26T19:25:30.996422Z","end":"2026-10-26T19:25:31.132889Z","steps":["trace[979775188] 'read index received' (duration: 3.951235ms)","trace[979775188] 'applied index is now lower than readState.Index' (duration: 132.51378ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:25:31.133111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.6714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/secretgen-controller/secretgen-controller\" ","response":"range_response_count:1 size:2731"} {"level":"info","ts":"2026-10-26T19:25:31.13375Z","caller":"traceutil/trace.go:171","msg":"trace[358691489] range","detail":"{range_begin:/registry/deployments/secretgen-controller/secretgen-controller; range_end:; response_count:1; response_revision:3752; }","duration":"137.334418ms","start":"2026-10-26T19:25:30.996393Z","end":"2026-10-26T19:25:31.133727Z","steps":["trace[358691489] 'agreement among raft nodes before linearized reading' (duration: 136.519057ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:31.138901Z","caller":"traceutil/trace.go:171","msg":"trace[907302853] transaction","detail":"{read_only:false; response_revision:3754; number_of_response:1; }","duration":"133.064775ms","start":"2026-10-26T19:25:31.00581Z","end":"2026-10-26T19:25:31.138875Z","steps":["trace[907302853] 'process raft request' (duration: 132.911361ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:31.138802Z","caller":"traceutil/trace.go:171","msg":"trace[1945764366] transaction","detail":"{read_only:false; response_revision:3753; number_of_response:1; }","duration":"133.546978ms","start":"2026-10-26T19:25:31.005233Z","end":"2026-10-26T19:25:31.13878Z","steps":["trace[1945764366] 'process raft request' (duration: 133.331432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:05.823557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.443073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:26:05.823638Z","caller":"traceutil/trace.go:171","msg":"trace[1929387894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3993; }","duration":"142.563815ms","start":"2026-10-26T19:26:05.681058Z","end":"2026-10-26T19:26:05.823621Z","steps":["trace[1929387894] 'range keys from in-memory index tree' (duration: 142.256287ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:05.943511Z","caller":"traceutil/trace.go:171","msg":"trace[1946188207] transaction","detail":"{read_only:false; response_revision:3994; number_of_response:1; }","duration":"127.295733ms","start":"2026-10-26T19:26:05.816197Z","end":"2026-10-26T19:26:05.943492Z","steps":["trace[1946188207] 'process raft request' (duration: 127.159149ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:06.040378Z","caller":"traceutil/trace.go:171","msg":"trace[981053028] transaction","detail":"{read_only:false; response_revision:3995; number_of_response:1; }","duration":"213.168274ms","start":"2026-10-26T19:26:05.82708Z","end":"2026-10-26T19:26:06.040249Z","steps":["trace[981053028] 'process raft request' (duration: 212.365063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:28.902686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.160116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:27:28.903047Z","caller":"traceutil/trace.go:171","msg":"trace[1857408164] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"203.888046ms","start":"2026-10-26T19:27:28.699129Z","end":"2026-10-26T19:27:28.903017Z","steps":["trace[1857408164] 'process raft request' (duration: 51.311528ms)","trace[1857408164] 'compare' (duration: 151.858018ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:28.903084Z","caller":"traceutil/trace.go:171","msg":"trace[726949669] linearizableReadLoop","detail":"{readStateIndex:5277; appliedIndex:5276; }","duration":"203.870426ms","start":"2026-10-26T19:27:28.699189Z","end":"2026-10-26T19:27:28.903059Z","steps":["trace[726949669] 'read index received' (duration: 51.295908ms)","trace[726949669] 'applied index is now lower than readState.Index' (duration: 152.572438ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:28.90333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.130603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7.18e22af6d58de53c\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-10-26T19:27:28.903378Z","caller":"traceutil/trace.go:171","msg":"trace[1423559393] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7.18e22af6d58de53c; range_end:; response_count:1; response_revision:5085; }","duration":"204.206824ms","start":"2026-10-26T19:27:28.69916Z","end":"2026-10-26T19:27:28.903367Z","steps":["trace[1423559393] 'agreement among raft nodes before linearized reading' (duration: 204.014859ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:28.903369Z","caller":"traceutil/trace.go:171","msg":"trace[2086711801] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"202.982201ms","start":"2026-10-26T19:27:28.700365Z","end":"2026-10-26T19:27:28.903347Z","steps":["trace[2086711801] 'process raft request' (duration: 202.763005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:28.903542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.363366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-10-26T19:27:28.903582Z","caller":"traceutil/trace.go:171","msg":"trace[827861403] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5086; }","duration":"201.438339ms","start":"2026-10-26T19:27:28.702131Z","end":"2026-10-26T19:27:28.903569Z","steps":["trace[827861403] 'agreement among raft nodes before linearized reading' (duration: 201.251504ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:28.903594Z","caller":"traceutil/trace.go:171","msg":"trace[181839474] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"204.272156ms","start":"2026-10-26T19:27:28.699289Z","end":"2026-10-26T19:27:28.903561Z","steps":["trace[181839474] 'process raft request' (duration: 203.577558ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:28.903683Z","caller":"traceutil/trace.go:171","msg":"trace[2137210125] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"204.195604ms","start":"2026-10-26T19:27:28.699424Z","end":"2026-10-26T19:27:28.90362Z","steps":["trace[2137210125] 'process raft request' (duration: 203.570077ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.112651Z","caller":"traceutil/trace.go:171","msg":"trace[515065883] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"172.218962ms","start":"2026-10-26T19:27:28.940401Z","end":"2026-10-26T19:27:29.11262Z","steps":["trace[515065883] 'process raft request' (duration: 172.089349ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.112675Z","caller":"traceutil/trace.go:171","msg":"trace[143258880] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"170.484185ms","start":"2026-10-26T19:27:28.942167Z","end":"2026-10-26T19:27:29.112651Z","steps":["trace[143258880] 'process raft request' (duration: 170.392073ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.112655Z","caller":"traceutil/trace.go:171","msg":"trace[1134212867] linearizableReadLoop","detail":"{readStateIndex:5287; appliedIndex:5284; }","duration":"122.58112ms","start":"2026-10-26T19:27:28.990044Z","end":"2026-10-26T19:27:29.112625Z","steps":["trace[1134212867] 'read index received' (duration: 42.92495ms)","trace[1134212867] 'applied index is now lower than readState.Index' (duration: 79.655ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:29.112634Z","caller":"traceutil/trace.go:171","msg":"trace[815516669] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"172.364077ms","start":"2026-10-26T19:27:28.940201Z","end":"2026-10-26T19:27:29.112565Z","steps":["trace[815516669] 'process raft request' (duration: 92.85368ms)","trace[815516669] 'compare' (duration: 79.26882ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:29.112964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.885479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-26T19:27:29.113019Z","caller":"traceutil/trace.go:171","msg":"trace[345493264] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5093; }","duration":"202.981312ms","start":"2026-10-26T19:27:28.910021Z","end":"2026-10-26T19:27:29.113003Z","steps":["trace[345493264] 'agreement among raft nodes before linearized reading' (duration: 202.728735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.197973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.617871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16492"} {"level":"info","ts":"2026-10-26T19:27:29.198079Z","caller":"traceutil/trace.go:171","msg":"trace[2020459401] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5094; }","duration":"206.749674ms","start":"2026-10-26T19:27:28.991304Z","end":"2026-10-26T19:27:29.198053Z","steps":["trace[2020459401] 'agreement among raft nodes before linearized reading' (duration: 206.403845ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.554078Z","caller":"traceutil/trace.go:171","msg":"trace[2105489927] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"436.159315ms","start":"2026-10-26T19:27:29.117884Z","end":"2026-10-26T19:27:29.554043Z","steps":["trace[2105489927] 'process raft request' (duration: 435.869437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.554241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.117849Z","time spent":"436.312579ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:27:29.554277Z","caller":"traceutil/trace.go:171","msg":"trace[1786337872] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"436.277428ms","start":"2026-10-26T19:27:29.117976Z","end":"2026-10-26T19:27:29.554253Z","steps":["trace[1786337872] 'process raft request' (duration: 436.034402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.554373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.117952Z","time spent":"436.377521ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:27:29.554472Z","caller":"traceutil/trace.go:171","msg":"trace[356326410] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"434.815079ms","start":"2026-10-26T19:27:29.119638Z","end":"2026-10-26T19:27:29.554453Z","steps":["trace[356326410] 'process raft request' (duration: 434.419158ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.554477Z","caller":"traceutil/trace.go:171","msg":"trace[1004455964] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"436.439173ms","start":"2026-10-26T19:27:29.11798Z","end":"2026-10-26T19:27:29.554419Z","steps":["trace[1004455964] 'process raft request' (duration: 435.98305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.554566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.119618Z","time spent":"434.904931ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3529,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:27:29.554619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.798793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-26T19:27:29.554705Z","caller":"traceutil/trace.go:171","msg":"trace[845780487] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5098; }","duration":"436.980747ms","start":"2026-10-26T19:27:29.117711Z","end":"2026-10-26T19:27:29.554691Z","steps":["trace[845780487] 'agreement among raft nodes before linearized reading' (duration: 436.538285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.554724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.11797Z","time spent":"436.629398ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:27:29.554823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.11768Z","time spent":"437.08441ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-10-26T19:27:29.554109Z","caller":"traceutil/trace.go:171","msg":"trace[2129115520] linearizableReadLoop","detail":"{readStateIndex:5292; appliedIndex:5288; }","duration":"356.450533ms","start":"2026-10-26T19:27:29.197642Z","end":"2026-10-26T19:27:29.554092Z","steps":["trace[2129115520] 'read index received' (duration: 356.015621ms)","trace[2129115520] 'applied index is now lower than readState.Index' (duration: 434.152µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:29.555283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.440532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:29.555369Z","caller":"traceutil/trace.go:171","msg":"trace[1675696378] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5098; }","duration":"280.585436ms","start":"2026-10-26T19:27:29.27476Z","end":"2026-10-26T19:27:29.555345Z","steps":["trace[1675696378] 'agreement among raft nodes before linearized reading' (duration: 280.408211ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.738145Z","caller":"traceutil/trace.go:171","msg":"trace[829118410] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"174.760861ms","start":"2026-10-26T19:27:29.563347Z","end":"2026-10-26T19:27:29.738108Z","steps":["trace[829118410] 'process raft request' (duration: 137.380064ms)","trace[829118410] 'compare' (duration: 37.062189ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:29.738155Z","caller":"traceutil/trace.go:171","msg":"trace[1402940588] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"174.760261ms","start":"2026-10-26T19:27:29.56336Z","end":"2026-10-26T19:27:29.73812Z","steps":["trace[1402940588] 'process raft request' (duration: 174.679508ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.749388Z","caller":"traceutil/trace.go:171","msg":"trace[987513765] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"185.891174ms","start":"2026-10-26T19:27:29.563464Z","end":"2026-10-26T19:27:29.749355Z","steps":["trace[987513765] 'process raft request' (duration: 185.421121ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.749446Z","caller":"traceutil/trace.go:171","msg":"trace[301185820] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"185.074482ms","start":"2026-10-26T19:27:29.564356Z","end":"2026-10-26T19:27:29.74943Z","steps":["trace[301185820] 'process raft request' (duration: 184.953039ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.749474Z","caller":"traceutil/trace.go:171","msg":"trace[474325818] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"185.203535ms","start":"2026-10-26T19:27:29.564235Z","end":"2026-10-26T19:27:29.749438Z","steps":["trace[474325818] 'process raft request' (duration: 185.02242ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.749655Z","caller":"traceutil/trace.go:171","msg":"trace[524536621] linearizableReadLoop","detail":"{readStateIndex:5296; appliedIndex:5292; }","duration":"185.313088ms","start":"2026-10-26T19:27:29.564315Z","end":"2026-10-26T19:27:29.749628Z","steps":["trace[524536621] 'read index received' (duration: 136.51169ms)","trace[524536621] 'applied index is now lower than readState.Index' (duration: 48.799878ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:29.7497Z","caller":"traceutil/trace.go:171","msg":"trace[1103942249] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"184.411854ms","start":"2026-10-26T19:27:29.565239Z","end":"2026-10-26T19:27:29.74965Z","steps":["trace[1103942249] 'process raft request' (duration: 184.109836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.749957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.617946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T19:27:29.750207Z","caller":"traceutil/trace.go:171","msg":"trace[829246057] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5105; }","duration":"185.742891ms","start":"2026-10-26T19:27:29.564263Z","end":"2026-10-26T19:27:29.750006Z","steps":["trace[829246057] 'agreement among raft nodes before linearized reading' (duration: 185.508334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.783924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.694404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:27:29.78394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.806004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-10-26T19:27:29.784006Z","caller":"traceutil/trace.go:171","msg":"trace[1416274150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5108; }","duration":"118.833258ms","start":"2026-10-26T19:27:29.665153Z","end":"2026-10-26T19:27:29.783986Z","steps":["trace[1416274150] 'agreement among raft nodes before linearized reading' (duration: 118.693194ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.784044Z","caller":"traceutil/trace.go:171","msg":"trace[1603408250] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7; range_end:; response_count:1; response_revision:5108; }","duration":"217.951338ms","start":"2026-10-26T19:27:29.566047Z","end":"2026-10-26T19:27:29.783998Z","steps":["trace[1603408250] 'agreement among raft nodes before linearized reading' (duration: 217.716842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.783922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.377479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:29.784182Z","caller":"traceutil/trace.go:171","msg":"trace[1380705908] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5108; }","duration":"218.682878ms","start":"2026-10-26T19:27:29.565457Z","end":"2026-10-26T19:27:29.78414Z","steps":["trace[1380705908] 'agreement among raft nodes before linearized reading' (duration: 218.327678ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.914427Z","caller":"traceutil/trace.go:171","msg":"trace[1195784547] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"119.087044ms","start":"2026-10-26T19:27:29.79529Z","end":"2026-10-26T19:27:29.914377Z","steps":["trace[1195784547] 'process raft request' (duration: 118.93868ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.914557Z","caller":"traceutil/trace.go:171","msg":"trace[759525109] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"126.117677ms","start":"2026-10-26T19:27:29.788413Z","end":"2026-10-26T19:27:29.914531Z","steps":["trace[759525109] 'process raft request' (duration: 116.027272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.914585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.283084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/monitoring/loki-gateway-cf54cb88c\" ","response":"range_response_count:1 size:4159"} {"level":"info","ts":"2026-10-26T19:27:29.914638Z","caller":"traceutil/trace.go:171","msg":"trace[368195603] range","detail":"{range_begin:/registry/replicasets/monitoring/loki-gateway-cf54cb88c; range_end:; response_count:1; response_revision:5113; }","duration":"121.405028ms","start":"2026-10-26T19:27:29.793219Z","end":"2026-10-26T19:27:29.914624Z","steps":["trace[368195603] 'agreement among raft nodes before linearized reading' (duration: 121.189562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.914956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.446345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-10-26T19:27:29.915032Z","caller":"traceutil/trace.go:171","msg":"trace[1278235419] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5113; }","duration":"126.536198ms","start":"2026-10-26T19:27:29.788452Z","end":"2026-10-26T19:27:29.914989Z","steps":["trace[1278235419] 'agreement among raft nodes before linearized reading' (duration: 126.398824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.915114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.93169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:29.915183Z","caller":"traceutil/trace.go:171","msg":"trace[789980189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5113; }","duration":"130.051354ms","start":"2026-10-26T19:27:29.785116Z","end":"2026-10-26T19:27:29.915168Z","steps":["trace[789980189] 'agreement among raft nodes before linearized reading' (duration: 129.92007ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.057473Z","caller":"traceutil/trace.go:171","msg":"trace[882791796] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"116.232427ms","start":"2026-10-26T19:27:29.941197Z","end":"2026-10-26T19:27:30.05743Z","steps":["trace[882791796] 'process raft request' (duration: 108.311951ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.057551Z","caller":"traceutil/trace.go:171","msg":"trace[69539722] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"116.183256ms","start":"2026-10-26T19:27:29.941333Z","end":"2026-10-26T19:27:30.057516Z","steps":["trace[69539722] 'process raft request' (duration: 116.014952ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.057725Z","caller":"traceutil/trace.go:171","msg":"trace[819906225] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"116.162255ms","start":"2026-10-26T19:27:29.941386Z","end":"2026-10-26T19:27:30.057548Z","steps":["trace[819906225] 'process raft request' (duration: 116.036772ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.057985Z","caller":"traceutil/trace.go:171","msg":"trace[1179764992] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"115.923248ms","start":"2026-10-26T19:27:29.94204Z","end":"2026-10-26T19:27:30.057963Z","steps":["trace[1179764992] 'process raft request' (duration: 115.464686ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.058209Z","caller":"traceutil/trace.go:171","msg":"trace[1839952975] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5128; }","duration":"114.846529ms","start":"2026-10-26T19:27:29.943338Z","end":"2026-10-26T19:27:30.058185Z","steps":["trace[1839952975] 'process raft request' (duration: 114.485ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.058348Z","caller":"traceutil/trace.go:171","msg":"trace[1601532083] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"114.690256ms","start":"2026-10-26T19:27:29.943646Z","end":"2026-10-26T19:27:30.058337Z","steps":["trace[1601532083] 'process raft request' (duration: 114.343316ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.058374Z","caller":"traceutil/trace.go:171","msg":"trace[630612178] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"114.692536ms","start":"2026-10-26T19:27:29.943647Z","end":"2026-10-26T19:27:30.058339Z","steps":["trace[630612178] 'process raft request' (duration: 114.586603ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.05844Z","caller":"traceutil/trace.go:171","msg":"trace[615040739] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"114.770538ms","start":"2026-10-26T19:27:29.943646Z","end":"2026-10-26T19:27:30.058416Z","steps":["trace[615040739] 'process raft request' (duration: 114.50486ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.058753Z","caller":"traceutil/trace.go:171","msg":"trace[887711393] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"114.941803ms","start":"2026-10-26T19:27:29.943797Z","end":"2026-10-26T19:27:30.058739Z","steps":["trace[887711393] 'process raft request' (duration: 114.49338ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.058774Z","caller":"traceutil/trace.go:171","msg":"trace[1340013263] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5317; }","duration":"117.4123ms","start":"2026-10-26T19:27:29.941332Z","end":"2026-10-26T19:27:30.058744Z","steps":["trace[1340013263] 'read index received' (duration: 19.546353ms)","trace[1340013263] 'applied index is now lower than readState.Index' (duration: 97.864717ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:30.058955Z","caller":"traceutil/trace.go:171","msg":"trace[977793359] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"109.265008ms","start":"2026-10-26T19:27:29.949674Z","end":"2026-10-26T19:27:30.058939Z","steps":["trace[977793359] 'process raft request' (duration: 108.948489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:30.059188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.428319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/loki-gateway\" ","response":"range_response_count:1 size:4616"} {"level":"warn","ts":"2026-10-26T19:27:30.059198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.834511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-26T19:27:30.059244Z","caller":"traceutil/trace.go:171","msg":"trace[505250474] range","detail":"{range_begin:/registry/deployments/monitoring/loki-gateway; range_end:; response_count:1; response_revision:5133; }","duration":"114.537911ms","start":"2026-10-26T19:27:29.94469Z","end":"2026-10-26T19:27:30.059228Z","steps":["trace[505250474] 'agreement among raft nodes before linearized reading' (duration: 114.262353ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.059299Z","caller":"traceutil/trace.go:171","msg":"trace[1878064057] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5133; }","duration":"117.993705ms","start":"2026-10-26T19:27:29.941279Z","end":"2026-10-26T19:27:30.059273Z","steps":["trace[1878064057] 'agreement among raft nodes before linearized reading' (duration: 117.657916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:30.059456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.345417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:30.059521Z","caller":"traceutil/trace.go:171","msg":"trace[1896956871] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5133; }","duration":"110.46263ms","start":"2026-10-26T19:27:29.949038Z","end":"2026-10-26T19:27:30.059501Z","steps":["trace[1896956871] 'agreement among raft nodes before linearized reading' (duration: 110.339917ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.772747Z","caller":"traceutil/trace.go:171","msg":"trace[1568609944] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"201.589513ms","start":"2026-10-26T19:27:30.571128Z","end":"2026-10-26T19:27:30.772717Z","steps":["trace[1568609944] 'process raft request' (duration: 200.670888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:30.773031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.687995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7\" ","response":"range_response_count:1 size:9769"} {"level":"info","ts":"2026-10-26T19:27:30.773109Z","caller":"traceutil/trace.go:171","msg":"trace[999753406] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7; range_end:; response_count:1; response_revision:5152; }","duration":"187.815088ms","start":"2026-10-26T19:27:30.58528Z","end":"2026-10-26T19:27:30.773095Z","steps":["trace[999753406] 'agreement among raft nodes before linearized reading' (duration: 187.52688ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:30.772675Z","caller":"traceutil/trace.go:171","msg":"trace[402310734] linearizableReadLoop","detail":"{readStateIndex:5346; appliedIndex:5344; }","duration":"187.339775ms","start":"2026-10-26T19:27:30.585318Z","end":"2026-10-26T19:27:30.772658Z","steps":["trace[402310734] 'read index received' (duration: 2.997352ms)","trace[402310734] 'applied index is now lower than readState.Index' (duration: 184.341663ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:30.773285Z","caller":"traceutil/trace.go:171","msg":"trace[808437179] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"186.013619ms","start":"2026-10-26T19:27:30.587216Z","end":"2026-10-26T19:27:30.773229Z","steps":["trace[808437179] 'process raft request' (duration: 185.391572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:30.773553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.993811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:27:30.773623Z","caller":"traceutil/trace.go:171","msg":"trace[1777951201] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5152; }","duration":"145.102174ms","start":"2026-10-26T19:27:30.628504Z","end":"2026-10-26T19:27:30.773606Z","steps":["trace[1777951201] 'agreement among raft nodes before linearized reading' (duration: 144.926649ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:33.026299Z","caller":"traceutil/trace.go:171","msg":"trace[177087910] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"409.661133ms","start":"2026-10-26T19:27:32.616609Z","end":"2026-10-26T19:27:33.02627Z","steps":["trace[177087910] 'process raft request' (duration: 409.459528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:33.026515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:32.616588Z","time spent":"409.792976ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:27:33.058215Z","caller":"traceutil/trace.go:171","msg":"trace[1322274040] linearizableReadLoop","detail":"{readStateIndex:5395; appliedIndex:5393; }","duration":"416.990203ms","start":"2026-10-26T19:27:32.641194Z","end":"2026-10-26T19:27:33.058184Z","steps":["trace[1322274040] 'read index received' (duration: 384.925189ms)","trace[1322274040] 'applied index is now lower than readState.Index' (duration: 32.063614ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:33.058294Z","caller":"traceutil/trace.go:171","msg":"trace[682761386] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"440.201405ms","start":"2026-10-26T19:27:32.618048Z","end":"2026-10-26T19:27:33.058249Z","steps":["trace[682761386] 'process raft request' (duration: 439.909007ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:33.058355Z","caller":"traceutil/trace.go:171","msg":"trace[630864262] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"346.325907ms","start":"2026-10-26T19:27:32.712005Z","end":"2026-10-26T19:27:33.058331Z","steps":["trace[630864262] 'process raft request' (duration: 346.132112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:33.058454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.234229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-013fbaf6-c6b9-4a03-a1b6-57fdc7c1db74\" ","response":"range_response_count:1 size:3721"} {"level":"warn","ts":"2026-10-26T19:27:33.058491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:32.618035Z","time spent":"440.35166ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:27:33.058536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:32.711973Z","time spent":"346.458131ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:27:33.058506Z","caller":"traceutil/trace.go:171","msg":"trace[2143393351] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-013fbaf6-c6b9-4a03-a1b6-57fdc7c1db74; range_end:; response_count:1; response_revision:5200; }","duration":"417.371713ms","start":"2026-10-26T19:27:32.641118Z","end":"2026-10-26T19:27:33.05849Z","steps":["trace[2143393351] 'agreement among raft nodes before linearized reading' (duration: 417.23073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:33.058702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:32.641079Z","time spent":"417.59987ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-013fbaf6-c6b9-4a03-a1b6-57fdc7c1db74\" "} {"level":"warn","ts":"2026-10-26T19:27:33.058722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.57238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-10-26T19:27:33.058744Z","caller":"traceutil/trace.go:171","msg":"trace[315570860] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6dhg7-tls; range_end:; response_count:1; response_revision:5200; }","duration":"293.640841ms","start":"2026-10-26T19:27:32.765097Z","end":"2026-10-26T19:27:33.058738Z","steps":["trace[315570860] 'agreement among raft nodes before linearized reading' (duration: 293.565609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:33.058853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.585855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:33.058973Z","caller":"traceutil/trace.go:171","msg":"trace[1049831937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5200; }","duration":"393.77203ms","start":"2026-10-26T19:27:32.665179Z","end":"2026-10-26T19:27:33.058951Z","steps":["trace[1049831937] 'agreement among raft nodes before linearized reading' (duration: 393.593355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:33.059059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:32.66515Z","time spent":"393.899574ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T19:27:33.059217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.080079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787160"} {"level":"info","ts":"2026-10-26T19:27:33.059282Z","caller":"traceutil/trace.go:171","msg":"trace[264959114] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5200; }","duration":"412.189442ms","start":"2026-10-26T19:27:32.647077Z","end":"2026-10-26T19:27:33.059266Z","steps":["trace[264959114] 'agreement among raft nodes before linearized reading' (duration: 411.463712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:33.059335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:32.64705Z","time spent":"412.271864ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":787183,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-10-26T19:27:35.104946Z","caller":"traceutil/trace.go:171","msg":"trace[1363250093] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"115.328293ms","start":"2026-10-26T19:27:34.989588Z","end":"2026-10-26T19:27:35.104917Z","steps":["trace[1363250093] 'process raft request' (duration: 115.131527ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:35.105006Z","caller":"traceutil/trace.go:171","msg":"trace[1904103767] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"114.257574ms","start":"2026-10-26T19:27:34.990731Z","end":"2026-10-26T19:27:35.104989Z","steps":["trace[1904103767] 'process raft request' (duration: 114.12612ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:35.104958Z","caller":"traceutil/trace.go:171","msg":"trace[37722508] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"115.112777ms","start":"2026-10-26T19:27:34.989818Z","end":"2026-10-26T19:27:35.104931Z","steps":["trace[37722508] 'process raft request' (duration: 115.014954ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:46.33891Z","caller":"traceutil/trace.go:171","msg":"trace[769126777] linearizableReadLoop","detail":"{readStateIndex:5651; appliedIndex:5650; }","duration":"100.593801ms","start":"2026-10-26T19:27:46.238298Z","end":"2026-10-26T19:27:46.338892Z","steps":["trace[769126777] 'read index received' (duration: 100.457217ms)","trace[769126777] 'applied index is now lower than readState.Index' (duration: 135.874µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:46.33905Z","caller":"traceutil/trace.go:171","msg":"trace[1756027170] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"103.953083ms","start":"2026-10-26T19:27:46.235041Z","end":"2026-10-26T19:27:46.338994Z","steps":["trace[1756027170] 'process raft request' (duration: 103.712786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:46.339251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.93887ms","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:27:46.339284Z","caller":"traceutil/trace.go:171","msg":"trace[1490818840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5446; }","duration":"101.008292ms","start":"2026-10-26T19:27:46.238268Z","end":"2026-10-26T19:27:46.339277Z","steps":["trace[1490818840] 'agreement among raft nodes before linearized reading' (duration: 100.893629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:46.339433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.937091ms","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:27:46.339497Z","caller":"traceutil/trace.go:171","msg":"trace[12583115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5446; }","duration":"101.042073ms","start":"2026-10-26T19:27:46.23844Z","end":"2026-10-26T19:27:46.339482Z","steps":["trace[12583115] 'agreement among raft nodes before linearized reading' (duration: 100.898559ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:54.525326Z","caller":"traceutil/trace.go:171","msg":"trace[245924124] linearizableReadLoop","detail":"{readStateIndex:5795; appliedIndex:5794; }","duration":"133.078156ms","start":"2026-10-26T19:27:54.392226Z","end":"2026-10-26T19:27:54.525304Z","steps":["trace[245924124] 'read index received' (duration: 97.625501ms)","trace[245924124] 'applied index is now lower than readState.Index' (duration: 35.451835ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:54.525408Z","caller":"traceutil/trace.go:171","msg":"trace[1845729206] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"143.949872ms","start":"2026-10-26T19:27:54.381416Z","end":"2026-10-26T19:27:54.525365Z","steps":["trace[1845729206] 'process raft request' (duration: 108.510917ms)","trace[1845729206] 'compare' (duration: 35.139227ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:54.52553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.284352ms","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:27:54.525563Z","caller":"traceutil/trace.go:171","msg":"trace[231732784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5588; }","duration":"133.381484ms","start":"2026-10-26T19:27:54.392171Z","end":"2026-10-26T19:27:54.525552Z","steps":["trace[231732784] 'agreement among raft nodes before linearized reading' (duration: 133.256301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:54.52607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.763365ms","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-26T19:27:54.526171Z","caller":"traceutil/trace.go:171","msg":"trace[18296310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5588; }","duration":"133.888569ms","start":"2026-10-26T19:27:54.39226Z","end":"2026-10-26T19:27:54.526148Z","steps":["trace[18296310] 'agreement among raft nodes before linearized reading' (duration: 133.19324ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:54.632115Z","caller":"traceutil/trace.go:171","msg":"trace[1258740501] transaction","detail":"{read_only:false; response_revision:5589; number_of_response:1; }","duration":"102.537124ms","start":"2026-10-26T19:27:54.529564Z","end":"2026-10-26T19:27:54.632101Z","steps":["trace[1258740501] 'process raft request' (duration: 98.427472ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:54.632391Z","caller":"traceutil/trace.go:171","msg":"trace[962987134] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"102.316728ms","start":"2026-10-26T19:27:54.530012Z","end":"2026-10-26T19:27:54.632329Z","steps":["trace[962987134] 'process raft request' (duration: 102.062261ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:29.573106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-10-26T19:28:29.65119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"76.540156ms","hash":118786276,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":32600064,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T19:28:29.651268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":118786276,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:28:55.188231Z","caller":"traceutil/trace.go:171","msg":"trace[719905237] transaction","detail":"{read_only:false; response_revision:6167; number_of_response:1; }","duration":"118.147829ms","start":"2026-10-26T19:28:55.070043Z","end":"2026-10-26T19:28:55.188191Z","steps":["trace[719905237] 'process raft request' (duration: 57.283351ms)","trace[719905237] 'compare' (duration: 60.663723ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:29.580792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5921} {"level":"info","ts":"2026-10-26T19:33:29.669969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5921,"took":"87.448417ms","hash":2194972169,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":26247168,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:33:29.670018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2194972169,"revision":5921,"compact-revision":2967} {"level":"info","ts":"2026-10-26T19:33:51.734252Z","caller":"traceutil/trace.go:171","msg":"trace[1569680360] linearizableReadLoop","detail":"{readStateIndex:8549; appliedIndex:8548; }","duration":"123.153415ms","start":"2026-10-26T19:33:51.611067Z","end":"2026-10-26T19:33:51.734221Z","steps":["trace[1569680360] 'read index received' (duration: 57.30076ms)","trace[1569680360] 'applied index is now lower than readState.Index' (duration: 65.851645ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:51.734446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.362151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:33:51.734482Z","caller":"traceutil/trace.go:171","msg":"trace[332798884] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8255; }","duration":"123.479754ms","start":"2026-10-26T19:33:51.610993Z","end":"2026-10-26T19:33:51.734473Z","steps":["trace[332798884] 'agreement among raft nodes before linearized reading' (duration: 123.32872ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:51.734508Z","caller":"traceutil/trace.go:171","msg":"trace[1887201814] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"132.210863ms","start":"2026-10-26T19:33:51.602268Z","end":"2026-10-26T19:33:51.734479Z","steps":["trace[1887201814] 'process raft request' (duration: 66.04239ms)","trace[1887201814] 'compare' (duration: 64.326132ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:05.568541Z","caller":"traceutil/trace.go:171","msg":"trace[1569246937] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"123.276828ms","start":"2026-10-26T19:34:05.445238Z","end":"2026-10-26T19:34:05.568514Z","steps":["trace[1569246937] 'process raft request' (duration: 122.660871ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:16.736974Z","caller":"traceutil/trace.go:171","msg":"trace[399312082] linearizableReadLoop","detail":"{readStateIndex:8821; appliedIndex:8820; }","duration":"331.126895ms","start":"2026-10-26T19:34:16.40582Z","end":"2026-10-26T19:34:16.736947Z","steps":["trace[399312082] 'read index received' (duration: 330.913739ms)","trace[399312082] 'applied index is now lower than readState.Index' (duration: 212.096µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:16.737075Z","caller":"traceutil/trace.go:171","msg":"trace[164889019] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"375.190713ms","start":"2026-10-26T19:34:16.361853Z","end":"2026-10-26T19:34:16.737044Z","steps":["trace[164889019] 'process raft request' (duration: 374.914955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:16.737181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.347381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:34:16.737202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:16.36184Z","time spent":"375.296895ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:34:16.737245Z","caller":"traceutil/trace.go:171","msg":"trace[1396387531] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8521; }","duration":"331.472534ms","start":"2026-10-26T19:34:16.405756Z","end":"2026-10-26T19:34:16.737228Z","steps":["trace[1396387531] 'agreement among raft nodes before linearized reading' (duration: 331.354341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:16.737306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:16.405696Z","time spent":"331.588947ms","remote":"127.0.0.1:34936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:34:18.330055Z","caller":"traceutil/trace.go:171","msg":"trace[1510176574] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"163.393238ms","start":"2026-10-26T19:34:18.166616Z","end":"2026-10-26T19:34:18.330009Z","steps":["trace[1510176574] 'process raft request' (duration: 81.851983ms)","trace[1510176574] 'compare' (duration: 81.01574ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:18.330114Z","caller":"traceutil/trace.go:171","msg":"trace[317636103] linearizableReadLoop","detail":"{readStateIndex:8830; appliedIndex:8829; }","duration":"130.221319ms","start":"2026-10-26T19:34:18.199866Z","end":"2026-10-26T19:34:18.330087Z","steps":["trace[317636103] 'read index received' (duration: 48.53091ms)","trace[317636103] 'applied index is now lower than readState.Index' (duration: 81.687579ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:18.354411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.518185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:34:18.354524Z","caller":"traceutil/trace.go:171","msg":"trace[1193231494] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8530; }","duration":"154.69932ms","start":"2026-10-26T19:34:18.199797Z","end":"2026-10-26T19:34:18.354497Z","steps":["trace[1193231494] 'agreement among raft nodes before linearized reading' (duration: 130.369243ms)","trace[1193231494] 'count revisions from in-memory index tree' (duration: 24.164392ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:45.672828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.652642ms","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:34:45.672939Z","caller":"traceutil/trace.go:171","msg":"trace[65748903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8729; }","duration":"203.798325ms","start":"2026-10-26T19:34:45.469115Z","end":"2026-10-26T19:34:45.672914Z","steps":["trace[65748903] 'count revisions from in-memory index tree' (duration: 203.577659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:45.672961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.342423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lbvnb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:34:45.673022Z","caller":"traceutil/trace.go:171","msg":"trace[1739226781] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lbvnb; range_end:; response_count:1; response_revision:8729; }","duration":"388.444665ms","start":"2026-10-26T19:34:45.284559Z","end":"2026-10-26T19:34:45.673003Z","steps":["trace[1739226781] 'range keys from in-memory index tree' (duration: 388.149168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:45.673063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:45.284537Z","time spent":"388.514658ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-lbvnb\" "} {"level":"warn","ts":"2026-10-26T19:34:45.673276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.043136ms","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:34:45.673379Z","caller":"traceutil/trace.go:171","msg":"trace[1170363750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8729; }","duration":"453.19421ms","start":"2026-10-26T19:34:45.220164Z","end":"2026-10-26T19:34:45.673358Z","steps":["trace[1170363750] 'range keys from in-memory index tree' (duration: 452.926463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:45.673425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:45.220145Z","time spent":"453.269393ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T19:34:45.673465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.882109ms","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:34:45.673522Z","caller":"traceutil/trace.go:171","msg":"trace[218548313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8729; }","duration":"430.967461ms","start":"2026-10-26T19:34:45.242541Z","end":"2026-10-26T19:34:45.673508Z","steps":["trace[218548313] 'range keys from in-memory index tree' (duration: 430.752616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:45.67358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:45.242524Z","time spent":"431.046353ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T19:34:45.805522Z","caller":"traceutil/trace.go:171","msg":"trace[1251496327] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"127.140374ms","start":"2026-10-26T19:34:45.678325Z","end":"2026-10-26T19:34:45.805466Z","steps":["trace[1251496327] 'process raft request' (duration: 126.97355ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:45.80728Z","caller":"traceutil/trace.go:171","msg":"trace[1996209257] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"128.866491ms","start":"2026-10-26T19:34:45.678393Z","end":"2026-10-26T19:34:45.807259Z","steps":["trace[1996209257] 'process raft request' (duration: 128.774559ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:53.220643Z","caller":"traceutil/trace.go:171","msg":"trace[2021258541] linearizableReadLoop","detail":"{readStateIndex:9080; appliedIndex:9079; }","duration":"100.132215ms","start":"2026-10-26T19:34:53.12047Z","end":"2026-10-26T19:34:53.220602Z","steps":["trace[2021258541] 'read index received' (duration: 99.95747ms)","trace[2021258541] 'applied index is now lower than readState.Index' (duration: 173.375µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:53.220951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.446673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-013fbaf6-c6b9-4a03-a1b6-57fdc7c1db74\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-10-26T19:34:53.220991Z","caller":"traceutil/trace.go:171","msg":"trace[589000666] range","detail":"{range_begin:/registry/persistentvolumes/pvc-013fbaf6-c6b9-4a03-a1b6-57fdc7c1db74; range_end:; response_count:1; response_revision:8770; }","duration":"100.567946ms","start":"2026-10-26T19:34:53.120413Z","end":"2026-10-26T19:34:53.220981Z","steps":["trace[589000666] 'agreement among raft nodes before linearized reading' (duration: 100.3481ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:53.258043Z","caller":"traceutil/trace.go:171","msg":"trace[1414011788] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"128.860201ms","start":"2026-10-26T19:34:53.129057Z","end":"2026-10-26T19:34:53.257917Z","steps":["trace[1414011788] 'process raft request' (duration: 128.534042ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:59.74059Z","caller":"traceutil/trace.go:171","msg":"trace[1622876798] linearizableReadLoop","detail":"{readStateIndex:9156; appliedIndex:9154; }","duration":"108.816962ms","start":"2026-10-26T19:34:59.631738Z","end":"2026-10-26T19:34:59.740555Z","steps":["trace[1622876798] 'read index received' (duration: 40.179051ms)","trace[1622876798] 'applied index is now lower than readState.Index' (duration: 68.636671ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:59.740782Z","caller":"traceutil/trace.go:171","msg":"trace[303332072] transaction","detail":"{read_only:false; response_revision:8842; number_of_response:1; }","duration":"112.138013ms","start":"2026-10-26T19:34:59.628624Z","end":"2026-10-26T19:34:59.740762Z","steps":["trace[303332072] 'process raft request' (duration: 111.732402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:59.741208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.440079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-26T19:34:59.741259Z","caller":"traceutil/trace.go:171","msg":"trace[754051811] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:8842; }","duration":"109.555903ms","start":"2026-10-26T19:34:59.63169Z","end":"2026-10-26T19:34:59.741246Z","steps":["trace[754051811] 'agreement among raft nodes before linearized reading' (duration: 109.421119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:00.546248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.580919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:35:00.546377Z","caller":"traceutil/trace.go:171","msg":"trace[1437699213] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8850; }","duration":"180.761843ms","start":"2026-10-26T19:35:00.365575Z","end":"2026-10-26T19:35:00.546337Z","steps":["trace[1437699213] 'range keys from in-memory index tree' (duration: 180.379803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:00.546513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.181775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:35:00.546591Z","caller":"traceutil/trace.go:171","msg":"trace[1731615350] transaction","detail":"{read_only:false; response_revision:8851; number_of_response:1; }","duration":"141.121918ms","start":"2026-10-26T19:35:00.405454Z","end":"2026-10-26T19:35:00.546576Z","steps":["trace[1731615350] 'process raft request' (duration: 36.802629ms)","trace[1731615350] 'compare' (duration: 103.860286ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:35:00.546927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.248778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6vg2l\" ","response":"range_response_count:1 size:4746"} {"level":"info","ts":"2026-10-26T19:35:00.547181Z","caller":"traceutil/trace.go:171","msg":"trace[1690465488] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6vg2l; range_end:; response_count:1; response_revision:8850; }","duration":"142.549036ms","start":"2026-10-26T19:35:00.404618Z","end":"2026-10-26T19:35:00.547167Z","steps":["trace[1690465488] 'range keys from in-memory index tree' (duration: 141.279842ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:00.705245Z","caller":"traceutil/trace.go:171","msg":"trace[1594417128] linearizableReadLoop","detail":"{readStateIndex:9166; appliedIndex:9165; }","duration":"155.582303ms","start":"2026-10-26T19:35:00.549631Z","end":"2026-10-26T19:35:00.705213Z","steps":["trace[1594417128] 'read index received' (duration: 109.006747ms)","trace[1594417128] 'applied index is now lower than readState.Index' (duration: 46.567205ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:35:00.705591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.768198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:35:00.705631Z","caller":"traceutil/trace.go:171","msg":"trace[1993347143] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8852; }","duration":"156.035746ms","start":"2026-10-26T19:35:00.549584Z","end":"2026-10-26T19:35:00.70562Z","steps":["trace[1993347143] 'agreement among raft nodes before linearized reading' (duration: 155.741778ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:00.705912Z","caller":"traceutil/trace.go:171","msg":"trace[736104128] transaction","detail":"{read_only:false; response_revision:8852; number_of_response:1; }","duration":"286.517931ms","start":"2026-10-26T19:35:00.419382Z","end":"2026-10-26T19:35:00.7059Z","steps":["trace[736104128] 'process raft request' (duration: 239.240867ms)","trace[736104128] 'compare' (duration: 46.448352ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:35:00.71231Z","caller":"traceutil/trace.go:171","msg":"trace[524749831] transaction","detail":"{read_only:false; response_revision:8853; number_of_response:1; }","duration":"133.924179ms","start":"2026-10-26T19:35:00.578359Z","end":"2026-10-26T19:35:00.712283Z","steps":["trace[524749831] 'process raft request' (duration: 133.20568ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:20.7193Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248a.wal"} {"level":"info","ts":"2026-10-26T19:36:19.339916Z","caller":"traceutil/trace.go:171","msg":"trace[715334510] transaction","detail":"{read_only:false; response_revision:9549; number_of_response:1; }","duration":"173.460593ms","start":"2026-10-26T19:36:19.166434Z","end":"2026-10-26T19:36:19.339895Z","steps":["trace[715334510] 'process raft request' (duration: 173.295698ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:26.215086Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"548b318b3528a14c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:36:26.221669Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:36:26.221807Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T19:38:29.597235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8098} {"level":"info","ts":"2026-10-26T19:38:29.669565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8098,"took":"70.489319ms","hash":3708963431,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":28983296,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T19:38:29.669629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3708963431,"revision":8098,"compact-revision":5921} {"level":"info","ts":"2026-10-26T19:39:42.822283Z","caller":"traceutil/trace.go:171","msg":"trace[1980824812] transaction","detail":"{read_only:false; response_revision:11380; number_of_response:1; }","duration":"114.397009ms","start":"2026-10-26T19:39:42.707847Z","end":"2026-10-26T19:39:42.822244Z","steps":["trace[1980824812] 'process raft request' (duration: 114.171923ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:39:57.031812Z","caller":"traceutil/trace.go:171","msg":"trace[318749542] transaction","detail":"{read_only:false; response_revision:11459; number_of_response:1; }","duration":"139.036505ms","start":"2026-10-26T19:39:56.892733Z","end":"2026-10-26T19:39:57.031769Z","steps":["trace[318749542] 'process raft request' (duration: 138.812758ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:39:57.701468Z","caller":"traceutil/trace.go:171","msg":"trace[134059219] transaction","detail":"{read_only:false; response_revision:11463; number_of_response:1; }","duration":"124.628575ms","start":"2026-10-26T19:39:57.576808Z","end":"2026-10-26T19:39:57.701436Z","steps":["trace[134059219] 'process raft request' (duration: 124.354198ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:43:29.604202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10570} {"level":"info","ts":"2026-10-26T19:43:29.697163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10570,"took":"89.245929ms","hash":2955080921,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":27418624,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T19:43:29.697227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2955080921,"revision":10570,"compact-revision":8098} {"level":"info","ts":"2026-10-26T19:45:50.115698Z","caller":"traceutil/trace.go:171","msg":"trace[606433265] linearizableReadLoop","detail":"{readStateIndex:14338; appliedIndex:14337; }","duration":"141.00079ms","start":"2026-10-26T19:45:49.974635Z","end":"2026-10-26T19:45:50.115636Z","steps":["trace[606433265] 'read index received' (duration: 140.705592ms)","trace[606433265] 'applied index is now lower than readState.Index' (duration: 294.168µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:45:50.115781Z","caller":"traceutil/trace.go:171","msg":"trace[150834179] transaction","detail":"{read_only:false; response_revision:13864; number_of_response:1; }","duration":"174.481269ms","start":"2026-10-26T19:45:49.94125Z","end":"2026-10-26T19:45:50.115731Z","steps":["trace[150834179] 'process raft request' (duration: 174.171471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:45:50.117072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.293597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-26T19:45:50.117151Z","caller":"traceutil/trace.go:171","msg":"trace[12571038] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13864; }","duration":"142.551181ms","start":"2026-10-26T19:45:49.974582Z","end":"2026-10-26T19:45:50.117133Z","steps":["trace[12571038] 'agreement among raft nodes before linearized reading' (duration: 141.192254ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:45:51.043408Z","caller":"traceutil/trace.go:171","msg":"trace[294447914] transaction","detail":"{read_only:false; response_revision:13869; number_of_response:1; }","duration":"114.452198ms","start":"2026-10-26T19:45:50.928927Z","end":"2026-10-26T19:45:51.04338Z","steps":["trace[294447914] 'process raft request' (duration: 114.284584ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:15.878778Z","caller":"traceutil/trace.go:171","msg":"trace[774150815] linearizableReadLoop","detail":"{readStateIndex:14477; appliedIndex:14476; }","duration":"216.406167ms","start":"2026-10-26T19:46:15.662353Z","end":"2026-10-26T19:46:15.878759Z","steps":["trace[774150815] 'read index received' (duration: 216.364136ms)","trace[774150815] 'applied index is now lower than readState.Index' (duration: 41.101µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:15.878888Z","caller":"traceutil/trace.go:171","msg":"trace[1226148446] transaction","detail":"{read_only:false; response_revision:13998; number_of_response:1; }","duration":"218.540115ms","start":"2026-10-26T19:46:15.660334Z","end":"2026-10-26T19:46:15.878874Z","steps":["trace[1226148446] 'process raft request' (duration: 218.280908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:15.878953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.589032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:15.879058Z","caller":"traceutil/trace.go:171","msg":"trace[1743129975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13998; }","duration":"216.739226ms","start":"2026-10-26T19:46:15.662299Z","end":"2026-10-26T19:46:15.879039Z","steps":["trace[1743129975] 'agreement among raft nodes before linearized reading' (duration: 216.598792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:15.894172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.568408ms","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:46:15.89424Z","caller":"traceutil/trace.go:171","msg":"trace[1307112192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13999; }","duration":"108.685942ms","start":"2026-10-26T19:46:15.785537Z","end":"2026-10-26T19:46:15.894223Z","steps":["trace[1307112192] 'agreement among raft nodes before linearized reading' (duration: 108.524728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:15.894502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.128063ms","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-26T19:46:15.894584Z","caller":"traceutil/trace.go:171","msg":"trace[374995553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13999; }","duration":"215.243266ms","start":"2026-10-26T19:46:15.679322Z","end":"2026-10-26T19:46:15.894565Z","steps":["trace[374995553] 'agreement among raft nodes before linearized reading' (duration: 214.989819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:32.088076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.319926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:46:32.088174Z","caller":"traceutil/trace.go:171","msg":"trace[1337668751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14094; }","duration":"131.47187ms","start":"2026-10-26T19:46:31.956679Z","end":"2026-10-26T19:46:32.088151Z","steps":["trace[1337668751] 'range keys from in-memory index tree' (duration: 131.126521ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:32.088296Z","caller":"traceutil/trace.go:171","msg":"trace[1309230742] transaction","detail":"{read_only:false; response_revision:14095; number_of_response:1; }","duration":"128.173672ms","start":"2026-10-26T19:46:31.960092Z","end":"2026-10-26T19:46:32.088266Z","steps":["trace[1309230742] 'process raft request' (duration: 104.128069ms)","trace[1309230742] 'compare' (duration: 23.689333ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:32.257957Z","caller":"traceutil/trace.go:171","msg":"trace[1606328945] transaction","detail":"{read_only:false; response_revision:14098; number_of_response:1; }","duration":"164.527188ms","start":"2026-10-26T19:46:32.0934Z","end":"2026-10-26T19:46:32.257927Z","steps":["trace[1606328945] 'process raft request' (duration: 164.153248ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:32.258089Z","caller":"traceutil/trace.go:171","msg":"trace[1050826035] transaction","detail":"{read_only:false; response_revision:14099; number_of_response:1; }","duration":"164.145968ms","start":"2026-10-26T19:46:32.093915Z","end":"2026-10-26T19:46:32.258061Z","steps":["trace[1050826035] 'process raft request' (duration: 163.945922ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:32.257964Z","caller":"traceutil/trace.go:171","msg":"trace[160928199] transaction","detail":"{read_only:false; response_revision:14097; number_of_response:1; }","duration":"164.688332ms","start":"2026-10-26T19:46:32.093242Z","end":"2026-10-26T19:46:32.257931Z","steps":["trace[160928199] 'process raft request' (duration: 164.078476ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:33.241309Z","caller":"traceutil/trace.go:171","msg":"trace[541555646] transaction","detail":"{read_only:false; response_revision:14103; number_of_response:1; }","duration":"260.226197ms","start":"2026-10-26T19:46:32.981017Z","end":"2026-10-26T19:46:33.241243Z","steps":["trace[541555646] 'process raft request' (duration: 259.503378ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:33.459997Z","caller":"traceutil/trace.go:171","msg":"trace[638124234] linearizableReadLoop","detail":"{readStateIndex:14587; appliedIndex:14586; }","duration":"190.910475ms","start":"2026-10-26T19:46:33.269037Z","end":"2026-10-26T19:46:33.459947Z","steps":["trace[638124234] 'read index received' (duration: 151.831434ms)","trace[638124234] 'applied index is now lower than readState.Index' (duration: 39.077351ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:33.460233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.174522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T19:46:33.460258Z","caller":"traceutil/trace.go:171","msg":"trace[1583589050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14103; }","duration":"191.269525ms","start":"2026-10-26T19:46:33.26898Z","end":"2026-10-26T19:46:33.46025Z","steps":["trace[1583589050] 'agreement among raft nodes before linearized reading' (duration: 191.0993ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:34.706071Z","caller":"traceutil/trace.go:171","msg":"trace[1713023675] transaction","detail":"{read_only:false; response_revision:14120; number_of_response:1; }","duration":"141.131633ms","start":"2026-10-26T19:46:34.564904Z","end":"2026-10-26T19:46:34.706036Z","steps":["trace[1713023675] 'process raft request' (duration: 140.848005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:44.795333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.691432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:46:44.795456Z","caller":"traceutil/trace.go:171","msg":"trace[1343139709] linearizableReadLoop","detail":"{readStateIndex:14769; appliedIndex:14768; }","duration":"133.516946ms","start":"2026-10-26T19:46:44.661923Z","end":"2026-10-26T19:46:44.79544Z","steps":["trace[1343139709] 'read index received' (duration: 24.451µs)","trace[1343139709] 'applied index is now lower than readState.Index' (duration: 133.490995ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:44.795525Z","caller":"traceutil/trace.go:171","msg":"trace[14669595] transaction","detail":"{read_only:false; response_revision:14281; number_of_response:1; }","duration":"214.545316ms","start":"2026-10-26T19:46:44.580939Z","end":"2026-10-26T19:46:44.795485Z","steps":["trace[14669595] 'process raft request' (duration: 52.611849ms)","trace[14669595] 'compare' (duration: 161.566527ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:44.795589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.64456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:44.795695Z","caller":"traceutil/trace.go:171","msg":"trace[1356566821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14281; }","duration":"133.760563ms","start":"2026-10-26T19:46:44.661896Z","end":"2026-10-26T19:46:44.795657Z","steps":["trace[1356566821] 'agreement among raft nodes before linearized reading' (duration: 133.617239ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:45.112864Z","caller":"traceutil/trace.go:171","msg":"trace[2001163183] transaction","detail":"{read_only:false; response_revision:14282; number_of_response:1; }","duration":"214.372842ms","start":"2026-10-26T19:46:44.898464Z","end":"2026-10-26T19:46:45.112837Z","steps":["trace[2001163183] 'process raft request' (duration: 164.654182ms)","trace[2001163183] 'compare' (duration: 49.397482ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:45.121174Z","caller":"traceutil/trace.go:171","msg":"trace[742155456] linearizableReadLoop","detail":"{readStateIndex:14771; appliedIndex:14769; }","duration":"139.794026ms","start":"2026-10-26T19:46:44.981362Z","end":"2026-10-26T19:46:45.121156Z","steps":["trace[742155456] 'read index received' (duration: 81.867673ms)","trace[742155456] 'applied index is now lower than readState.Index' (duration: 57.925713ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:45.121215Z","caller":"traceutil/trace.go:171","msg":"trace[65835094] transaction","detail":"{read_only:false; response_revision:14283; number_of_response:1; }","duration":"215.892563ms","start":"2026-10-26T19:46:44.905295Z","end":"2026-10-26T19:46:45.121188Z","steps":["trace[65835094] 'process raft request' (duration: 215.78322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:45.121291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.91812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:46:45.121319Z","caller":"traceutil/trace.go:171","msg":"trace[339800781] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:14283; }","duration":"139.993282ms","start":"2026-10-26T19:46:44.981318Z","end":"2026-10-26T19:46:45.121311Z","steps":["trace[339800781] 'agreement among raft nodes before linearized reading' (duration: 139.89977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:45.64124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11622842282190850003,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T19:46:46.101549Z","caller":"traceutil/trace.go:171","msg":"trace[1161241211] transaction","detail":"{read_only:false; response_revision:14284; number_of_response:1; }","duration":"963.98617ms","start":"2026-10-26T19:46:45.137516Z","end":"2026-10-26T19:46:46.101502Z","steps":["trace[1161241211] 'process raft request' (duration: 963.831625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:46.101741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.137495Z","time spent":"964.124743ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:46:46.114464Z","caller":"traceutil/trace.go:171","msg":"trace[1859896283] linearizableReadLoop","detail":"{readStateIndex:14773; appliedIndex:14771; }","duration":"973.863888ms","start":"2026-10-26T19:46:45.140575Z","end":"2026-10-26T19:46:46.114438Z","steps":["trace[1859896283] 'read index received' (duration: 960.727621ms)","trace[1859896283] 'applied index is now lower than readState.Index' (duration: 13.134967ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:46.114568Z","caller":"traceutil/trace.go:171","msg":"trace[2058831414] transaction","detail":"{read_only:false; response_revision:14285; number_of_response:1; }","duration":"818.154468ms","start":"2026-10-26T19:46:45.296367Z","end":"2026-10-26T19:46:46.114521Z","steps":["trace[2058831414] 'process raft request' (duration: 817.921972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:46.114765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.29634Z","time spent":"818.303663ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:46:46.114769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.167476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-m92mk.18e22c0575d53782\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-10-26T19:46:46.114824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.370688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:46:46.114825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.454149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:46:46.115095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.062886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-30e55e25-b769-49e8-bf52-9624b406c6d2\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-10-26T19:46:46.114877Z","caller":"traceutil/trace.go:171","msg":"trace[262056409] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:14285; }","duration":"676.45727ms","start":"2026-10-26T19:46:45.438404Z","end":"2026-10-26T19:46:46.114861Z","steps":["trace[262056409] 'agreement among raft nodes before linearized reading' (duration: 676.392029ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:46.115162Z","caller":"traceutil/trace.go:171","msg":"trace[1108420766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14285; }","duration":"448.87384ms","start":"2026-10-26T19:46:45.666267Z","end":"2026-10-26T19:46:46.11514Z","steps":["trace[1108420766] 'agreement among raft nodes before linearized reading' (duration: 448.456179ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:46.115196Z","caller":"traceutil/trace.go:171","msg":"trace[324117232] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-30e55e25-b769-49e8-bf52-9624b406c6d2; range_end:; response_count:1; response_revision:14285; }","duration":"628.236841ms","start":"2026-10-26T19:46:45.486936Z","end":"2026-10-26T19:46:46.115172Z","steps":["trace[324117232] 'agreement among raft nodes before linearized reading' (duration: 627.8283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:46.115279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.438371Z","time spent":"676.886222ms","remote":"127.0.0.1:45042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:46:46.114968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.84404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03fea68c-ca91-43ec-a2db-4d6271280ab4\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-10-26T19:46:46.115377Z","caller":"traceutil/trace.go:171","msg":"trace[1389734479] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03fea68c-ca91-43ec-a2db-4d6271280ab4; range_end:; response_count:1; response_revision:14285; }","duration":"628.273002ms","start":"2026-10-26T19:46:45.487086Z","end":"2026-10-26T19:46:46.115359Z","steps":["trace[1389734479] 'agreement among raft nodes before linearized reading' (duration: 627.787149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:46.115383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.486912Z","time spent":"628.373935ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-30e55e25-b769-49e8-bf52-9624b406c6d2\" "} {"level":"warn","ts":"2026-10-26T19:46:46.115425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.487069Z","time spent":"628.341843ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3630,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03fea68c-ca91-43ec-a2db-4d6271280ab4\" "} {"level":"warn","ts":"2026-10-26T19:46:46.114985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.978583ms","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:46:46.115553Z","caller":"traceutil/trace.go:171","msg":"trace[493781360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14285; }","duration":"586.524178ms","start":"2026-10-26T19:46:45.528949Z","end":"2026-10-26T19:46:46.115473Z","steps":["trace[493781360] 'agreement among raft nodes before linearized reading' (duration: 585.768038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:46.115048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.643667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lbvnb\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-26T19:46:46.115756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.52892Z","time spent":"586.811416ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T19:46:46.115851Z","caller":"traceutil/trace.go:171","msg":"trace[114924979] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lbvnb; range_end:; response_count:1; response_revision:14285; }","duration":"497.4798ms","start":"2026-10-26T19:46:45.618355Z","end":"2026-10-26T19:46:46.115835Z","steps":["trace[114924979] 'agreement among raft nodes before linearized reading' (duration: 496.574625ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:46.114834Z","caller":"traceutil/trace.go:171","msg":"trace[1335471775] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-m92mk.18e22c0575d53782; range_end:; response_count:1; response_revision:14285; }","duration":"974.279199ms","start":"2026-10-26T19:46:45.140532Z","end":"2026-10-26T19:46:46.114811Z","steps":["trace[1335471775] 'agreement among raft nodes before linearized reading' (duration: 974.057383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:46.115948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.140515Z","time spent":"975.41386ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":782,"request content":"key:\"/registry/events/openstack/ovn-controller-m92mk.18e22c0575d53782\" "} {"level":"warn","ts":"2026-10-26T19:46:46.114751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.329222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:46.116083Z","caller":"traceutil/trace.go:171","msg":"trace[1666631431] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:14285; }","duration":"904.698229ms","start":"2026-10-26T19:46:45.211348Z","end":"2026-10-26T19:46:46.116046Z","steps":["trace[1666631431] 'agreement among raft nodes before linearized reading' (duration: 903.27847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:46.116163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.211325Z","time spent":"904.813322ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:46:46.115893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.618325Z","time spent":"497.553412ms","remote":"127.0.0.1:45228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-lbvnb\" "} {"level":"warn","ts":"2026-10-26T19:46:46.115391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:45.666243Z","time spent":"449.022544ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:47:51.28996Z","caller":"traceutil/trace.go:171","msg":"trace[444634669] transaction","detail":"{read_only:false; response_revision:15191; number_of_response:1; }","duration":"113.395859ms","start":"2026-10-26T19:47:51.176541Z","end":"2026-10-26T19:47:51.289937Z","steps":["trace[444634669] 'process raft request' (duration: 113.226544ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:25.140729Z","caller":"traceutil/trace.go:171","msg":"trace[977215477] linearizableReadLoop","detail":"{readStateIndex:15889; appliedIndex:15888; }","duration":"238.130292ms","start":"2026-10-26T19:48:24.902575Z","end":"2026-10-26T19:48:25.140705Z","steps":["trace[977215477] 'read index received' (duration: 237.974008ms)","trace[977215477] 'applied index is now lower than readState.Index' (duration: 155.304µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:48:25.140773Z","caller":"traceutil/trace.go:171","msg":"trace[2093216155] transaction","detail":"{read_only:false; response_revision:15366; number_of_response:1; }","duration":"266.977365ms","start":"2026-10-26T19:48:24.873762Z","end":"2026-10-26T19:48:25.14074Z","steps":["trace[2093216155] 'process raft request' (duration: 266.79274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.140936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.342128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-vp7r2\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-26T19:48:25.140969Z","caller":"traceutil/trace.go:171","msg":"trace[370883844] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-vp7r2; range_end:; response_count:1; response_revision:15366; }","duration":"238.41826ms","start":"2026-10-26T19:48:24.902542Z","end":"2026-10-26T19:48:25.14096Z","steps":["trace[370883844] 'agreement among raft nodes before linearized reading' (duration: 238.276126ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:25.281753Z","caller":"traceutil/trace.go:171","msg":"trace[501052925] linearizableReadLoop","detail":"{readStateIndex:15890; appliedIndex:15889; }","duration":"140.939195ms","start":"2026-10-26T19:48:25.140794Z","end":"2026-10-26T19:48:25.281733Z","steps":["trace[501052925] 'read index received' (duration: 140.716958ms)","trace[501052925] 'applied index is now lower than readState.Index' (duration: 221.527µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:48:25.281885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.109599ms","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:48:25.281941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.354659ms","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-26T19:48:25.281968Z","caller":"traceutil/trace.go:171","msg":"trace[356922793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15367; }","duration":"325.436672ms","start":"2026-10-26T19:48:24.956524Z","end":"2026-10-26T19:48:25.281961Z","steps":["trace[356922793] 'agreement among raft nodes before linearized reading' (duration: 325.311578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.281992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.956504Z","time spent":"325.482383ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-26T19:48:25.281984Z","caller":"traceutil/trace.go:171","msg":"trace[1972774575] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15367; }","duration":"309.240612ms","start":"2026-10-26T19:48:24.972717Z","end":"2026-10-26T19:48:25.281957Z","steps":["trace[1972774575] 'agreement among raft nodes before linearized reading' (duration: 309.087467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.281987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.211727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-10-26T19:48:25.282037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.972702Z","time spent":"309.322144ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-10-26T19:48:25.282046Z","caller":"traceutil/trace.go:171","msg":"trace[788591146] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15367; }","duration":"307.30611ms","start":"2026-10-26T19:48:24.974723Z","end":"2026-10-26T19:48:25.282029Z","steps":["trace[788591146] 'agreement among raft nodes before linearized reading' (duration: 307.140305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.282086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.974703Z","time spent":"307.372771ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-26T19:48:25.282132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.630747ms","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:48:25.28216Z","caller":"traceutil/trace.go:171","msg":"trace[173251896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15367; }","duration":"325.681358ms","start":"2026-10-26T19:48:24.956473Z","end":"2026-10-26T19:48:25.282155Z","steps":["trace[173251896] 'agreement among raft nodes before linearized reading' (duration: 325.614206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.282176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.956461Z","time spent":"325.710979ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T19:48:25.282226Z","caller":"traceutil/trace.go:171","msg":"trace[1848754266] transaction","detail":"{read_only:false; response_revision:15367; number_of_response:1; }","duration":"376.128247ms","start":"2026-10-26T19:48:24.906078Z","end":"2026-10-26T19:48:25.282206Z","steps":["trace[1848754266] 'process raft request' (duration: 375.50225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.281993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.495317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-10-26T19:48:25.282297Z","caller":"traceutil/trace.go:171","msg":"trace[1400655137] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15367; }","duration":"306.839526ms","start":"2026-10-26T19:48:24.975438Z","end":"2026-10-26T19:48:25.282278Z","steps":["trace[1400655137] 'agreement among raft nodes before linearized reading' (duration: 306.318112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.282313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.596997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:48:25.282346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.97543Z","time spent":"306.901479ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":704,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-10-26T19:48:25.282363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.85373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:48:25.28236Z","caller":"traceutil/trace.go:171","msg":"trace[378269916] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15367; }","duration":"307.68605ms","start":"2026-10-26T19:48:24.974657Z","end":"2026-10-26T19:48:25.282343Z","steps":["trace[378269916] 'agreement among raft nodes before linearized reading' (duration: 307.613068ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:25.282384Z","caller":"traceutil/trace.go:171","msg":"trace[1058135370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15367; }","duration":"333.901011ms","start":"2026-10-26T19:48:24.948477Z","end":"2026-10-26T19:48:25.282378Z","steps":["trace[1058135370] 'agreement among raft nodes before linearized reading' (duration: 333.840849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.282407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.94846Z","time spent":"333.943113ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T19:48:25.2824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.974647Z","time spent":"307.741871ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-10-26T19:48:25.282406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.937287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:48:25.282446Z","caller":"traceutil/trace.go:171","msg":"trace[1376997553] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:15367; }","duration":"300.975998ms","start":"2026-10-26T19:48:24.981456Z","end":"2026-10-26T19:48:25.282432Z","steps":["trace[1376997553] 'agreement among raft nodes before linearized reading' (duration: 300.913016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.282486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.98142Z","time spent":"301.05258ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:48:25.282342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.906062Z","time spent":"376.203509ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:48:25.282788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.974781ms","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:48:25.282827Z","caller":"traceutil/trace.go:171","msg":"trace[513150973] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15367; }","duration":"307.026522ms","start":"2026-10-26T19:48:24.975788Z","end":"2026-10-26T19:48:25.282815Z","steps":["trace[513150973] 'agreement among raft nodes before linearized reading' (duration: 306.870948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.282864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:24.975782Z","time spent":"307.074503ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-26T19:48:25.28305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.591714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:772509"} {"level":"info","ts":"2026-10-26T19:48:25.283085Z","caller":"traceutil/trace.go:171","msg":"trace[124461039] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15367; }","duration":"137.657146ms","start":"2026-10-26T19:48:25.145421Z","end":"2026-10-26T19:48:25.283078Z","steps":["trace[124461039] 'agreement among raft nodes before linearized reading' (duration: 137.06781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.283514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.623785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19037"} {"level":"info","ts":"2026-10-26T19:48:25.283726Z","caller":"traceutil/trace.go:171","msg":"trace[1896226207] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:15367; }","duration":"137.865471ms","start":"2026-10-26T19:48:25.145837Z","end":"2026-10-26T19:48:25.283703Z","steps":["trace[1896226207] 'agreement among raft nodes before linearized reading' (duration: 137.44519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.288142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.183724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lbvnb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:48:25.288249Z","caller":"traceutil/trace.go:171","msg":"trace[1954109025] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lbvnb; range_end:; response_count:1; response_revision:15367; }","duration":"119.327167ms","start":"2026-10-26T19:48:25.168896Z","end":"2026-10-26T19:48:25.288223Z","steps":["trace[1954109025] 'agreement among raft nodes before linearized reading' (duration: 114.310922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.894546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.369074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-26T19:48:25.894554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.475551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:48:25.894624Z","caller":"traceutil/trace.go:171","msg":"trace[379444200] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15371; }","duration":"292.475267ms","start":"2026-10-26T19:48:25.602129Z","end":"2026-10-26T19:48:25.894604Z","steps":["trace[379444200] 'range keys from in-memory index tree' (duration: 292.145158ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:25.894644Z","caller":"traceutil/trace.go:171","msg":"trace[227522987] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:15371; }","duration":"144.617084ms","start":"2026-10-26T19:48:25.750003Z","end":"2026-10-26T19:48:25.89462Z","steps":["trace[227522987] 'count revisions from in-memory index tree' (duration: 144.330506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.8945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.104548ms","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:48:25.89466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.337365ms","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:48:25.894733Z","caller":"traceutil/trace.go:171","msg":"trace[2063848208] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15371; }","duration":"309.392276ms","start":"2026-10-26T19:48:25.585316Z","end":"2026-10-26T19:48:25.894709Z","steps":["trace[2063848208] 'range keys from in-memory index tree' (duration: 309.022206ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:25.894735Z","caller":"traceutil/trace.go:171","msg":"trace[1750760889] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15371; }","duration":"309.452148ms","start":"2026-10-26T19:48:25.585268Z","end":"2026-10-26T19:48:25.89472Z","steps":["trace[1750760889] 'range keys from in-memory index tree' (duration: 309.143439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:25.894774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:25.5853Z","time spent":"309.460368ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-26T19:48:25.89479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:25.585237Z","time spent":"309.531479ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-26T19:48:25.894793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.739848ms","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:48:25.89494Z","caller":"traceutil/trace.go:171","msg":"trace[1710993464] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15371; }","duration":"102.934263ms","start":"2026-10-26T19:48:25.791981Z","end":"2026-10-26T19:48:25.894915Z","steps":["trace[1710993464] 'range keys from in-memory index tree' (duration: 102.519272ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:29.610387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12824} {"level":"info","ts":"2026-10-26T19:48:29.700896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12824,"took":"85.912232ms","hash":138086726,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":30711808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T19:48:29.701094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":138086726,"revision":12824,"compact-revision":10570} {"level":"info","ts":"2026-10-26T19:48:31.452393Z","caller":"traceutil/trace.go:171","msg":"trace[1145630513] linearizableReadLoop","detail":"{readStateIndex:15923; appliedIndex:15922; }","duration":"101.045672ms","start":"2026-10-26T19:48:31.351329Z","end":"2026-10-26T19:48:31.452375Z","steps":["trace[1145630513] 'read index received' (duration: 14.094143ms)","trace[1145630513] 'applied index is now lower than readState.Index' (duration: 86.950919ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:48:31.452393Z","caller":"traceutil/trace.go:171","msg":"trace[930642853] transaction","detail":"{read_only:false; response_revision:15398; number_of_response:1; }","duration":"108.863944ms","start":"2026-10-26T19:48:31.343487Z","end":"2026-10-26T19:48:31.452351Z","steps":["trace[930642853] 'process raft request' (duration: 21.947465ms)","trace[930642853] 'compare' (duration: 86.800766ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:48:31.45257Z","caller":"traceutil/trace.go:171","msg":"trace[1735561769] transaction","detail":"{read_only:false; response_revision:15399; number_of_response:1; }","duration":"101.152075ms","start":"2026-10-26T19:48:31.351392Z","end":"2026-10-26T19:48:31.452544Z","steps":["trace[1735561769] 'process raft request' (duration: 100.95609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:31.452732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.384601ms","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:48:31.45276Z","caller":"traceutil/trace.go:171","msg":"trace[994043426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15399; }","duration":"101.450303ms","start":"2026-10-26T19:48:31.351301Z","end":"2026-10-26T19:48:31.452751Z","steps":["trace[994043426] 'agreement among raft nodes before linearized reading' (duration: 101.251767ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:50:31.612739Z","caller":"traceutil/trace.go:171","msg":"trace[1684109999] linearizableReadLoop","detail":"{readStateIndex:16807; appliedIndex:16806; }","duration":"115.69209ms","start":"2026-10-26T19:50:31.497007Z","end":"2026-10-26T19:50:31.612699Z","steps":["trace[1684109999] 'read index received' (duration: 50.8618ms)","trace[1684109999] 'applied index is now lower than readState.Index' (duration: 64.82922ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:50:31.613075Z","caller":"traceutil/trace.go:171","msg":"trace[1324831546] transaction","detail":"{read_only:false; response_revision:16250; number_of_response:1; }","duration":"137.688397ms","start":"2026-10-26T19:50:31.475373Z","end":"2026-10-26T19:50:31.613061Z","steps":["trace[1324831546] 'process raft request' (duration: 72.618001ms)","trace[1324831546] 'compare' (duration: 64.49069ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:50:31.613401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.365788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T19:50:31.613445Z","caller":"traceutil/trace.go:171","msg":"trace[72630259] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16250; }","duration":"116.45165ms","start":"2026-10-26T19:50:31.496978Z","end":"2026-10-26T19:50:31.61343Z","steps":["trace[72630259] 'agreement among raft nodes before linearized reading' (duration: 116.278075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:50:31.614437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.180855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2f6b2\" ","response":"range_response_count:1 size:20799"} {"level":"info","ts":"2026-10-26T19:50:31.614482Z","caller":"traceutil/trace.go:171","msg":"trace[1955785403] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2f6b2; range_end:; response_count:1; response_revision:16250; }","duration":"108.251088ms","start":"2026-10-26T19:50:31.506218Z","end":"2026-10-26T19:50:31.614469Z","steps":["trace[1955785403] 'agreement among raft nodes before linearized reading' (duration: 108.124994ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:29.617666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15392} {"level":"info","ts":"2026-10-26T19:53:29.728509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15392,"took":"107.758675ms","hash":2664574904,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:53:29.728568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2664574904,"revision":15392,"compact-revision":12824} {"level":"info","ts":"2026-10-26T19:54:04.881353Z","caller":"traceutil/trace.go:171","msg":"trace[816263731] linearizableReadLoop","detail":"{readStateIndex:18489; appliedIndex:18488; }","duration":"216.482485ms","start":"2026-10-26T19:54:04.664844Z","end":"2026-10-26T19:54:04.881327Z","steps":["trace[816263731] 'read index received' (duration: 216.336341ms)","trace[816263731] 'applied index is now lower than readState.Index' (duration: 144.644µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:04.881398Z","caller":"traceutil/trace.go:171","msg":"trace[862036377] transaction","detail":"{read_only:false; response_revision:17880; number_of_response:1; }","duration":"255.470393ms","start":"2026-10-26T19:54:04.625901Z","end":"2026-10-26T19:54:04.881371Z","steps":["trace[862036377] 'process raft request' (duration: 255.250517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:04.881568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.707341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:54:04.881639Z","caller":"traceutil/trace.go:171","msg":"trace[463574497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17880; }","duration":"216.858785ms","start":"2026-10-26T19:54:04.664764Z","end":"2026-10-26T19:54:04.881623Z","steps":["trace[463574497] 'agreement among raft nodes before linearized reading' (duration: 216.702841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:04.897576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.481501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:54:04.897702Z","caller":"traceutil/trace.go:171","msg":"trace[1435123636] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:17881; }","duration":"102.689657ms","start":"2026-10-26T19:54:04.794986Z","end":"2026-10-26T19:54:04.897675Z","steps":["trace[1435123636] 'agreement among raft nodes before linearized reading' (duration: 102.44487ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:11.842655Z","caller":"traceutil/trace.go:171","msg":"trace[1700452356] transaction","detail":"{read_only:false; response_revision:17961; number_of_response:1; }","duration":"119.882624ms","start":"2026-10-26T19:54:11.722745Z","end":"2026-10-26T19:54:11.842628Z","steps":["trace[1700452356] 'process raft request' (duration: 119.627887ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:57:00.388574Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"548b318b3528a14c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:57:00.398008Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T19:57:00.398105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T19:58:29.626062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17571} {"level":"info","ts":"2026-10-26T19:58:29.751766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17571,"took":"121.119182ms","hash":514078024,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":33951744,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T19:58:29.751822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":514078024,"revision":17571,"compact-revision":15392} {"level":"info","ts":"2026-10-26T19:59:16.329649Z","caller":"traceutil/trace.go:171","msg":"trace[1441424950] transaction","detail":"{read_only:false; response_revision:20324; number_of_response:1; }","duration":"210.897911ms","start":"2026-10-26T19:59:16.118718Z","end":"2026-10-26T19:59:16.329616Z","steps":["trace[1441424950] 'process raft request' (duration: 210.635334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:32.847421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.14244ms","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-26T19:59:32.847525Z","caller":"traceutil/trace.go:171","msg":"trace[1083067553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20404; }","duration":"112.267704ms","start":"2026-10-26T19:59:32.735214Z","end":"2026-10-26T19:59:32.847482Z","steps":["trace[1083067553] 'range keys from in-memory index tree' (duration: 111.874334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:32.847638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.146229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:59:32.847746Z","caller":"traceutil/trace.go:171","msg":"trace[378871191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20404; }","duration":"181.304784ms","start":"2026-10-26T19:59:32.666409Z","end":"2026-10-26T19:59:32.847714Z","steps":["trace[378871191] 'range keys from in-memory index tree' (duration: 180.994555ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:33.049211Z","caller":"traceutil/trace.go:171","msg":"trace[1111779322] linearizableReadLoop","detail":"{readStateIndex:21101; appliedIndex:21100; }","duration":"126.481477ms","start":"2026-10-26T19:59:32.922671Z","end":"2026-10-26T19:59:33.049153Z","steps":["trace[1111779322] 'read index received' (duration: 111.081652ms)","trace[1111779322] 'applied index is now lower than readState.Index' (duration: 15.398705ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:59:33.04945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.745524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:59:33.049501Z","caller":"traceutil/trace.go:171","msg":"trace[827373205] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:20406; }","duration":"126.823486ms","start":"2026-10-26T19:59:32.922661Z","end":"2026-10-26T19:59:33.049485Z","steps":["trace[827373205] 'agreement among raft nodes before linearized reading' (duration: 126.670682ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:33.049906Z","caller":"traceutil/trace.go:171","msg":"trace[763120252] transaction","detail":"{read_only:false; response_revision:20406; number_of_response:1; }","duration":"128.953594ms","start":"2026-10-26T19:59:32.920936Z","end":"2026-10-26T19:59:33.04989Z","steps":["trace[763120252] 'process raft request' (duration: 112.814979ms)","trace[763120252] 'compare' (duration: 15.2257ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:59:33.272368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.736085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418236"} {"level":"info","ts":"2026-10-26T19:59:33.272487Z","caller":"traceutil/trace.go:171","msg":"trace[966171031] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:20406; }","duration":"103.93382ms","start":"2026-10-26T19:59:33.168524Z","end":"2026-10-26T19:59:33.272457Z","steps":["trace[966171031] 'range keys from in-memory index tree' (duration: 103.456387ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:33.58853Z","caller":"traceutil/trace.go:171","msg":"trace[1360242759] transaction","detail":"{read_only:false; response_revision:20407; number_of_response:1; }","duration":"310.077633ms","start":"2026-10-26T19:59:33.278436Z","end":"2026-10-26T19:59:33.588513Z","steps":["trace[1360242759] 'process raft request' (duration: 309.96175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:33.588643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:33.278412Z","time spent":"310.178986ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:59:33.588682Z","caller":"traceutil/trace.go:171","msg":"trace[386860443] linearizableReadLoop","detail":"{readStateIndex:21103; appliedIndex:21101; }","duration":"281.808201ms","start":"2026-10-26T19:59:33.306832Z","end":"2026-10-26T19:59:33.58864Z","steps":["trace[386860443] 'read index received' (duration: 78.426712ms)","trace[386860443] 'applied index is now lower than readState.Index' (duration: 203.379919ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:59:33.588776Z","caller":"traceutil/trace.go:171","msg":"trace[1864343043] transaction","detail":"{read_only:false; response_revision:20408; number_of_response:1; }","duration":"246.611303ms","start":"2026-10-26T19:59:33.342157Z","end":"2026-10-26T19:59:33.588769Z","steps":["trace[1864343043] 'process raft request' (duration: 246.331285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:33.588893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.054028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T19:59:33.588935Z","caller":"traceutil/trace.go:171","msg":"trace[1370038128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20408; }","duration":"282.13417ms","start":"2026-10-26T19:59:33.30679Z","end":"2026-10-26T19:59:33.588924Z","steps":["trace[1370038128] 'agreement among raft nodes before linearized reading' (duration: 281.986926ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:33.597111Z","caller":"traceutil/trace.go:171","msg":"trace[1717700754] transaction","detail":"{read_only:false; response_revision:20410; number_of_response:1; }","duration":"181.862479ms","start":"2026-10-26T19:59:33.415211Z","end":"2026-10-26T19:59:33.597074Z","steps":["trace[1717700754] 'process raft request' (duration: 181.662694ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:33.597147Z","caller":"traceutil/trace.go:171","msg":"trace[18535585] transaction","detail":"{read_only:false; response_revision:20409; number_of_response:1; }","duration":"210.903022ms","start":"2026-10-26T19:59:33.386221Z","end":"2026-10-26T19:59:33.597124Z","steps":["trace[18535585] 'process raft request' (duration: 210.563582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:33.59725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.575546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-26T19:59:33.597439Z","caller":"traceutil/trace.go:171","msg":"trace[1601245093] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20411; }","duration":"284.803401ms","start":"2026-10-26T19:59:33.312608Z","end":"2026-10-26T19:59:33.597412Z","steps":["trace[1601245093] 'agreement among raft nodes before linearized reading' (duration: 284.565955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:33.597249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.590023ms","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-26T19:59:33.597613Z","caller":"traceutil/trace.go:171","msg":"trace[2142369367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20411; }","duration":"285.975143ms","start":"2026-10-26T19:59:33.311624Z","end":"2026-10-26T19:59:33.597599Z","steps":["trace[2142369367] 'agreement among raft nodes before linearized reading' (duration: 285.544352ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:29.655584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20079} {"level":"info","ts":"2026-10-26T20:03:29.786792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20079,"took":"126.740624ms","hash":3655017387,"current-db-size-bytes":55447552,"current-db-size":"55 MB","current-db-size-in-use-bytes":42164224,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-26T20:03:29.786846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3655017387,"revision":20079,"compact-revision":17571} {"level":"info","ts":"2026-10-26T20:03:33.169269Z","caller":"traceutil/trace.go:171","msg":"trace[880000311] transaction","detail":"{read_only:false; response_revision:22945; number_of_response:1; }","duration":"374.040855ms","start":"2026-10-26T20:03:32.795179Z","end":"2026-10-26T20:03:33.16922Z","steps":["trace[880000311] 'process raft request' (duration: 345.420384ms)","trace[880000311] 'compare' (duration: 27.962404ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:03:33.169449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.879669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-26T20:03:33.16948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:03:32.795154Z","time spent":"374.24238ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:03:33.169497Z","caller":"traceutil/trace.go:171","msg":"trace[208218557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22946; }","duration":"116.940891ms","start":"2026-10-26T20:03:33.052547Z","end":"2026-10-26T20:03:33.169488Z","steps":["trace[208218557] 'agreement among raft nodes before linearized reading' (duration: 116.782016ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:33.169306Z","caller":"traceutil/trace.go:171","msg":"trace[1877583550] linearizableReadLoop","detail":"{readStateIndex:23718; appliedIndex:23717; }","duration":"116.717145ms","start":"2026-10-26T20:03:33.052555Z","end":"2026-10-26T20:03:33.169273Z","steps":["trace[1877583550] 'read index received' (duration: 88.064213ms)","trace[1877583550] 'applied index is now lower than readState.Index' (duration: 28.649042ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:00.549303Z","caller":"traceutil/trace.go:171","msg":"trace[296203590] linearizableReadLoop","detail":"{readStateIndex:25869; appliedIndex:25868; }","duration":"132.366811ms","start":"2026-10-26T20:07:00.416907Z","end":"2026-10-26T20:07:00.549274Z","steps":["trace[296203590] 'read index received' (duration: 132.111404ms)","trace[296203590] 'applied index is now lower than readState.Index' (duration: 254.337µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:00.54947Z","caller":"traceutil/trace.go:171","msg":"trace[1499923176] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"137.568062ms","start":"2026-10-26T20:07:00.411859Z","end":"2026-10-26T20:07:00.549427Z","steps":["trace[1499923176] 'process raft request' (duration: 137.191582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:00.549545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.872345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:07:00.549586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.660798ms","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:07:00.549621Z","caller":"traceutil/trace.go:171","msg":"trace[1219678774] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25043; }","duration":"129.009219ms","start":"2026-10-26T20:07:00.420597Z","end":"2026-10-26T20:07:00.549606Z","steps":["trace[1219678774] 'agreement among raft nodes before linearized reading' (duration: 128.853925ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:00.549642Z","caller":"traceutil/trace.go:171","msg":"trace[1211147872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25043; }","duration":"132.773321ms","start":"2026-10-26T20:07:00.416853Z","end":"2026-10-26T20:07:00.549626Z","steps":["trace[1211147872] 'agreement among raft nodes before linearized reading' (duration: 132.574776ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:00.949031Z","caller":"traceutil/trace.go:171","msg":"trace[1977742115] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"395.827098ms","start":"2026-10-26T20:07:00.553175Z","end":"2026-10-26T20:07:00.949002Z","steps":["trace[1977742115] 'process raft request' (duration: 379.084082ms)","trace[1977742115] 'compare' (duration: 16.141929ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:00.949124Z","caller":"traceutil/trace.go:171","msg":"trace[380863124] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"395.17181ms","start":"2026-10-26T20:07:00.553882Z","end":"2026-10-26T20:07:00.949053Z","steps":["trace[380863124] 'process raft request' (duration: 395.073467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:00.94921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:00.55315Z","time spent":"395.984821ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:07:00.949197Z","caller":"traceutil/trace.go:171","msg":"trace[1035182136] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"395.373525ms","start":"2026-10-26T20:07:00.553767Z","end":"2026-10-26T20:07:00.94914Z","steps":["trace[1035182136] 'process raft request' (duration: 395.048407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:00.949353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:00.553861Z","time spent":"395.372005ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:07:00.949464Z","caller":"traceutil/trace.go:171","msg":"trace[846437137] linearizableReadLoop","detail":"{readStateIndex:25872; appliedIndex:25869; }","duration":"284.9142ms","start":"2026-10-26T20:07:00.66454Z","end":"2026-10-26T20:07:00.949454Z","steps":["trace[846437137] 'read index received' (duration: 267.732872ms)","trace[846437137] 'applied index is now lower than readState.Index' (duration: 17.180318ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:00.949468Z","caller":"traceutil/trace.go:171","msg":"trace[1057842656] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"163.155088ms","start":"2026-10-26T20:07:00.786275Z","end":"2026-10-26T20:07:00.94943Z","steps":["trace[1057842656] 'process raft request' (duration: 162.721006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:00.9494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:00.553745Z","time spent":"395.545339ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:07:00.949627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.531466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:07:00.949646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.586966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-26T20:07:00.949684Z","caller":"traceutil/trace.go:171","msg":"trace[753525748] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25048; }","duration":"114.616358ms","start":"2026-10-26T20:07:00.835048Z","end":"2026-10-26T20:07:00.949665Z","steps":["trace[753525748] 'agreement among raft nodes before linearized reading' (duration: 114.524105ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:00.949694Z","caller":"traceutil/trace.go:171","msg":"trace[1418308713] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25048; }","duration":"211.684068ms","start":"2026-10-26T20:07:00.737995Z","end":"2026-10-26T20:07:00.949679Z","steps":["trace[1418308713] 'agreement among raft nodes before linearized reading' (duration: 211.529444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:00.949645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.080495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:07:00.949785Z","caller":"traceutil/trace.go:171","msg":"trace[1744548996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25048; }","duration":"285.259339ms","start":"2026-10-26T20:07:00.664505Z","end":"2026-10-26T20:07:00.949765Z","steps":["trace[1744548996] 'agreement among raft nodes before linearized reading' (duration: 284.979742ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:01.160073Z","caller":"traceutil/trace.go:171","msg":"trace[1522144939] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"206.092016ms","start":"2026-10-26T20:07:00.95395Z","end":"2026-10-26T20:07:01.160042Z","steps":["trace[1522144939] 'process raft request' (duration: 189.643949ms)","trace[1522144939] 'compare' (duration: 16.047656ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:01.375173Z","caller":"traceutil/trace.go:171","msg":"trace[1915731671] linearizableReadLoop","detail":"{readStateIndex:25876; appliedIndex:25875; }","duration":"215.173014ms","start":"2026-10-26T20:07:01.159979Z","end":"2026-10-26T20:07:01.375152Z","steps":["trace[1915731671] 'read index received' (duration: 177.457578ms)","trace[1915731671] 'applied index is now lower than readState.Index' (duration: 37.714356ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:01.375181Z","caller":"traceutil/trace.go:171","msg":"trace[205280562] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"272.775981ms","start":"2026-10-26T20:07:01.102362Z","end":"2026-10-26T20:07:01.375138Z","steps":["trace[205280562] 'process raft request' (duration: 235.101506ms)","trace[205280562] 'compare' (duration: 37.470439ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:07:01.375365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.514189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:07:01.375437Z","caller":"traceutil/trace.go:171","msg":"trace[931857505] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25051; }","duration":"277.634062ms","start":"2026-10-26T20:07:01.097783Z","end":"2026-10-26T20:07:01.375417Z","steps":["trace[931857505] 'agreement among raft nodes before linearized reading' (duration: 277.487948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:57.734301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.340823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:07:57.734382Z","caller":"traceutil/trace.go:171","msg":"trace[971557355] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25564; }","duration":"113.465927ms","start":"2026-10-26T20:07:57.620899Z","end":"2026-10-26T20:07:57.734365Z","steps":["trace[971557355] 'range keys from in-memory index tree' (duration: 113.271121ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:57.734646Z","caller":"traceutil/trace.go:171","msg":"trace[764052076] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"109.336344ms","start":"2026-10-26T20:07:57.625299Z","end":"2026-10-26T20:07:57.734635Z","steps":["trace[764052076] 'process raft request' (duration: 64.966787ms)","trace[764052076] 'compare' (duration: 43.839503ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:03.139788Z","caller":"traceutil/trace.go:171","msg":"trace[500867724] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26439; }","duration":"246.353372ms","start":"2026-10-26T20:08:02.893408Z","end":"2026-10-26T20:08:03.139761Z","steps":["trace[500867724] 'read index received' (duration: 246.127006ms)","trace[500867724] 'applied index is now lower than readState.Index' (duration: 225.386µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:03.140153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.760092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:03.140219Z","caller":"traceutil/trace.go:171","msg":"trace[559418825] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25601; }","duration":"107.874575ms","start":"2026-10-26T20:08:03.032332Z","end":"2026-10-26T20:08:03.140207Z","steps":["trace[559418825] 'agreement among raft nodes before linearized reading' (duration: 107.762082ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:03.140284Z","caller":"traceutil/trace.go:171","msg":"trace[322529093] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"248.661544ms","start":"2026-10-26T20:08:02.891542Z","end":"2026-10-26T20:08:03.140204Z","steps":["trace[322529093] 'process raft request' (duration: 248.074248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:03.140464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.058301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-26T20:08:03.140509Z","caller":"traceutil/trace.go:171","msg":"trace[2143139479] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25601; }","duration":"247.128943ms","start":"2026-10-26T20:08:02.893373Z","end":"2026-10-26T20:08:03.140502Z","steps":["trace[2143139479] 'agreement among raft nodes before linearized reading' (duration: 247.00435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:03.833565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.024451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:08:03.833643Z","caller":"traceutil/trace.go:171","msg":"trace[1386111133] linearizableReadLoop","detail":"{readStateIndex:26441; appliedIndex:26440; }","duration":"609.017935ms","start":"2026-10-26T20:08:03.224612Z","end":"2026-10-26T20:08:03.83363Z","steps":["trace[1386111133] 'read index received' (duration: 58.921033ms)","trace[1386111133] 'applied index is now lower than readState.Index' (duration: 550.095752ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:03.833693Z","caller":"traceutil/trace.go:171","msg":"trace[1647845661] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"650.045002ms","start":"2026-10-26T20:08:03.183609Z","end":"2026-10-26T20:08:03.833654Z","steps":["trace[1647845661] 'process raft request' (duration: 99.880608ms)","trace[1647845661] 'compare' (duration: 549.917197ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:03.833775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.159819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.239\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-26T20:08:03.833802Z","caller":"traceutil/trace.go:171","msg":"trace[878352768] range","detail":"{range_begin:/registry/masterleases/162.253.55.239; range_end:; response_count:1; response_revision:25602; }","duration":"609.214961ms","start":"2026-10-26T20:08:03.224579Z","end":"2026-10-26T20:08:03.833794Z","steps":["trace[878352768] 'agreement among raft nodes before linearized reading' (duration: 609.110818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:03.833821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.183587Z","time spent":"650.167665ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:08:03.833829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.224565Z","time spent":"609.255112ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/162.253.55.239\" "} {"level":"info","ts":"2026-10-26T20:08:03.90585Z","caller":"traceutil/trace.go:171","msg":"trace[1636050251] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"152.227421ms","start":"2026-10-26T20:08:03.753476Z","end":"2026-10-26T20:08:03.905703Z","steps":["trace[1636050251] 'process raft request' (duration: 152.093607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.271653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.45758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:08:04.271854Z","caller":"traceutil/trace.go:171","msg":"trace[1319033127] linearizableReadLoop","detail":"{readStateIndex:26444; appliedIndex:26441; }","duration":"438.18245ms","start":"2026-10-26T20:08:03.833659Z","end":"2026-10-26T20:08:04.271841Z","steps":["trace[1319033127] 'read index received' (duration: 71.945507ms)","trace[1319033127] 'applied index is now lower than readState.Index' (duration: 366.236173ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:04.271914Z","caller":"traceutil/trace.go:171","msg":"trace[822938680] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"512.149292ms","start":"2026-10-26T20:08:03.759756Z","end":"2026-10-26T20:08:04.271905Z","steps":["trace[822938680] 'process raft request' (duration: 185.344702ms)","trace[822938680] 'compare' (duration: 326.265485ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:04.271956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.759702Z","time spent":"512.231964ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:08:04.272112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.824452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-26T20:08:04.272181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.172393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T20:08:04.272212Z","caller":"traceutil/trace.go:171","msg":"trace[1048808975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25605; }","duration":"926.233545ms","start":"2026-10-26T20:08:03.345971Z","end":"2026-10-26T20:08:04.272205Z","steps":["trace[1048808975] 'agreement among raft nodes before linearized reading' (duration: 926.120622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.272216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.835248Z","time spent":"436.966156ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-26T20:08:04.272236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.345955Z","time spent":"926.276465ms","remote":"127.0.0.1:45316","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:08:04.272251Z","caller":"traceutil/trace.go:171","msg":"trace[1593381847] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"492.542309ms","start":"2026-10-26T20:08:03.779664Z","end":"2026-10-26T20:08:04.272206Z","steps":["trace[1593381847] 'process raft request' (duration: 492.066836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.27229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.06387ms","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:08:04.272184Z","caller":"traceutil/trace.go:171","msg":"trace[1246360080] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25605; }","duration":"747.911964ms","start":"2026-10-26T20:08:03.524239Z","end":"2026-10-26T20:08:04.272151Z","steps":["trace[1246360080] 'agreement among raft nodes before linearized reading' (duration: 747.7638ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.272351Z","caller":"traceutil/trace.go:171","msg":"trace[1702763457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25605; }","duration":"926.154942ms","start":"2026-10-26T20:08:03.34618Z","end":"2026-10-26T20:08:04.272335Z","steps":["trace[1702763457] 'agreement among raft nodes before linearized reading' (duration: 925.857274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.27236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.524222Z","time spent":"748.122239ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T20:08:04.272396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.346157Z","time spent":"926.229535ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T20:08:04.272411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.932927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:08:04.272401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.779641Z","time spent":"492.686972ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:08:04.272395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.847465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:04.272435Z","caller":"traceutil/trace.go:171","msg":"trace[1293901463] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25605; }","duration":"864.954948ms","start":"2026-10-26T20:08:03.407471Z","end":"2026-10-26T20:08:04.272426Z","steps":["trace[1293901463] 'agreement among raft nodes before linearized reading' (duration: 864.920087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.272462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.407456Z","time spent":"865.000619ms","remote":"127.0.0.1:35022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:08:04.272365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.162142ms","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:08:04.272472Z","caller":"traceutil/trace.go:171","msg":"trace[2133719406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25605; }","duration":"607.967078ms","start":"2026-10-26T20:08:03.664488Z","end":"2026-10-26T20:08:04.272455Z","steps":["trace[2133719406] 'agreement among raft nodes before linearized reading' (duration: 607.822305ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.272511Z","caller":"traceutil/trace.go:171","msg":"trace[1948479151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25605; }","duration":"944.338297ms","start":"2026-10-26T20:08:03.328148Z","end":"2026-10-26T20:08:04.272486Z","steps":["trace[1948479151] 'agreement among raft nodes before linearized reading' (duration: 944.170233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.272558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.328128Z","time spent":"944.417309ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T20:08:04.272516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.664472Z","time spent":"608.03581ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T20:08:04.368987Z","caller":"traceutil/trace.go:171","msg":"trace[261907035] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"419.867482ms","start":"2026-10-26T20:08:03.949094Z","end":"2026-10-26T20:08:04.368962Z","steps":["trace[261907035] 'process raft request' (duration: 419.714218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.369132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.949077Z","time spent":"419.978374ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:08:04.41124Z","caller":"traceutil/trace.go:171","msg":"trace[153775326] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"163.847237ms","start":"2026-10-26T20:08:04.247358Z","end":"2026-10-26T20:08:04.411205Z","steps":["trace[153775326] 'process raft request' (duration: 163.407125ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.411378Z","caller":"traceutil/trace.go:171","msg":"trace[658976379] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"135.198148ms","start":"2026-10-26T20:08:04.276149Z","end":"2026-10-26T20:08:04.411347Z","steps":["trace[658976379] 'process raft request' (duration: 134.987272ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.411442Z","caller":"traceutil/trace.go:171","msg":"trace[1510963193] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"134.846098ms","start":"2026-10-26T20:08:04.276586Z","end":"2026-10-26T20:08:04.411432Z","steps":["trace[1510963193] 'process raft request' (duration: 134.632552ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.411473Z","caller":"traceutil/trace.go:171","msg":"trace[1558753062] linearizableReadLoop","detail":"{readStateIndex:26447; appliedIndex:26445; }","duration":"139.574447ms","start":"2026-10-26T20:08:04.271878Z","end":"2026-10-26T20:08:04.411452Z","steps":["trace[1558753062] 'read index received' (duration: 96.968128ms)","trace[1558753062] 'applied index is now lower than readState.Index' (duration: 42.604809ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:04.41154Z","caller":"traceutil/trace.go:171","msg":"trace[2147106681] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"134.726635ms","start":"2026-10-26T20:08:04.276806Z","end":"2026-10-26T20:08:04.411532Z","steps":["trace[2147106681] 'process raft request' (duration: 134.460868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.411603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.782986ms","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":"warn","ts":"2026-10-26T20:08:04.411622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.655396ms","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":"warn","ts":"2026-10-26T20:08:04.411624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.924633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:04.411651Z","caller":"traceutil/trace.go:171","msg":"trace[1725225507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25613; }","duration":"258.723318ms","start":"2026-10-26T20:08:04.152917Z","end":"2026-10-26T20:08:04.411641Z","steps":["trace[1725225507] 'agreement among raft nodes before linearized reading' (duration: 258.623665ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.411656Z","caller":"traceutil/trace.go:171","msg":"trace[676984868] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"134.115168ms","start":"2026-10-26T20:08:04.277511Z","end":"2026-10-26T20:08:04.411626Z","steps":["trace[676984868] 'process raft request' (duration: 133.82641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.411659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.968427ms","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:08:04.411649Z","caller":"traceutil/trace.go:171","msg":"trace[237776873] 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:25613; }","duration":"327.873179ms","start":"2026-10-26T20:08:04.083763Z","end":"2026-10-26T20:08:04.411636Z","steps":["trace[237776873] 'agreement among raft nodes before linearized reading' (duration: 327.766296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.411696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.060166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:04.411685Z","caller":"traceutil/trace.go:171","msg":"trace[1920500726] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25613; }","duration":"277.055166ms","start":"2026-10-26T20:08:04.13461Z","end":"2026-10-26T20:08:04.411665Z","steps":["trace[1920500726] 'agreement among raft nodes before linearized reading' (duration: 276.911763ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.411695Z","caller":"traceutil/trace.go:171","msg":"trace[1826814324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25613; }","duration":"537.040219ms","start":"2026-10-26T20:08:03.874645Z","end":"2026-10-26T20:08:04.411685Z","steps":["trace[1826814324] 'agreement among raft nodes before linearized reading' (duration: 536.935916ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.41146Z","caller":"traceutil/trace.go:171","msg":"trace[1550309338] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"137.780919ms","start":"2026-10-26T20:08:04.273632Z","end":"2026-10-26T20:08:04.411413Z","steps":["trace[1550309338] 'process raft request' (duration: 137.421199ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:04.411714Z","caller":"traceutil/trace.go:171","msg":"trace[290622800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25613; }","duration":"138.107587ms","start":"2026-10-26T20:08:04.273601Z","end":"2026-10-26T20:08:04.411709Z","steps":["trace[290622800] 'agreement among raft nodes before linearized reading' (duration: 138.077206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:04.411731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:03.874629Z","time spent":"537.09405ms","remote":"127.0.0.1:45316","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":"warn","ts":"2026-10-26T20:08:04.411743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:04.083747Z","time spent":"327.962812ms","remote":"127.0.0.1:39516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:08:29.706876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22875} {"level":"info","ts":"2026-10-26T20:08:29.924018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22875,"took":"212.269924ms","hash":2130294470,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":37867520,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-26T20:08:29.924092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2130294470,"revision":22875,"compact-revision":20079} {"level":"info","ts":"2026-10-26T20:08:34.447493Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000689e.wal"} {"level":"warn","ts":"2026-10-26T20:08:35.319727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.492691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:08:35.319912Z","caller":"traceutil/trace.go:171","msg":"trace[51450712] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"269.017868ms","start":"2026-10-26T20:08:35.050854Z","end":"2026-10-26T20:08:35.319872Z","steps":["trace[51450712] 'process raft request' (duration: 35.27498ms)","trace[51450712] 'compare' (duration: 233.341457ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:35.319996Z","caller":"traceutil/trace.go:171","msg":"trace[1916537983] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"235.028243ms","start":"2026-10-26T20:08:35.084945Z","end":"2026-10-26T20:08:35.319973Z","steps":["trace[1916537983] 'process raft request' (duration: 234.91313ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:35.320059Z","caller":"traceutil/trace.go:171","msg":"trace[1482820508] linearizableReadLoop","detail":"{readStateIndex:26803; appliedIndex:26802; }","duration":"263.60727ms","start":"2026-10-26T20:08:35.056419Z","end":"2026-10-26T20:08:35.320026Z","steps":["trace[1482820508] 'read index received' (duration: 29.693878ms)","trace[1482820508] 'applied index is now lower than readState.Index' (duration: 233.911062ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:35.321156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.719481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:141 size:1359670"} {"level":"info","ts":"2026-10-26T20:08:35.32123Z","caller":"traceutil/trace.go:171","msg":"trace[989278540] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:141; response_revision:25954; }","duration":"264.840464ms","start":"2026-10-26T20:08:35.056376Z","end":"2026-10-26T20:08:35.321217Z","steps":["trace[989278540] 'agreement among raft nodes before linearized reading' (duration: 263.741294ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:35.53346Z","caller":"traceutil/trace.go:171","msg":"trace[663847368] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"169.365508ms","start":"2026-10-26T20:08:35.364068Z","end":"2026-10-26T20:08:35.533434Z","steps":["trace[663847368] 'process raft request' (duration: 145.588201ms)","trace[663847368] 'compare' (duration: 23.656323ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:35.545188Z","caller":"traceutil/trace.go:171","msg":"trace[1696714110] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"179.513414ms","start":"2026-10-26T20:08:35.365653Z","end":"2026-10-26T20:08:35.545166Z","steps":["trace[1696714110] 'process raft request' (duration: 178.97772ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:35.783677Z","caller":"traceutil/trace.go:171","msg":"trace[1203440199] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"243.00291ms","start":"2026-10-26T20:08:35.540654Z","end":"2026-10-26T20:08:35.783657Z","steps":["trace[1203440199] 'process raft request' (duration: 177.362614ms)","trace[1203440199] 'compare' (duration: 65.464841ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:35.802449Z","caller":"traceutil/trace.go:171","msg":"trace[36596471] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"246.473325ms","start":"2026-10-26T20:08:35.555947Z","end":"2026-10-26T20:08:35.80242Z","steps":["trace[36596471] 'process raft request' (duration: 246.29613ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:29.712721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25793} {"level":"info","ts":"2026-10-26T20:13:29.864256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25793,"took":"148.551951ms","hash":1867286171,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":35233792,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T20:13:29.864328Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1867286171,"revision":25793,"compact-revision":22875} {"level":"info","ts":"2026-10-26T20:14:24.768701Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"548b318b3528a14c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:14:24.781046Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:14:24.781223Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:18:29.721524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28665} {"level":"info","ts":"2026-10-26T20:18:29.864712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28665,"took":"139.564483ms","hash":2394193136,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":30490624,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:18:29.864765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2394193136,"revision":28665,"compact-revision":25793} {"level":"info","ts":"2026-10-26T20:23:29.729322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30884} {"level":"info","ts":"2026-10-26T20:23:29.860353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30884,"took":"127.452516ms","hash":1939685807,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":30404608,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:23:29.860411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1939685807,"revision":30884,"compact-revision":28665} {"level":"warn","ts":"2026-10-26T20:28:18.596754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.149513ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-26T20:28:29.73629Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33145} {"level":"info","ts":"2026-10-26T20:28:29.885919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33145,"took":"146.475567ms","hash":3422204711,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":30138368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:28:29.886007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3422204711,"revision":33145,"compact-revision":30884} {"level":"info","ts":"2026-10-26T20:33:29.744219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35373} {"level":"info","ts":"2026-10-26T20:33:29.87561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35373,"took":"128.305451ms","hash":1961605891,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":32239616,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:33:29.875675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1961605891,"revision":35373,"compact-revision":33145} {"level":"info","ts":"2026-10-26T20:35:52.548875Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"548b318b3528a14c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:35:52.556643Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:35:52.556791Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:38:29.753263Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37667} {"level":"info","ts":"2026-10-26T20:38:29.887371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37667,"took":"127.794027ms","hash":3091380103,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:38:29.887463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3091380103,"revision":37667,"compact-revision":35373} {"level":"info","ts":"2026-10-26T20:43:29.761647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39938} {"level":"info","ts":"2026-10-26T20:43:29.88655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39938,"took":"121.701238ms","hash":1961262802,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":30945280,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:43:29.886648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1961262802,"revision":39938,"compact-revision":37667}