{"level":"warn","ts":"2026-07-21T13:24:48.263826Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:24:48.263917Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-07-21T13:24:48.263984Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:24:48.263992Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-21T13:24:48.264012Z","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-07-21T13:24:48.264805Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-07-21T13:24:48.264896Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-07-21T13:24:48.267921Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.766421ms"} {"level":"info","ts":"2026-07-21T13:24:48.273655Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-07-21T13:24:48.273773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T13:24:48.273809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-07-21T13:24:48.273836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T13:24:48.273861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-07-21T13:24:48.273913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-07-21T13:24:48.278991Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T13:24:48.280484Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T13:24:48.281577Z","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-07-21T13:24:48.283849Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T13:24:48.284094Z","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-07-21T13:24:48.284214Z","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-07-21T13:24:48.284229Z","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-07-21T13:24:48.284021Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T13:24:48.286033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-07-21T13:24:48.286999Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-21T13:24:48.290054Z","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-07-21T13:24:48.290122Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-21T13:24:48.290154Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-21T13:24:48.290477Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T13:24:48.290556Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T13:24:48.374378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T13:24:48.374497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T13:24:48.37454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-07-21T13:24:48.374562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-07-21T13:24:48.37457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-21T13:24:48.374584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-07-21T13:24:48.374596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-21T13:24:48.375995Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T13:24:48.376019Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:24:48.376148Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:24:48.37629Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:24:48.376498Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T13:24:48.376588Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T13:24:48.377133Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:24:48.377229Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:24:48.377288Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:24:48.379617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T13:24:48.380244Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-07-21T13:25:34.440191Z","caller":"traceutil/trace.go:171","msg":"trace[1295356843] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"158.95221ms","start":"2026-07-21T13:25:34.28121Z","end":"2026-07-21T13:25:34.440163Z","steps":["trace[1295356843] 'process raft request' (duration: 154.431307ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:25:34.4402Z","caller":"traceutil/trace.go:171","msg":"trace[870084470] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"156.682583ms","start":"2026-07-21T13:25:34.283459Z","end":"2026-07-21T13:25:34.440141Z","steps":["trace[870084470] 'read index received' (duration: 151.852111ms)","trace[870084470] 'applied index is now lower than readState.Index' (duration: 4.828802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:25:34.440937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.438525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q6djg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-21T13:25:34.441049Z","caller":"traceutil/trace.go:171","msg":"trace[1008327076] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q6djg; range_end:; response_count:1; response_revision:595; }","duration":"157.635661ms","start":"2026-07-21T13:25:34.28339Z","end":"2026-07-21T13:25:34.441025Z","steps":["trace[1008327076] 'agreement among raft nodes before linearized reading' (duration: 156.859288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:25:34.852225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.90193ms","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-07-21T13:25:34.852298Z","caller":"traceutil/trace.go:171","msg":"trace[1051861903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"140.038454ms","start":"2026-07-21T13:25:34.712243Z","end":"2026-07-21T13:25:34.852282Z","steps":["trace[1051861903] 'range keys from in-memory index tree' (duration: 139.606772ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:25:35.400783Z","caller":"traceutil/trace.go:171","msg":"trace[1756911744] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"396.143489ms","start":"2026-07-21T13:25:35.004611Z","end":"2026-07-21T13:25:35.400754Z","steps":["trace[1756911744] 'read index received' (duration: 395.561871ms)","trace[1756911744] 'applied index is now lower than readState.Index' (duration: 580.368µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:25:35.400899Z","caller":"traceutil/trace.go:171","msg":"trace[1597238901] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"438.181824ms","start":"2026-07-21T13:25:34.962673Z","end":"2026-07-21T13:25:35.400855Z","steps":["trace[1597238901] 'process raft request' (duration: 437.649498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:25:35.401027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.400546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T13:25:35.401075Z","caller":"traceutil/trace.go:171","msg":"trace[1432768464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"396.51059ms","start":"2026-07-21T13:25:35.004553Z","end":"2026-07-21T13:25:35.401063Z","steps":["trace[1432768464] 'agreement among raft nodes before linearized reading' (duration: 396.348815ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:25:35.401049Z","caller":"traceutil/trace.go:171","msg":"trace[1770920542] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"390.071899ms","start":"2026-07-21T13:25:35.010944Z","end":"2026-07-21T13:25:35.401016Z","steps":["trace[1770920542] 'process raft request' (duration: 389.861753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:25:35.401113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:25:35.00453Z","time spent":"396.574281ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T13:25:35.401191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.427902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T13:25:35.401293Z","caller":"traceutil/trace.go:171","msg":"trace[606031642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"274.599217ms","start":"2026-07-21T13:25:35.126666Z","end":"2026-07-21T13:25:35.401265Z","steps":["trace[606031642] 'agreement among raft nodes before linearized reading' (duration: 274.303939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:25:35.401811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:25:34.962647Z","time spent":"438.344179ms","remote":"127.0.0.1:40948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:25:35.401827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:25:35.010924Z","time spent":"390.199623ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:25:51.593106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.094087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rg8vt\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-21T13:25:51.593207Z","caller":"traceutil/trace.go:171","msg":"trace[1649757487] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rg8vt; range_end:; response_count:1; response_revision:763; }","duration":"203.254552ms","start":"2026-07-21T13:25:51.389929Z","end":"2026-07-21T13:25:51.593184Z","steps":["trace[1649757487] 'range keys from in-memory index tree' (duration: 202.885401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:26:19.404825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.468267ms","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-07-21T13:26:19.404978Z","caller":"traceutil/trace.go:171","msg":"trace[482515145] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1155; }","duration":"141.712025ms","start":"2026-07-21T13:26:19.263235Z","end":"2026-07-21T13:26:19.404947Z","steps":["trace[482515145] 'count revisions from in-memory index tree' (duration: 141.24112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:27:07.608632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.240581ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-21T13:29:20.142798Z","caller":"traceutil/trace.go:171","msg":"trace[304554421] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"111.037067ms","start":"2026-07-21T13:29:20.031721Z","end":"2026-07-21T13:29:20.142759Z","steps":["trace[304554421] 'process raft request' (duration: 110.876122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:29:20.483324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.292897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:29:20.483324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.295049ms","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:5"} {"level":"info","ts":"2026-07-21T13:29:20.483431Z","caller":"traceutil/trace.go:171","msg":"trace[162191879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2789; }","duration":"130.467842ms","start":"2026-07-21T13:29:20.35294Z","end":"2026-07-21T13:29:20.483408Z","steps":["trace[162191879] 'range keys from in-memory index tree' (duration: 130.196294ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:29:20.483466Z","caller":"traceutil/trace.go:171","msg":"trace[440137817] 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:2789; }","duration":"163.453194ms","start":"2026-07-21T13:29:20.319991Z","end":"2026-07-21T13:29:20.483445Z","steps":["trace[440137817] 'count revisions from in-memory index tree' (duration: 163.161216ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:32:07.316589Z","caller":"traceutil/trace.go:171","msg":"trace[548570879] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"257.717293ms","start":"2026-07-21T13:32:07.058846Z","end":"2026-07-21T13:32:07.316563Z","steps":["trace[548570879] 'process raft request' (duration: 257.558178ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:32:07.400948Z","caller":"traceutil/trace.go:171","msg":"trace[778391348] transaction","detail":"{read_only:false; response_revision:3869; number_of_response:1; }","duration":"341.493175ms","start":"2026-07-21T13:32:07.059417Z","end":"2026-07-21T13:32:07.40091Z","steps":["trace[778391348] 'process raft request' (duration: 325.772225ms)","trace[778391348] 'compare' (duration: 15.256246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:32:07.401083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:32:07.059399Z","time spent":"341.615868ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:33:33.127668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.542901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:33:33.127816Z","caller":"traceutil/trace.go:171","msg":"trace[1178616512] linearizableReadLoop","detail":"{readStateIndex:5254; appliedIndex:5253; }","duration":"104.82215ms","start":"2026-07-21T13:33:33.022976Z","end":"2026-07-21T13:33:33.127798Z","steps":["trace[1178616512] 'read index received' (duration: 47.611µs)","trace[1178616512] 'applied index is now lower than readState.Index' (duration: 104.773149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:33:33.127906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.932362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4877ec8-685f-47b8-bc12-cf87d502797f\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-07-21T13:33:33.127936Z","caller":"traceutil/trace.go:171","msg":"trace[626142214] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4877ec8-685f-47b8-bc12-cf87d502797f; range_end:; response_count:1; response_revision:5055; }","duration":"104.998454ms","start":"2026-07-21T13:33:33.022927Z","end":"2026-07-21T13:33:33.127926Z","steps":["trace[626142214] 'agreement among raft nodes before linearized reading' (duration: 104.926202ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:33:33.128067Z","caller":"traceutil/trace.go:171","msg":"trace[1187013416] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"293.291306ms","start":"2026-07-21T13:33:32.834736Z","end":"2026-07-21T13:33:33.128027Z","steps":["trace[1187013416] 'process raft request' (duration: 156.300812ms)","trace[1187013416] 'compare' (duration: 136.275664ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:33:45.218667Z","caller":"traceutil/trace.go:171","msg":"trace[817649440] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5491; }","duration":"262.361188ms","start":"2026-07-21T13:33:44.956272Z","end":"2026-07-21T13:33:45.218633Z","steps":["trace[817649440] 'read index received' (duration: 262.235114ms)","trace[817649440] 'applied index is now lower than readState.Index' (duration: 124.754µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:33:45.218787Z","caller":"traceutil/trace.go:171","msg":"trace[1932028324] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"267.093957ms","start":"2026-07-21T13:33:44.951642Z","end":"2026-07-21T13:33:45.218736Z","steps":["trace[1932028324] 'process raft request' (duration: 266.782158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:33:45.218896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.610335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-21T13:33:45.218947Z","caller":"traceutil/trace.go:171","msg":"trace[369462429] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5285; }","duration":"262.712638ms","start":"2026-07-21T13:33:44.956215Z","end":"2026-07-21T13:33:45.218927Z","steps":["trace[369462429] 'agreement among raft nodes before linearized reading' (duration: 262.540343ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:34:49.032129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2951} {"level":"info","ts":"2026-07-21T13:34:49.099951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2951,"took":"66.321787ms","hash":1790609701,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":33906688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T13:34:49.100009Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1790609701,"revision":2951,"compact-revision":-1} {"level":"warn","ts":"2026-07-21T13:39:37.581813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.589112ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-21T13:39:49.08209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6027} {"level":"warn","ts":"2026-07-21T13:39:49.464144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.952207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:39:49.464267Z","caller":"traceutil/trace.go:171","msg":"trace[338742874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8256; }","duration":"113.093541ms","start":"2026-07-21T13:39:49.351149Z","end":"2026-07-21T13:39:49.464243Z","steps":["trace[338742874] 'range keys from in-memory index tree' (duration: 112.871214ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:39:49.815741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6027,"took":"731.24129ms","hash":1365715184,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":23560192,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-21T13:39:49.815912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1365715184,"revision":6027,"compact-revision":2951} {"level":"info","ts":"2026-07-21T13:39:50.371065Z","caller":"traceutil/trace.go:171","msg":"trace[2072942352] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"195.479949ms","start":"2026-07-21T13:39:50.175567Z","end":"2026-07-21T13:39:50.371047Z","steps":["trace[2072942352] 'process raft request' (duration: 166.441553ms)","trace[2072942352] 'compare' (duration: 28.796529ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:40:19.00025Z","caller":"traceutil/trace.go:171","msg":"trace[1202035386] linearizableReadLoop","detail":"{readStateIndex:8777; appliedIndex:8776; }","duration":"204.519955ms","start":"2026-07-21T13:40:18.795706Z","end":"2026-07-21T13:40:19.000226Z","steps":["trace[1202035386] 'read index received' (duration: 204.333249ms)","trace[1202035386] 'applied index is now lower than readState.Index' (duration: 185.806µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:40:19.000488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.829604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T13:40:19.000535Z","caller":"traceutil/trace.go:171","msg":"trace[89944731] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8469; }","duration":"204.888935ms","start":"2026-07-21T13:40:18.79563Z","end":"2026-07-21T13:40:19.000519Z","steps":["trace[89944731] 'agreement among raft nodes before linearized reading' (duration: 204.70722ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:40:19.000657Z","caller":"traceutil/trace.go:171","msg":"trace[1994768299] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"210.965444ms","start":"2026-07-21T13:40:18.789663Z","end":"2026-07-21T13:40:19.000629Z","steps":["trace[1994768299] 'process raft request' (duration: 210.433719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:40:31.47362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.680526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:40:31.474101Z","caller":"traceutil/trace.go:171","msg":"trace[1408478350] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"187.756161ms","start":"2026-07-21T13:40:31.286313Z","end":"2026-07-21T13:40:31.474069Z","steps":["trace[1408478350] 'process raft request' (duration: 187.416471ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:40:31.47415Z","caller":"traceutil/trace.go:171","msg":"trace[650630521] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"258.68057ms","start":"2026-07-21T13:40:31.21541Z","end":"2026-07-21T13:40:31.474091Z","steps":["trace[650630521] 'process raft request' (duration: 46.443368ms)","trace[650630521] 'compare' (duration: 211.569392ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:40:31.474132Z","caller":"traceutil/trace.go:171","msg":"trace[746438908] linearizableReadLoop","detail":"{readStateIndex:8887; appliedIndex:8885; }","duration":"166.292119ms","start":"2026-07-21T13:40:31.307655Z","end":"2026-07-21T13:40:31.473947Z","steps":["trace[746438908] 'read index received' (duration: 117.4826ms)","trace[746438908] 'applied index is now lower than readState.Index' (duration: 48.774458ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:40:31.47426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.604457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-64wqk\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-07-21T13:40:31.474329Z","caller":"traceutil/trace.go:171","msg":"trace[2048914475] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-64wqk; range_end:; response_count:1; response_revision:8574; }","duration":"166.68555ms","start":"2026-07-21T13:40:31.307615Z","end":"2026-07-21T13:40:31.474301Z","steps":["trace[2048914475] 'agreement among raft nodes before linearized reading' (duration: 166.528945ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:40:31.481612Z","caller":"traceutil/trace.go:171","msg":"trace[2001374613] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"168.03548ms","start":"2026-07-21T13:40:31.313557Z","end":"2026-07-21T13:40:31.481593Z","steps":["trace[2001374613] 'process raft request' (duration: 167.728971ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:40:32.437615Z","caller":"traceutil/trace.go:171","msg":"trace[1588220935] linearizableReadLoop","detail":"{readStateIndex:8893; appliedIndex:8891; }","duration":"202.826126ms","start":"2026-07-21T13:40:32.234758Z","end":"2026-07-21T13:40:32.437584Z","steps":["trace[1588220935] 'read index received' (duration: 13.975352ms)","trace[1588220935] 'applied index is now lower than readState.Index' (duration: 188.849723ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:40:32.43777Z","caller":"traceutil/trace.go:171","msg":"trace[1364432200] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"220.128424ms","start":"2026-07-21T13:40:32.217613Z","end":"2026-07-21T13:40:32.437742Z","steps":["trace[1364432200] 'process raft request' (duration: 139.690124ms)","trace[1364432200] 'compare' (duration: 80.161982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:40:32.437848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.065752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:40:32.437958Z","caller":"traceutil/trace.go:171","msg":"trace[1504709440] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8580; }","duration":"203.230707ms","start":"2026-07-21T13:40:32.234702Z","end":"2026-07-21T13:40:32.437933Z","steps":["trace[1504709440] 'agreement among raft nodes before linearized reading' (duration: 203.065482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:40:32.466059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.229497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-21T13:40:32.466069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.850173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:40:32.466138Z","caller":"traceutil/trace.go:171","msg":"trace[1077706044] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:8581; }","duration":"221.35007ms","start":"2026-07-21T13:40:32.244769Z","end":"2026-07-21T13:40:32.466119Z","steps":["trace[1077706044] 'agreement among raft nodes before linearized reading' (duration: 221.139754ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:40:32.466153Z","caller":"traceutil/trace.go:171","msg":"trace[849358945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8581; }","duration":"114.965896ms","start":"2026-07-21T13:40:32.351167Z","end":"2026-07-21T13:40:32.466133Z","steps":["trace[849358945] 'agreement among raft nodes before linearized reading' (duration: 114.807342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:40:32.466507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.213041ms","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-07-21T13:40:32.466524Z","caller":"traceutil/trace.go:171","msg":"trace[1896476935] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"149.575456ms","start":"2026-07-21T13:40:32.31692Z","end":"2026-07-21T13:40:32.466496Z","steps":["trace[1896476935] 'process raft request' (duration: 148.750322ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:40:32.466612Z","caller":"traceutil/trace.go:171","msg":"trace[654449340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8581; }","duration":"174.356696ms","start":"2026-07-21T13:40:32.292239Z","end":"2026-07-21T13:40:32.466596Z","steps":["trace[654449340] 'agreement among raft nodes before linearized reading' (duration: 174.090648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:40:32.466631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.907733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T13:40:32.466718Z","caller":"traceutil/trace.go:171","msg":"trace[1097660663] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8581; }","duration":"209.027167ms","start":"2026-07-21T13:40:32.257662Z","end":"2026-07-21T13:40:32.466689Z","steps":["trace[1097660663] 'agreement among raft nodes before linearized reading' (duration: 208.837721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:40:32.466897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.029189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-64wqk\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-07-21T13:40:32.466963Z","caller":"traceutil/trace.go:171","msg":"trace[2008141530] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-64wqk; range_end:; response_count:1; response_revision:8581; }","duration":"150.182913ms","start":"2026-07-21T13:40:32.316765Z","end":"2026-07-21T13:40:32.466948Z","steps":["trace[2008141530] 'agreement among raft nodes before linearized reading' (duration: 149.845273ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:41:20.463962Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ac.wal"} {"level":"info","ts":"2026-07-21T13:41:43.11553Z","caller":"traceutil/trace.go:171","msg":"trace[1558200171] transaction","detail":"{read_only:false; response_revision:9299; number_of_response:1; }","duration":"124.085355ms","start":"2026-07-21T13:41:42.99139Z","end":"2026-07-21T13:41:43.115476Z","steps":["trace[1558200171] 'process raft request' (duration: 123.873739ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:42:32.604474Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T13:42:32.612743Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-21T13:42:32.61286Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-21T13:44:49.087938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8254} {"level":"info","ts":"2026-07-21T13:44:49.164406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8254,"took":"74.25584ms","hash":241823090,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":25067520,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-21T13:44:49.164455Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":241823090,"revision":8254,"compact-revision":6027} {"level":"info","ts":"2026-07-21T13:45:23.835429Z","caller":"traceutil/trace.go:171","msg":"trace[722156086] transaction","detail":"{read_only:false; response_revision:11128; number_of_response:1; }","duration":"151.755166ms","start":"2026-07-21T13:45:23.683624Z","end":"2026-07-21T13:45:23.83538Z","steps":["trace[722156086] 'process raft request' (duration: 151.581311ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:49:49.093942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10749} {"level":"info","ts":"2026-07-21T13:49:49.17872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10749,"took":"80.440861ms","hash":2854377988,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":27758592,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-21T13:49:49.178824Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2854377988,"revision":10749,"compact-revision":8254} {"level":"info","ts":"2026-07-21T13:51:47.577501Z","caller":"traceutil/trace.go:171","msg":"trace[1293629590] transaction","detail":"{read_only:false; response_revision:13719; number_of_response:1; }","duration":"263.928872ms","start":"2026-07-21T13:51:47.313547Z","end":"2026-07-21T13:51:47.577475Z","steps":["trace[1293629590] 'process raft request' (duration: 263.780767ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:51:47.577819Z","caller":"traceutil/trace.go:171","msg":"trace[578163328] linearizableReadLoop","detail":"{readStateIndex:14196; appliedIndex:14196; }","duration":"227.654822ms","start":"2026-07-21T13:51:47.350138Z","end":"2026-07-21T13:51:47.577792Z","steps":["trace[578163328] 'read index received' (duration: 227.646492ms)","trace[578163328] 'applied index is now lower than readState.Index' (duration: 6.33µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:51:47.578171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.012024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:51:47.578237Z","caller":"traceutil/trace.go:171","msg":"trace[1331879612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13719; }","duration":"228.126317ms","start":"2026-07-21T13:51:47.350096Z","end":"2026-07-21T13:51:47.578222Z","steps":["trace[1331879612] 'agreement among raft nodes before linearized reading' (duration: 227.865569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:51:48.162352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.982018ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-21T13:51:48.162558Z","caller":"traceutil/trace.go:171","msg":"trace[238546789] linearizableReadLoop","detail":"{readStateIndex:14199; appliedIndex:14198; }","duration":"392.12754ms","start":"2026-07-21T13:51:47.770417Z","end":"2026-07-21T13:51:48.162544Z","steps":["trace[238546789] 'read index received' (duration: 41.984907ms)","trace[238546789] 'applied index is now lower than readState.Index' (duration: 350.142033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:51:48.162689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.285915ms","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-07-21T13:51:48.162708Z","caller":"traceutil/trace.go:171","msg":"trace[1226963401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13721; }","duration":"392.313566ms","start":"2026-07-21T13:51:47.770389Z","end":"2026-07-21T13:51:48.162702Z","steps":["trace[1226963401] 'agreement among raft nodes before linearized reading' (duration: 392.214183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:51:48.162727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:51:47.77034Z","time spent":"392.383229ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T13:51:48.162793Z","caller":"traceutil/trace.go:171","msg":"trace[1298515857] transaction","detail":"{read_only:false; response_revision:13722; number_of_response:1; }","duration":"235.689609ms","start":"2026-07-21T13:51:47.927071Z","end":"2026-07-21T13:51:48.16276Z","steps":["trace[1298515857] 'process raft request' (duration: 235.3993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:51:48.178442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.94178ms","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-07-21T13:51:48.178538Z","caller":"traceutil/trace.go:171","msg":"trace[1467522912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13723; }","duration":"384.134325ms","start":"2026-07-21T13:51:47.794384Z","end":"2026-07-21T13:51:48.178519Z","steps":["trace[1467522912] 'agreement among raft nodes before linearized reading' (duration: 383.881347ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:51:48.178516Z","caller":"traceutil/trace.go:171","msg":"trace[305923069] transaction","detail":"{read_only:false; response_revision:13723; number_of_response:1; }","duration":"248.725603ms","start":"2026-07-21T13:51:47.929762Z","end":"2026-07-21T13:51:48.178487Z","steps":["trace[305923069] 'process raft request' (duration: 247.708223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:51:48.178584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:51:47.794363Z","time spent":"384.209917ms","remote":"127.0.0.1:39580","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-07-21T13:51:48.17877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.143272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-brnxp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-21T13:51:48.178805Z","caller":"traceutil/trace.go:171","msg":"trace[1138085581] range","detail":"{range_begin:/registry/pods/openstack/keepalived-brnxp; range_end:; response_count:1; response_revision:13723; }","duration":"198.224865ms","start":"2026-07-21T13:51:47.980568Z","end":"2026-07-21T13:51:48.178793Z","steps":["trace[1138085581] 'agreement among raft nodes before linearized reading' (duration: 198.087911ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:54:49.109847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12929} {"level":"info","ts":"2026-07-21T13:54:49.188592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12929,"took":"76.261234ms","hash":299481760,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":31055872,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-21T13:54:49.188649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":299481760,"revision":12929,"compact-revision":10749} {"level":"info","ts":"2026-07-21T13:59:10.699197Z","caller":"traceutil/trace.go:171","msg":"trace[1254645913] transaction","detail":"{read_only:false; response_revision:17497; number_of_response:1; }","duration":"126.444465ms","start":"2026-07-21T13:59:10.572721Z","end":"2026-07-21T13:59:10.699166Z","steps":["trace[1254645913] 'process raft request' (duration: 125.998531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:59:12.046277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.700077ms","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-07-21T13:59:12.046363Z","caller":"traceutil/trace.go:171","msg":"trace[1804099051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17511; }","duration":"105.848612ms","start":"2026-07-21T13:59:11.940496Z","end":"2026-07-21T13:59:12.046344Z","steps":["trace[1804099051] 'range keys from in-memory index tree' (duration: 105.552933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:59:16.252835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.431184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T13:59:16.252948Z","caller":"traceutil/trace.go:171","msg":"trace[1712703909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17546; }","duration":"171.592189ms","start":"2026-07-21T13:59:16.081331Z","end":"2026-07-21T13:59:16.252923Z","steps":["trace[1712703909] 'range keys from in-memory index tree' (duration: 171.256839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:59:16.25297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.039519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-21T13:59:16.253049Z","caller":"traceutil/trace.go:171","msg":"trace[989570511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17546; }","duration":"133.202684ms","start":"2026-07-21T13:59:16.119828Z","end":"2026-07-21T13:59:16.25303Z","steps":["trace[989570511] 'range keys from in-memory index tree' (duration: 132.73579ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:59:16.409343Z","caller":"traceutil/trace.go:171","msg":"trace[1206809198] transaction","detail":"{read_only:false; response_revision:17547; number_of_response:1; }","duration":"152.063906ms","start":"2026-07-21T13:59:16.257252Z","end":"2026-07-21T13:59:16.409316Z","steps":["trace[1206809198] 'process raft request' (duration: 151.912492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:59:16.599057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.168083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T13:59:16.599683Z","caller":"traceutil/trace.go:171","msg":"trace[1912405483] linearizableReadLoop","detail":"{readStateIndex:18146; appliedIndex:18144; }","duration":"212.382704ms","start":"2026-07-21T13:59:16.387279Z","end":"2026-07-21T13:59:16.599661Z","steps":["trace[1912405483] 'read index received' (duration: 21.893552ms)","trace[1912405483] 'applied index is now lower than readState.Index' (duration: 190.486962ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:59:16.59979Z","caller":"traceutil/trace.go:171","msg":"trace[1081952110] transaction","detail":"{read_only:false; response_revision:17548; number_of_response:1; }","duration":"341.498389ms","start":"2026-07-21T13:59:16.258255Z","end":"2026-07-21T13:59:16.599753Z","steps":["trace[1081952110] 'process raft request' (duration: 207.517962ms)","trace[1081952110] 'compare' (duration: 133.03871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:59:16.59999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.691004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T13:59:16.600552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.651366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-21T13:59:16.600582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.155513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-21T13:59:16.600621Z","caller":"traceutil/trace.go:171","msg":"trace[1621992703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17548; }","duration":"134.752259ms","start":"2026-07-21T13:59:16.465845Z","end":"2026-07-21T13:59:16.600598Z","steps":["trace[1621992703] 'agreement among raft nodes before linearized reading' (duration: 134.564224ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:59:16.600663Z","caller":"traceutil/trace.go:171","msg":"trace[1773329671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17548; }","duration":"132.272866ms","start":"2026-07-21T13:59:16.46837Z","end":"2026-07-21T13:59:16.600643Z","steps":["trace[1773329671] 'agreement among raft nodes before linearized reading' (duration: 132.06101ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:59:16.600652Z","caller":"traceutil/trace.go:171","msg":"trace[1424841857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17548; }","duration":"213.372693ms","start":"2026-07-21T13:59:16.387226Z","end":"2026-07-21T13:59:16.600598Z","steps":["trace[1424841857] 'agreement among raft nodes before linearized reading' (duration: 212.658803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:59:16.600523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:59:16.258237Z","time spent":"342.084755ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:59:49.116537Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15445} {"level":"info","ts":"2026-07-21T13:59:49.216582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15445,"took":"95.818818ms","hash":3751324485,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":33492992,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T13:59:49.216665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3751324485,"revision":15445,"compact-revision":12929} {"level":"info","ts":"2026-07-21T14:02:46.235939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T14:02:46.246575Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-21T14:02:46.246729Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-21T14:04:14.309312Z","caller":"traceutil/trace.go:171","msg":"trace[1160748947] transaction","detail":"{read_only:false; response_revision:19951; number_of_response:1; }","duration":"135.10895ms","start":"2026-07-21T14:04:14.174173Z","end":"2026-07-21T14:04:14.309282Z","steps":["trace[1160748947] 'process raft request' (duration: 134.966636ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:14.400423Z","caller":"traceutil/trace.go:171","msg":"trace[1825429812] transaction","detail":"{read_only:false; response_revision:19952; number_of_response:1; }","duration":"225.658455ms","start":"2026-07-21T14:04:14.174745Z","end":"2026-07-21T14:04:14.400403Z","steps":["trace[1825429812] 'process raft request' (duration: 224.78665ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:14.400546Z","caller":"traceutil/trace.go:171","msg":"trace[1321592687] linearizableReadLoop","detail":"{readStateIndex:20629; appliedIndex:20627; }","duration":"224.490442ms","start":"2026-07-21T14:04:14.176047Z","end":"2026-07-21T14:04:14.400537Z","steps":["trace[1321592687] 'read index received' (duration: 133.107623ms)","trace[1321592687] 'applied index is now lower than readState.Index' (duration: 91.381929ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:04:14.400514Z","caller":"traceutil/trace.go:171","msg":"trace[933826495] transaction","detail":"{read_only:false; response_revision:19953; number_of_response:1; }","duration":"223.188363ms","start":"2026-07-21T14:04:14.177292Z","end":"2026-07-21T14:04:14.40048Z","steps":["trace[933826495] 'process raft request' (duration: 223.037758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:14.400701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.640826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T14:04:14.400729Z","caller":"traceutil/trace.go:171","msg":"trace[1708247462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19954; }","duration":"224.711848ms","start":"2026-07-21T14:04:14.176008Z","end":"2026-07-21T14:04:14.40072Z","steps":["trace[1708247462] 'agreement among raft nodes before linearized reading' (duration: 224.587434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:14.400938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.885166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-07-21T14:04:14.401103Z","caller":"traceutil/trace.go:171","msg":"trace[1742036386] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:19954; }","duration":"190.088622ms","start":"2026-07-21T14:04:14.210988Z","end":"2026-07-21T14:04:14.401077Z","steps":["trace[1742036386] 'agreement among raft nodes before linearized reading' (duration: 189.794274ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:30.214266Z","caller":"traceutil/trace.go:171","msg":"trace[1275694001] transaction","detail":"{read_only:false; response_revision:20030; number_of_response:1; }","duration":"112.493388ms","start":"2026-07-21T14:04:30.101731Z","end":"2026-07-21T14:04:30.214224Z","steps":["trace[1275694001] 'process raft request' (duration: 112.2238ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:30.539343Z","caller":"traceutil/trace.go:171","msg":"trace[857496804] transaction","detail":"{read_only:false; response_revision:20031; number_of_response:1; }","duration":"125.830829ms","start":"2026-07-21T14:04:30.413487Z","end":"2026-07-21T14:04:30.539318Z","steps":["trace[857496804] 'process raft request' (duration: 125.682244ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:30.847954Z","caller":"traceutil/trace.go:171","msg":"trace[1314824291] linearizableReadLoop","detail":"{readStateIndex:20712; appliedIndex:20711; }","duration":"375.950151ms","start":"2026-07-21T14:04:30.471973Z","end":"2026-07-21T14:04:30.847923Z","steps":["trace[1314824291] 'read index received' (duration: 67.372826ms)","trace[1314824291] 'applied index is now lower than readState.Index' (duration: 308.576175ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:04:30.848042Z","caller":"traceutil/trace.go:171","msg":"trace[563743771] transaction","detail":"{read_only:false; response_revision:20032; number_of_response:1; }","duration":"396.521894ms","start":"2026-07-21T14:04:30.451468Z","end":"2026-07-21T14:04:30.84799Z","steps":["trace[563743771] 'process raft request' (duration: 396.284857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:30.848217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.278893ms","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-07-21T14:04:30.848264Z","caller":"traceutil/trace.go:171","msg":"trace[1912627005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20032; }","duration":"372.393856ms","start":"2026-07-21T14:04:30.475859Z","end":"2026-07-21T14:04:30.848253Z","steps":["trace[1912627005] 'agreement among raft nodes before linearized reading' (duration: 372.223861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:30.848305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:04:30.475834Z","time spent":"372.460058ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T14:04:30.848377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:04:30.451439Z","time spent":"396.755641ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:04:30.848995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.984031ms","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-07-21T14:04:30.849141Z","caller":"traceutil/trace.go:171","msg":"trace[2143349705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20032; }","duration":"377.167526ms","start":"2026-07-21T14:04:30.471935Z","end":"2026-07-21T14:04:30.849103Z","steps":["trace[2143349705] 'agreement among raft nodes before linearized reading' (duration: 376.806256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:30.849241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:04:30.471911Z","time spent":"377.314811ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T14:04:30.849238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.567234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-21T14:04:30.849384Z","caller":"traceutil/trace.go:171","msg":"trace[750233583] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20032; }","duration":"367.77921ms","start":"2026-07-21T14:04:30.481567Z","end":"2026-07-21T14:04:30.849346Z","steps":["trace[750233583] 'agreement among raft nodes before linearized reading' (duration: 367.252485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:30.849831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:04:30.481492Z","time spent":"368.321016ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-21T14:04:30.849957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.043558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-21T14:04:30.850055Z","caller":"traceutil/trace.go:171","msg":"trace[317776537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20032; }","duration":"368.175442ms","start":"2026-07-21T14:04:30.481854Z","end":"2026-07-21T14:04:30.85003Z","steps":["trace[317776537] 'agreement among raft nodes before linearized reading' (duration: 367.877483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:30.850113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:04:30.481847Z","time spent":"368.254874ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T14:04:30.990737Z","caller":"traceutil/trace.go:171","msg":"trace[1198960089] linearizableReadLoop","detail":"{readStateIndex:20714; appliedIndex:20712; }","duration":"136.248825ms","start":"2026-07-21T14:04:30.854465Z","end":"2026-07-21T14:04:30.990714Z","steps":["trace[1198960089] 'read index received' (duration: 135.996337ms)","trace[1198960089] 'applied index is now lower than readState.Index' (duration: 251.568µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:04:30.990861Z","caller":"traceutil/trace.go:171","msg":"trace[758618430] transaction","detail":"{read_only:false; response_revision:20034; number_of_response:1; }","duration":"137.040647ms","start":"2026-07-21T14:04:30.853785Z","end":"2026-07-21T14:04:30.990826Z","steps":["trace[758618430] 'process raft request' (duration: 136.882542ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:30.990775Z","caller":"traceutil/trace.go:171","msg":"trace[2118562611] transaction","detail":"{read_only:false; response_revision:20033; number_of_response:1; }","duration":"136.959505ms","start":"2026-07-21T14:04:30.853784Z","end":"2026-07-21T14:04:30.990744Z","steps":["trace[2118562611] 'process raft request' (duration: 136.659626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:30.991162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.644936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-21T14:04:30.991282Z","caller":"traceutil/trace.go:171","msg":"trace[1876055649] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20034; }","duration":"136.823671ms","start":"2026-07-21T14:04:30.854431Z","end":"2026-07-21T14:04:30.991255Z","steps":["trace[1876055649] 'agreement among raft nodes before linearized reading' (duration: 136.41669ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:31.065672Z","caller":"traceutil/trace.go:171","msg":"trace[1174275841] transaction","detail":"{read_only:false; response_revision:20036; number_of_response:1; }","duration":"209.957125ms","start":"2026-07-21T14:04:30.8557Z","end":"2026-07-21T14:04:31.065657Z","steps":["trace[1174275841] 'process raft request' (duration: 209.685467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:31.066014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.36772ms","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-07-21T14:04:31.066137Z","caller":"traceutil/trace.go:171","msg":"trace[1017565777] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:20036; }","duration":"117.559816ms","start":"2026-07-21T14:04:30.948553Z","end":"2026-07-21T14:04:31.066113Z","steps":["trace[1017565777] 'agreement among raft nodes before linearized reading' (duration: 117.160733ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:31.065517Z","caller":"traceutil/trace.go:171","msg":"trace[2014870350] transaction","detail":"{read_only:false; response_revision:20035; number_of_response:1; }","duration":"210.780678ms","start":"2026-07-21T14:04:30.854693Z","end":"2026-07-21T14:04:31.065473Z","steps":["trace[2014870350] 'process raft request' (duration: 210.567722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:31.233364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.60368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:04:31.233456Z","caller":"traceutil/trace.go:171","msg":"trace[1301522563] transaction","detail":"{read_only:false; response_revision:20037; number_of_response:1; }","duration":"166.200831ms","start":"2026-07-21T14:04:31.067239Z","end":"2026-07-21T14:04:31.23344Z","steps":["trace[1301522563] 'process raft request' (duration: 51.441847ms)","trace[1301522563] 'compare' (duration: 114.393994ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:04:31.512387Z","caller":"traceutil/trace.go:171","msg":"trace[1459642446] linearizableReadLoop","detail":"{readStateIndex:20718; appliedIndex:20717; }","duration":"382.522563ms","start":"2026-07-21T14:04:31.129825Z","end":"2026-07-21T14:04:31.512348Z","steps":["trace[1459642446] 'read index received' (duration: 339.4594ms)","trace[1459642446] 'applied index is now lower than readState.Index' (duration: 43.061413ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:04:31.512541Z","caller":"traceutil/trace.go:171","msg":"trace[2032598413] transaction","detail":"{read_only:false; response_revision:20038; number_of_response:1; }","duration":"388.172409ms","start":"2026-07-21T14:04:31.124318Z","end":"2026-07-21T14:04:31.51249Z","steps":["trace[2032598413] 'process raft request' (duration: 345.047856ms)","trace[2032598413] 'compare' (duration: 42.828855ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:04:31.512659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.715387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:04:31.512708Z","caller":"traceutil/trace.go:171","msg":"trace[1972630250] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:20038; }","duration":"151.81504ms","start":"2026-07-21T14:04:31.360878Z","end":"2026-07-21T14:04:31.512693Z","steps":["trace[1972630250] 'agreement among raft nodes before linearized reading' (duration: 151.685856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:31.512749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:04:31.124293Z","time spent":"388.344704ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:04:31.512751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.840262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:04:31.512843Z","caller":"traceutil/trace.go:171","msg":"trace[465447074] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20038; }","duration":"383.024118ms","start":"2026-07-21T14:04:31.129795Z","end":"2026-07-21T14:04:31.512819Z","steps":["trace[465447074] 'agreement among raft nodes before linearized reading' (duration: 382.74311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:04:31.512894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:04:31.129781Z","time spent":"383.10461ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-21T14:04:31.513575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.741901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:04:31.513673Z","caller":"traceutil/trace.go:171","msg":"trace[2024538302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20038; }","duration":"162.795442ms","start":"2026-07-21T14:04:31.350852Z","end":"2026-07-21T14:04:31.513648Z","steps":["trace[2024538302] 'agreement among raft nodes before linearized reading' (duration: 161.715211ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:04:49.123332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17981} {"level":"info","ts":"2026-07-21T14:04:49.253219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17981,"took":"124.268003ms","hash":2533524936,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":29921280,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-21T14:04:49.253288Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2533524936,"revision":17981,"compact-revision":15445} {"level":"warn","ts":"2026-07-21T14:08:18.006507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.585801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:08:18.006618Z","caller":"traceutil/trace.go:171","msg":"trace[1685127715] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:22472; }","duration":"168.753707ms","start":"2026-07-21T14:08:17.837834Z","end":"2026-07-21T14:08:18.006588Z","steps":["trace[1685127715] 'count revisions from in-memory index tree' (duration: 168.477538ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:08:18.007168Z","caller":"traceutil/trace.go:171","msg":"trace[411630320] transaction","detail":"{read_only:false; response_revision:22473; number_of_response:1; }","duration":"136.489931ms","start":"2026-07-21T14:08:17.87065Z","end":"2026-07-21T14:08:18.00714Z","steps":["trace[411630320] 'process raft request' (duration: 134.595875ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:09:49.130635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20126} {"level":"info","ts":"2026-07-21T14:09:49.23693Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20126,"took":"102.66544ms","hash":524444428,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":43405312,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-21T14:09:49.237002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":524444428,"revision":20126,"compact-revision":17981} {"level":"info","ts":"2026-07-21T14:09:51.527732Z","caller":"traceutil/trace.go:171","msg":"trace[1742692522] transaction","detail":"{read_only:false; response_revision:23436; number_of_response:1; }","duration":"112.24457ms","start":"2026-07-21T14:09:51.415461Z","end":"2026-07-21T14:09:51.527706Z","steps":["trace[1742692522] 'process raft request' (duration: 112.063455ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:44.651505Z","caller":"traceutil/trace.go:171","msg":"trace[8946207] linearizableReadLoop","detail":"{readStateIndex:25380; appliedIndex:25379; }","duration":"191.518564ms","start":"2026-07-21T14:11:44.459954Z","end":"2026-07-21T14:11:44.651473Z","steps":["trace[8946207] 'read index received' (duration: 191.357399ms)","trace[8946207] 'applied index is now lower than readState.Index' (duration: 159.265µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:44.651609Z","caller":"traceutil/trace.go:171","msg":"trace[1333113518] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"238.802223ms","start":"2026-07-21T14:11:44.412773Z","end":"2026-07-21T14:11:44.651575Z","steps":["trace[1333113518] 'process raft request' (duration: 238.489524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:44.651772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.804123ms","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-07-21T14:11:44.651839Z","caller":"traceutil/trace.go:171","msg":"trace[1798189565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24575; }","duration":"191.922816ms","start":"2026-07-21T14:11:44.459901Z","end":"2026-07-21T14:11:44.651824Z","steps":["trace[1798189565] 'agreement among raft nodes before linearized reading' (duration: 191.691769ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:44.664972Z","caller":"traceutil/trace.go:171","msg":"trace[303534051] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"189.146883ms","start":"2026-07-21T14:11:44.475793Z","end":"2026-07-21T14:11:44.66494Z","steps":["trace[303534051] 'process raft request' (duration: 188.917357ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:44.665137Z","caller":"traceutil/trace.go:171","msg":"trace[1915074659] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"187.913907ms","start":"2026-07-21T14:11:44.477199Z","end":"2026-07-21T14:11:44.665113Z","steps":["trace[1915074659] 'process raft request' (duration: 187.635779ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:44.809662Z","caller":"traceutil/trace.go:171","msg":"trace[141996234] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"153.137347ms","start":"2026-07-21T14:11:44.656485Z","end":"2026-07-21T14:11:44.809623Z","steps":["trace[141996234] 'process raft request' (duration: 54.342526ms)","trace[141996234] 'compare' (duration: 98.605326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:45.117572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.031674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:45.117661Z","caller":"traceutil/trace.go:171","msg":"trace[73054304] linearizableReadLoop","detail":"{readStateIndex:25385; appliedIndex:25384; }","duration":"261.615792ms","start":"2026-07-21T14:11:44.856032Z","end":"2026-07-21T14:11:45.117647Z","steps":["trace[73054304] 'read index received' (duration: 108.419263ms)","trace[73054304] 'applied index is now lower than readState.Index' (duration: 153.195659ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:45.117738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.724844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:45.117759Z","caller":"traceutil/trace.go:171","msg":"trace[1864862791] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24580; }","duration":"261.797587ms","start":"2026-07-21T14:11:44.855953Z","end":"2026-07-21T14:11:45.117751Z","steps":["trace[1864862791] 'agreement among raft nodes before linearized reading' (duration: 261.728135ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:45.117803Z","caller":"traceutil/trace.go:171","msg":"trace[40090025] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"304.649635ms","start":"2026-07-21T14:11:44.813115Z","end":"2026-07-21T14:11:45.117765Z","steps":["trace[40090025] 'process raft request' (duration: 151.333433ms)","trace[40090025] 'compare' (duration: 152.914281ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:45.117943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:44.813077Z","time spent":"304.79211ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:49.406443Z","caller":"traceutil/trace.go:171","msg":"trace[96434397] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"271.834441ms","start":"2026-07-21T14:11:49.134517Z","end":"2026-07-21T14:11:49.406351Z","steps":["trace[96434397] 'process raft request' (duration: 271.541963ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:49.428007Z","caller":"traceutil/trace.go:171","msg":"trace[817775777] linearizableReadLoop","detail":"{readStateIndex:25425; appliedIndex:25423; }","duration":"231.529458ms","start":"2026-07-21T14:11:49.196436Z","end":"2026-07-21T14:11:49.427965Z","steps":["trace[817775777] 'read index received' (duration: 209.626985ms)","trace[817775777] 'applied index is now lower than readState.Index' (duration: 21.900843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:49.428351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.891859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-21T14:11:49.428407Z","caller":"traceutil/trace.go:171","msg":"trace[1014822937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24619; }","duration":"232.054744ms","start":"2026-07-21T14:11:49.196339Z","end":"2026-07-21T14:11:49.428394Z","steps":["trace[1014822937] 'agreement among raft nodes before linearized reading' (duration: 231.795356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:49.428811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.043715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-21T14:11:49.428911Z","caller":"traceutil/trace.go:171","msg":"trace[323986115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24619; }","duration":"229.1885ms","start":"2026-07-21T14:11:49.199705Z","end":"2026-07-21T14:11:49.428893Z","steps":["trace[323986115] 'agreement among raft nodes before linearized reading' (duration: 229.014645ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:49.428974Z","caller":"traceutil/trace.go:171","msg":"trace[721231431] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"275.892812ms","start":"2026-07-21T14:11:49.153048Z","end":"2026-07-21T14:11:49.428941Z","steps":["trace[721231431] 'process raft request' (duration: 274.719448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:49.429332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.922043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T14:11:49.429379Z","caller":"traceutil/trace.go:171","msg":"trace[65357495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24619; }","duration":"165.039446ms","start":"2026-07-21T14:11:49.264326Z","end":"2026-07-21T14:11:49.429366Z","steps":["trace[65357495] 'agreement among raft nodes before linearized reading' (duration: 164.853091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:49.429538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.098805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-21T14:11:49.429645Z","caller":"traceutil/trace.go:171","msg":"trace[511980695] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:24619; }","duration":"185.2641ms","start":"2026-07-21T14:11:49.244351Z","end":"2026-07-21T14:11:49.429615Z","steps":["trace[511980695] 'agreement among raft nodes before linearized reading' (duration: 184.9078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:49.722102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.066044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:49.722216Z","caller":"traceutil/trace.go:171","msg":"trace[1916924230] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"287.651707ms","start":"2026-07-21T14:11:49.434545Z","end":"2026-07-21T14:11:49.722196Z","steps":["trace[1916924230] 'process raft request' (duration: 149.375996ms)","trace[1916924230] 'compare' (duration: 137.961742ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:49.734868Z","caller":"traceutil/trace.go:171","msg":"trace[1518665296] linearizableReadLoop","detail":"{readStateIndex:25427; appliedIndex:25425; }","duration":"297.161526ms","start":"2026-07-21T14:11:49.437691Z","end":"2026-07-21T14:11:49.734852Z","steps":["trace[1518665296] 'read index received' (duration: 146.226884ms)","trace[1518665296] 'applied index is now lower than readState.Index' (duration: 150.934012ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:49.734939Z","caller":"traceutil/trace.go:171","msg":"trace[580770684] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"300.218625ms","start":"2026-07-21T14:11:49.434715Z","end":"2026-07-21T14:11:49.734933Z","steps":["trace[580770684] 'process raft request' (duration: 300.05005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:49.734995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:49.434673Z","time spent":"300.277947ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:49.735119Z","caller":"traceutil/trace.go:171","msg":"trace[976820664] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"294.037094ms","start":"2026-07-21T14:11:49.441076Z","end":"2026-07-21T14:11:49.735114Z","steps":["trace[976820664] 'process raft request' (duration: 293.752655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:49.73526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.569368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e0f31dd6-8672-4333-8484-ce5ee49d7eb0\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-07-21T14:11:49.735277Z","caller":"traceutil/trace.go:171","msg":"trace[1745283384] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e0f31dd6-8672-4333-8484-ce5ee49d7eb0; range_end:; response_count:1; response_revision:24622; }","duration":"297.610398ms","start":"2026-07-21T14:11:49.43766Z","end":"2026-07-21T14:11:49.735271Z","steps":["trace[1745283384] 'agreement among raft nodes before linearized reading' (duration: 297.528246ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:50.037216Z","caller":"traceutil/trace.go:171","msg":"trace[1059784718] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"378.169985ms","start":"2026-07-21T14:11:49.659024Z","end":"2026-07-21T14:11:50.037194Z","steps":["trace[1059784718] 'process raft request' (duration: 307.982974ms)","trace[1059784718] 'compare' (duration: 69.949544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:50.037347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:49.658985Z","time spent":"378.285088ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:50.037185Z","caller":"traceutil/trace.go:171","msg":"trace[231899691] linearizableReadLoop","detail":"{readStateIndex:25429; appliedIndex:25428; }","duration":"302.233374ms","start":"2026-07-21T14:11:49.734908Z","end":"2026-07-21T14:11:50.037141Z","steps":["trace[231899691] 'read index received' (duration: 232.119165ms)","trace[231899691] 'applied index is now lower than readState.Index' (duration: 70.112859ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:50.037406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.755495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-21T14:11:50.037466Z","caller":"traceutil/trace.go:171","msg":"trace[191883880] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24623; }","duration":"545.864658ms","start":"2026-07-21T14:11:49.491583Z","end":"2026-07-21T14:11:50.037448Z","steps":["trace[191883880] 'agreement among raft nodes before linearized reading' (duration: 545.669992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:50.037535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:49.491558Z","time spent":"545.962751ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-21T14:11:50.348276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.878739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:50.348412Z","caller":"traceutil/trace.go:171","msg":"trace[1185919783] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"488.984048ms","start":"2026-07-21T14:11:49.859414Z","end":"2026-07-21T14:11:50.348398Z","steps":["trace[1185919783] 'process raft request' (duration: 287.920044ms)","trace[1185919783] 'compare' (duration: 200.731634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:50.358654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:49.859381Z","time spent":"489.05814ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:50.359248Z","caller":"traceutil/trace.go:171","msg":"trace[246431218] linearizableReadLoop","detail":"{readStateIndex:25433; appliedIndex:25429; }","duration":"199.903319ms","start":"2026-07-21T14:11:50.1593Z","end":"2026-07-21T14:11:50.359204Z","steps":["trace[246431218] 'read index received' (duration: 23.982013ms)","trace[246431218] 'applied index is now lower than readState.Index' (duration: 175.920306ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:50.359366Z","caller":"traceutil/trace.go:171","msg":"trace[1161261265] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"409.216496ms","start":"2026-07-21T14:11:49.950135Z","end":"2026-07-21T14:11:50.359351Z","steps":["trace[1161261265] 'process raft request' (duration: 398.209942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:50.35945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:49.950109Z","time spent":"409.277508ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:50.359583Z","caller":"traceutil/trace.go:171","msg":"trace[1590352549] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"406.399104ms","start":"2026-07-21T14:11:49.95316Z","end":"2026-07-21T14:11:50.35956Z","steps":["trace[1590352549] 'process raft request' (duration: 395.217715ms)","trace[1590352549] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capo-system/controller-leader-election-capo; req_size:520; } (duration: 10.647053ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:50.359592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.280521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T14:11:50.359626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:49.953131Z","time spent":"406.473626ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:50.359673Z","caller":"traceutil/trace.go:171","msg":"trace[602915386] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:24627; }","duration":"200.378143ms","start":"2026-07-21T14:11:50.159271Z","end":"2026-07-21T14:11:50.359649Z","steps":["trace[602915386] 'agreement among raft nodes before linearized reading' (duration: 200.160987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:50.359792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.495275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-21T14:11:50.359884Z","caller":"traceutil/trace.go:171","msg":"trace[15559796] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24627; }","duration":"109.646399ms","start":"2026-07-21T14:11:50.250217Z","end":"2026-07-21T14:11:50.359863Z","steps":["trace[15559796] 'agreement among raft nodes before linearized reading' (duration: 109.297409ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:50.359921Z","caller":"traceutil/trace.go:171","msg":"trace[1349721253] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"317.996006ms","start":"2026-07-21T14:11:50.041905Z","end":"2026-07-21T14:11:50.359901Z","steps":["trace[1349721253] 'process raft request' (duration: 317.233844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:50.360019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:50.041886Z","time spent":"318.081139ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:50.675882Z","caller":"traceutil/trace.go:171","msg":"trace[1370559181] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"139.735843ms","start":"2026-07-21T14:11:50.536105Z","end":"2026-07-21T14:11:50.675841Z","steps":["trace[1370559181] 'process raft request' (duration: 139.517166ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:51.066244Z","caller":"traceutil/trace.go:171","msg":"trace[1690936554] linearizableReadLoop","detail":"{readStateIndex:25435; appliedIndex:25434; }","duration":"370.170659ms","start":"2026-07-21T14:11:50.696042Z","end":"2026-07-21T14:11:51.066212Z","steps":["trace[1690936554] 'read index received' (duration: 370.049826ms)","trace[1690936554] 'applied index is now lower than readState.Index' (duration: 119.493µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:51.066624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.565052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-21T14:11:51.066698Z","caller":"traceutil/trace.go:171","msg":"trace[1806934016] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24629; }","duration":"370.692256ms","start":"2026-07-21T14:11:50.695987Z","end":"2026-07-21T14:11:51.066679Z","steps":["trace[1806934016] 'agreement among raft nodes before linearized reading' (duration: 370.379206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:51.066745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:50.695957Z","time spent":"370.778718ms","remote":"127.0.0.1:52624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-21T14:11:51.066942Z","caller":"traceutil/trace.go:171","msg":"trace[287662453] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"371.324724ms","start":"2026-07-21T14:11:50.695573Z","end":"2026-07-21T14:11:51.066898Z","steps":["trace[287662453] 'process raft request' (duration: 370.401046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:51.067122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:50.695558Z","time spent":"371.483158ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:11:51.281834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.95617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:51.282005Z","caller":"traceutil/trace.go:171","msg":"trace[175235967] linearizableReadLoop","detail":"{readStateIndex:25437; appliedIndex:25435; }","duration":"215.656152ms","start":"2026-07-21T14:11:51.066331Z","end":"2026-07-21T14:11:51.281988Z","steps":["trace[175235967] 'read index received' (duration: 75.446455ms)","trace[175235967] 'applied index is now lower than readState.Index' (duration: 140.208557ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:51.282097Z","caller":"traceutil/trace.go:171","msg":"trace[751207254] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"442.690979ms","start":"2026-07-21T14:11:50.839394Z","end":"2026-07-21T14:11:51.282085Z","steps":["trace[751207254] 'process raft request' (duration: 442.529034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:51.282158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:50.839378Z","time spent":"442.74787ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:51.282196Z","caller":"traceutil/trace.go:171","msg":"trace[150101791] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"585.659618ms","start":"2026-07-21T14:11:50.696514Z","end":"2026-07-21T14:11:51.282174Z","steps":["trace[150101791] 'process raft request' (duration: 445.280096ms)","trace[150101791] 'compare' (duration: 139.851936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:51.282323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.825125ms","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":"warn","ts":"2026-07-21T14:11:51.282448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.332877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-21T14:11:51.282464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:50.696496Z","time spent":"585.771571ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:51.282496Z","caller":"traceutil/trace.go:171","msg":"trace[961215173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24631; }","duration":"444.486612ms","start":"2026-07-21T14:11:50.837996Z","end":"2026-07-21T14:11:51.282483Z","steps":["trace[961215173] 'agreement among raft nodes before linearized reading' (duration: 444.210874ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:51.282485Z","caller":"traceutil/trace.go:171","msg":"trace[1840327057] 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:24631; }","duration":"456.953098ms","start":"2026-07-21T14:11:50.825429Z","end":"2026-07-21T14:11:51.282382Z","steps":["trace[1840327057] 'agreement among raft nodes before linearized reading' (duration: 456.770663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:51.282532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:50.837978Z","time spent":"444.544663ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T14:11:51.282549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:50.825398Z","time spent":"457.135354ms","remote":"127.0.0.1:37364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-21T14:11:51.570025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.138383ms","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-07-21T14:11:51.57013Z","caller":"traceutil/trace.go:171","msg":"trace[1665167227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24632; }","duration":"159.300387ms","start":"2026-07-21T14:11:51.410806Z","end":"2026-07-21T14:11:51.570107Z","steps":["trace[1665167227] 'range keys from in-memory index tree' (duration: 158.857985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:51.57039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.279553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:51.570518Z","caller":"traceutil/trace.go:171","msg":"trace[951444544] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"133.542361ms","start":"2026-07-21T14:11:51.436958Z","end":"2026-07-21T14:11:51.5705Z","steps":["trace[951444544] 'process raft request' (duration: 15.069972ms)","trace[951444544] 'compare' (duration: 118.046816ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:51.895095Z","caller":"traceutil/trace.go:171","msg":"trace[1621900444] linearizableReadLoop","detail":"{readStateIndex:25440; appliedIndex:25439; }","duration":"168.791846ms","start":"2026-07-21T14:11:51.726272Z","end":"2026-07-21T14:11:51.895064Z","steps":["trace[1621900444] 'read index received' (duration: 161.084081ms)","trace[1621900444] 'applied index is now lower than readState.Index' (duration: 7.706595ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:51.89513Z","caller":"traceutil/trace.go:171","msg":"trace[2004972291] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"320.439248ms","start":"2026-07-21T14:11:51.574666Z","end":"2026-07-21T14:11:51.895105Z","steps":["trace[2004972291] 'process raft request' (duration: 312.64344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:51.895245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:51.574637Z","time spent":"320.546832ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:11:51.895325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.047873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-21T14:11:51.895338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.892128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T14:11:51.895378Z","caller":"traceutil/trace.go:171","msg":"trace[1377566743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24634; }","duration":"155.015732ms","start":"2026-07-21T14:11:51.740346Z","end":"2026-07-21T14:11:51.895362Z","steps":["trace[1377566743] 'agreement among raft nodes before linearized reading' (duration: 154.889898ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:51.895382Z","caller":"traceutil/trace.go:171","msg":"trace[715643186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24634; }","duration":"169.139517ms","start":"2026-07-21T14:11:51.726218Z","end":"2026-07-21T14:11:51.895357Z","steps":["trace[715643186] 'agreement among raft nodes before linearized reading' (duration: 168.978592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:51.895369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.782273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-21T14:11:51.895447Z","caller":"traceutil/trace.go:171","msg":"trace[680079235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24634; }","duration":"155.926328ms","start":"2026-07-21T14:11:51.739498Z","end":"2026-07-21T14:11:51.895424Z","steps":["trace[680079235] 'agreement among raft nodes before linearized reading' (duration: 155.731372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:52.223753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.888806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:52.224045Z","caller":"traceutil/trace.go:171","msg":"trace[1149836490] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"324.145018ms","start":"2026-07-21T14:11:51.899874Z","end":"2026-07-21T14:11:52.224019Z","steps":["trace[1149836490] 'process raft request' (duration: 324.001544ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:52.224048Z","caller":"traceutil/trace.go:171","msg":"trace[868387069] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"324.344554ms","start":"2026-07-21T14:11:51.899674Z","end":"2026-07-21T14:11:52.224018Z","steps":["trace[868387069] 'process raft request' (duration: 150.069567ms)","trace[868387069] 'compare' (duration: 173.577246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:52.224151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:51.899865Z","time spent":"324.23642ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:52.224203Z","caller":"traceutil/trace.go:171","msg":"trace[2103352334] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"324.255432ms","start":"2026-07-21T14:11:51.899919Z","end":"2026-07-21T14:11:52.224174Z","steps":["trace[2103352334] 'process raft request' (duration: 324.048195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:52.224279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:51.899652Z","time spent":"324.521059ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:11:52.224232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.082651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-21T14:11:52.22432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:51.899899Z","time spent":"324.366684ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:52.224353Z","caller":"traceutil/trace.go:171","msg":"trace[778807236] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:24637; }","duration":"112.241455ms","start":"2026-07-21T14:11:52.112098Z","end":"2026-07-21T14:11:52.22434Z","steps":["trace[778807236] 'agreement among raft nodes before linearized reading' (duration: 112.091521ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:52.224168Z","caller":"traceutil/trace.go:171","msg":"trace[1702873289] linearizableReadLoop","detail":"{readStateIndex:25443; appliedIndex:25440; }","duration":"112.009449ms","start":"2026-07-21T14:11:52.112145Z","end":"2026-07-21T14:11:52.224155Z","steps":["trace[1702873289] 'read index received' (duration: 43.711µs)","trace[1702873289] 'applied index is now lower than readState.Index' (duration: 111.963938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:52.514888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.951056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:52.514976Z","caller":"traceutil/trace.go:171","msg":"trace[752125878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24637; }","duration":"163.0891ms","start":"2026-07-21T14:11:52.351865Z","end":"2026-07-21T14:11:52.514954Z","steps":["trace[752125878] 'range keys from in-memory index tree' (duration: 162.837963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:52.51521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.274604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-21T14:11:52.515286Z","caller":"traceutil/trace.go:171","msg":"trace[1447077533] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24637; }","duration":"149.397838ms","start":"2026-07-21T14:11:52.365868Z","end":"2026-07-21T14:11:52.515266Z","steps":["trace[1447077533] 'range keys from in-memory index tree' (duration: 149.105729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:52.515293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.482633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:52.515371Z","caller":"traceutil/trace.go:171","msg":"trace[2032732036] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24637; }","duration":"190.609397ms","start":"2026-07-21T14:11:52.32474Z","end":"2026-07-21T14:11:52.51535Z","steps":["trace[2032732036] 'count revisions from in-memory index tree' (duration: 190.339299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:52.515369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.265314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-21T14:11:52.515437Z","caller":"traceutil/trace.go:171","msg":"trace[868831147] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:24637; }","duration":"179.375117ms","start":"2026-07-21T14:11:52.336044Z","end":"2026-07-21T14:11:52.515419Z","steps":["trace[868831147] 'range keys from in-memory index tree' (duration: 179.11123ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:52.515485Z","caller":"traceutil/trace.go:171","msg":"trace[583787458] transaction","detail":"{read_only:false; response_revision:24638; number_of_response:1; }","duration":"146.687968ms","start":"2026-07-21T14:11:52.368783Z","end":"2026-07-21T14:11:52.515471Z","steps":["trace[583787458] 'process raft request' (duration: 139.125326ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:52.842806Z","caller":"traceutil/trace.go:171","msg":"trace[1258531614] linearizableReadLoop","detail":"{readStateIndex:25446; appliedIndex:25444; }","duration":"326.257751ms","start":"2026-07-21T14:11:52.516531Z","end":"2026-07-21T14:11:52.842788Z","steps":["trace[1258531614] 'read index received' (duration: 271.623416ms)","trace[1258531614] 'applied index is now lower than readState.Index' (duration: 54.633684ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:52.842857Z","caller":"traceutil/trace.go:171","msg":"trace[232799207] transaction","detail":"{read_only:false; response_revision:24640; number_of_response:1; }","duration":"471.719902ms","start":"2026-07-21T14:11:52.371116Z","end":"2026-07-21T14:11:52.842836Z","steps":["trace[232799207] 'process raft request' (duration: 471.599748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:52.842985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:52.371098Z","time spent":"471.814865ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:11:52.843276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.730394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:52.843308Z","caller":"traceutil/trace.go:171","msg":"trace[1434040419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24640; }","duration":"326.797336ms","start":"2026-07-21T14:11:52.516502Z","end":"2026-07-21T14:11:52.843299Z","steps":["trace[1434040419] 'agreement among raft nodes before linearized reading' (duration: 326.374704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:52.843331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:52.516486Z","time spent":"326.840107ms","remote":"127.0.0.1:40872","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-07-21T14:11:52.843575Z","caller":"traceutil/trace.go:171","msg":"trace[1368318855] transaction","detail":"{read_only:false; response_revision:24639; number_of_response:1; }","duration":"472.565397ms","start":"2026-07-21T14:11:52.370227Z","end":"2026-07-21T14:11:52.842792Z","steps":["trace[1368318855] 'process raft request' (duration: 417.915981ms)","trace[1368318855] 'compare' (duration: 54.247753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:52.843654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:52.370208Z","time spent":"473.40655ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:11:53.182473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.647584ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-21T14:11:53.182654Z","caller":"traceutil/trace.go:171","msg":"trace[830372913] linearizableReadLoop","detail":"{readStateIndex:25448; appliedIndex:25446; }","duration":"339.787407ms","start":"2026-07-21T14:11:52.842855Z","end":"2026-07-21T14:11:53.182643Z","steps":["trace[830372913] 'read index received' (duration: 142.905277ms)","trace[830372913] 'applied index is now lower than readState.Index' (duration: 196.88159ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:53.182649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:52.517395Z","time spent":"665.246314ms","remote":"127.0.0.1:40878","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T14:11:53.182759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.465665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:53.18279Z","caller":"traceutil/trace.go:171","msg":"trace[1372295167] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:24641; }","duration":"595.544687ms","start":"2026-07-21T14:11:52.587235Z","end":"2026-07-21T14:11:53.18278Z","steps":["trace[1372295167] 'agreement among raft nodes before linearized reading' (duration: 595.462535ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:53.182796Z","caller":"traceutil/trace.go:171","msg":"trace[466981140] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"662.806833ms","start":"2026-07-21T14:11:52.519945Z","end":"2026-07-21T14:11:53.182752Z","steps":["trace[466981140] 'process raft request' (duration: 662.591536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:53.18282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:52.587213Z","time spent":"595.598129ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-07-21T14:11:53.182942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:52.519931Z","time spent":"662.933827ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:53.256275Z","caller":"traceutil/trace.go:171","msg":"trace[10058168] transaction","detail":"{read_only:false; response_revision:24642; number_of_response:1; }","duration":"183.370953ms","start":"2026-07-21T14:11:53.072878Z","end":"2026-07-21T14:11:53.256249Z","steps":["trace[10058168] 'process raft request' (duration: 183.226639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:53.50736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.9583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:53.507503Z","caller":"traceutil/trace.go:171","msg":"trace[409602765] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"323.731957ms","start":"2026-07-21T14:11:53.183743Z","end":"2026-07-21T14:11:53.507475Z","steps":["trace[409602765] 'process raft request' (duration: 152.55894ms)","trace[409602765] 'compare' (duration: 170.747454ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:53.507585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:53.183716Z","time spent":"323.826229ms","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:53.59795Z","caller":"traceutil/trace.go:171","msg":"trace[389446789] linearizableReadLoop","detail":"{readStateIndex:25452; appliedIndex:25449; }","duration":"297.067132ms","start":"2026-07-21T14:11:53.300862Z","end":"2026-07-21T14:11:53.59793Z","steps":["trace[389446789] 'read index received' (duration: 35.517343ms)","trace[389446789] 'applied index is now lower than readState.Index' (duration: 261.548919ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:53.598031Z","caller":"traceutil/trace.go:171","msg":"trace[1372154475] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"310.546509ms","start":"2026-07-21T14:11:53.28746Z","end":"2026-07-21T14:11:53.598006Z","steps":["trace[1372154475] 'process raft request' (duration: 310.436885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:53.598137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.262239ms","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-07-21T14:11:53.598035Z","caller":"traceutil/trace.go:171","msg":"trace[387223377] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"310.855588ms","start":"2026-07-21T14:11:53.287109Z","end":"2026-07-21T14:11:53.597964Z","steps":["trace[387223377] 'process raft request' (duration: 310.666213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:53.598165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:53.287435Z","time spent":"310.649032ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:53.598182Z","caller":"traceutil/trace.go:171","msg":"trace[871667742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24645; }","duration":"297.356941ms","start":"2026-07-21T14:11:53.300815Z","end":"2026-07-21T14:11:53.598172Z","steps":["trace[871667742] 'agreement among raft nodes before linearized reading' (duration: 297.220017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:53.598344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:53.287083Z","time spent":"311.125895ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:53.678318Z","caller":"traceutil/trace.go:171","msg":"trace[1836064690] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"103.195481ms","start":"2026-07-21T14:11:53.575101Z","end":"2026-07-21T14:11:53.678296Z","steps":["trace[1836064690] 'process raft request' (duration: 101.612994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:53.678659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.737394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-21T14:11:53.678738Z","caller":"traceutil/trace.go:171","msg":"trace[315782015] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24646; }","duration":"168.863808ms","start":"2026-07-21T14:11:53.50986Z","end":"2026-07-21T14:11:53.678724Z","steps":["trace[315782015] 'agreement among raft nodes before linearized reading' (duration: 168.451726ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:54.036128Z","caller":"traceutil/trace.go:171","msg":"trace[1193972556] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"133.872621ms","start":"2026-07-21T14:11:53.902224Z","end":"2026-07-21T14:11:54.036097Z","steps":["trace[1193972556] 'process raft request' (duration: 133.677014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:54.294534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.628552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:54.294611Z","caller":"traceutil/trace.go:171","msg":"trace[1840786534] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24648; }","duration":"215.743515ms","start":"2026-07-21T14:11:54.078848Z","end":"2026-07-21T14:11:54.294592Z","steps":["trace[1840786534] 'count revisions from in-memory index tree' (duration: 215.5799ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:54.440938Z","caller":"traceutil/trace.go:171","msg":"trace[162740484] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"142.835025ms","start":"2026-07-21T14:11:54.298086Z","end":"2026-07-21T14:11:54.440921Z","steps":["trace[162740484] 'process raft request' (duration: 142.729292ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:54.644608Z","caller":"traceutil/trace.go:171","msg":"trace[837128976] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"345.375392ms","start":"2026-07-21T14:11:54.299208Z","end":"2026-07-21T14:11:54.644584Z","steps":["trace[837128976] 'process raft request' (duration: 330.482524ms)","trace[837128976] 'compare' (duration: 14.738944ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:54.644745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:54.299182Z","time spent":"345.489026ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:54.644872Z","caller":"traceutil/trace.go:171","msg":"trace[626387769] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"345.048362ms","start":"2026-07-21T14:11:54.299809Z","end":"2026-07-21T14:11:54.644857Z","steps":["trace[626387769] 'process raft request' (duration: 344.730803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:54.644934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:54.299799Z","time spent":"345.107594ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:54.644962Z","caller":"traceutil/trace.go:171","msg":"trace[1458036085] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"124.185686ms","start":"2026-07-21T14:11:54.520746Z","end":"2026-07-21T14:11:54.644932Z","steps":["trace[1458036085] 'process raft request' (duration: 124.050932ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:54.64497Z","caller":"traceutil/trace.go:171","msg":"trace[754266674] linearizableReadLoop","detail":"{readStateIndex:25458; appliedIndex:25456; }","duration":"294.821706ms","start":"2026-07-21T14:11:54.350116Z","end":"2026-07-21T14:11:54.644938Z","steps":["trace[754266674] 'read index received' (duration: 90.851447ms)","trace[754266674] 'applied index is now lower than readState.Index' (duration: 203.967309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:54.64508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:54.645121Z","caller":"traceutil/trace.go:171","msg":"trace[147732685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24652; }","duration":"295.034173ms","start":"2026-07-21T14:11:54.35008Z","end":"2026-07-21T14:11:54.645114Z","steps":["trace[147732685] 'agreement among raft nodes before linearized reading' (duration: 294.9383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:54.645233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.94416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:54.645292Z","caller":"traceutil/trace.go:171","msg":"trace[1450418341] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24652; }","duration":"138.051813ms","start":"2026-07-21T14:11:54.507226Z","end":"2026-07-21T14:11:54.645278Z","steps":["trace[1450418341] 'agreement among raft nodes before linearized reading' (duration: 137.956501ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:54.971751Z","caller":"traceutil/trace.go:171","msg":"trace[1283159497] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"122.769795ms","start":"2026-07-21T14:11:54.848951Z","end":"2026-07-21T14:11:54.971721Z","steps":["trace[1283159497] 'process raft request' (duration: 122.651392ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:54.97186Z","caller":"traceutil/trace.go:171","msg":"trace[1719635936] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"123.419374ms","start":"2026-07-21T14:11:54.848392Z","end":"2026-07-21T14:11:54.971811Z","steps":["trace[1719635936] 'process raft request' (duration: 33.520943ms)","trace[1719635936] 'compare' (duration: 89.22071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:55.227471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.521956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:11:55.227899Z","caller":"traceutil/trace.go:171","msg":"trace[1189808441] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"184.903679ms","start":"2026-07-21T14:11:55.042965Z","end":"2026-07-21T14:11:55.227869Z","steps":["trace[1189808441] 'process raft request' (duration: 27.906459ms)","trace[1189808441] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi; req_size:623; } (duration: 156.011771ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:55.444628Z","caller":"traceutil/trace.go:171","msg":"trace[1638055787] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"211.588252ms","start":"2026-07-21T14:11:55.233009Z","end":"2026-07-21T14:11:55.444597Z","steps":["trace[1638055787] 'process raft request' (duration: 148.49187ms)","trace[1638055787] 'compare' (duration: 62.920687ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:55.464002Z","caller":"traceutil/trace.go:171","msg":"trace[234467084] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25462; }","duration":"113.211414ms","start":"2026-07-21T14:11:55.350771Z","end":"2026-07-21T14:11:55.463983Z","steps":["trace[234467084] 'read index received' (duration: 30.806115ms)","trace[234467084] 'applied index is now lower than readState.Index' (duration: 82.404449ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:55.464138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.350188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:55.464124Z","caller":"traceutil/trace.go:171","msg":"trace[1334510436] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"202.439285ms","start":"2026-07-21T14:11:55.261657Z","end":"2026-07-21T14:11:55.464097Z","steps":["trace[1334510436] 'process raft request' (duration: 202.191627ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:55.464174Z","caller":"traceutil/trace.go:171","msg":"trace[1788455982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24657; }","duration":"113.42942ms","start":"2026-07-21T14:11:55.350734Z","end":"2026-07-21T14:11:55.464164Z","steps":["trace[1788455982] 'agreement among raft nodes before linearized reading' (duration: 113.353998ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:55.782823Z","caller":"traceutil/trace.go:171","msg":"trace[1033599291] linearizableReadLoop","detail":"{readStateIndex:25466; appliedIndex:25464; }","duration":"142.563597ms","start":"2026-07-21T14:11:55.64023Z","end":"2026-07-21T14:11:55.782794Z","steps":["trace[1033599291] 'read index received' (duration: 23.880642ms)","trace[1033599291] 'applied index is now lower than readState.Index' (duration: 118.681345ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:55.782946Z","caller":"traceutil/trace.go:171","msg":"trace[821362549] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"178.486312ms","start":"2026-07-21T14:11:55.604445Z","end":"2026-07-21T14:11:55.782931Z","steps":["trace[821362549] 'process raft request' (duration: 155.738864ms)","trace[821362549] 'compare' (duration: 22.260954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:55.7831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.863865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T14:11:55.783176Z","caller":"traceutil/trace.go:171","msg":"trace[42835061] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24659; }","duration":"142.989098ms","start":"2026-07-21T14:11:55.640169Z","end":"2026-07-21T14:11:55.783158Z","steps":["trace[42835061] 'agreement among raft nodes before linearized reading' (duration: 142.864645ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:56.077022Z","caller":"traceutil/trace.go:171","msg":"trace[2017667842] linearizableReadLoop","detail":"{readStateIndex:25468; appliedIndex:25466; }","duration":"294.106956ms","start":"2026-07-21T14:11:55.782892Z","end":"2026-07-21T14:11:56.076999Z","steps":["trace[2017667842] 'read index received' (duration: 201.716783ms)","trace[2017667842] 'applied index is now lower than readState.Index' (duration: 92.389203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:56.077288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.911076ms","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-07-21T14:11:56.077337Z","caller":"traceutil/trace.go:171","msg":"trace[2136398056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24661; }","duration":"394.005959ms","start":"2026-07-21T14:11:55.683319Z","end":"2026-07-21T14:11:56.077325Z","steps":["trace[2136398056] 'agreement among raft nodes before linearized reading' (duration: 393.844624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:56.077359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:55.683299Z","time spent":"394.05648ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T14:11:56.077351Z","caller":"traceutil/trace.go:171","msg":"trace[1097154697] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"393.086322ms","start":"2026-07-21T14:11:55.684233Z","end":"2026-07-21T14:11:56.077319Z","steps":["trace[1097154697] 'process raft request' (duration: 300.332948ms)","trace[1097154697] 'compare' (duration: 92.172407ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:11:56.077463Z","caller":"traceutil/trace.go:171","msg":"trace[564478331] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"338.965673ms","start":"2026-07-21T14:11:55.738472Z","end":"2026-07-21T14:11:56.077438Z","steps":["trace[564478331] 'process raft request' (duration: 338.429688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:56.077549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:55.684212Z","time spent":"393.251587ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:11:56.077604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:55.738443Z","time spent":"339.069547ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:56.750796Z","caller":"traceutil/trace.go:171","msg":"trace[1705647067] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"101.750068ms","start":"2026-07-21T14:11:56.649026Z","end":"2026-07-21T14:11:56.750776Z","steps":["trace[1705647067] 'process raft request' (duration: 101.626235ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:57.041383Z","caller":"traceutil/trace.go:171","msg":"trace[1535306697] linearizableReadLoop","detail":"{readStateIndex:25474; appliedIndex:25472; }","duration":"262.475827ms","start":"2026-07-21T14:11:56.778888Z","end":"2026-07-21T14:11:57.041364Z","steps":["trace[1535306697] 'read index received' (duration: 258.49927ms)","trace[1535306697] 'applied index is now lower than readState.Index' (duration: 3.974697ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:11:57.041501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.58801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:11:57.04154Z","caller":"traceutil/trace.go:171","msg":"trace[541901161] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24667; }","duration":"262.677882ms","start":"2026-07-21T14:11:56.778853Z","end":"2026-07-21T14:11:57.041531Z","steps":["trace[541901161] 'agreement among raft nodes before linearized reading' (duration: 262.58711ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:57.041422Z","caller":"traceutil/trace.go:171","msg":"trace[932098255] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"388.424206ms","start":"2026-07-21T14:11:56.652974Z","end":"2026-07-21T14:11:57.041398Z","steps":["trace[932098255] 'process raft request' (duration: 388.2422ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:57.0419Z","caller":"traceutil/trace.go:171","msg":"trace[1602154006] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"389.053314ms","start":"2026-07-21T14:11:56.652233Z","end":"2026-07-21T14:11:57.041286Z","steps":["trace[1602154006] 'process raft request' (duration: 385.16819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:57.041921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:56.652964Z","time spent":"388.88357ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:11:57.042027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:11:56.65222Z","time spent":"389.729625ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:11:57.112303Z","caller":"traceutil/trace.go:171","msg":"trace[417543016] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"135.620003ms","start":"2026-07-21T14:11:56.976659Z","end":"2026-07-21T14:11:57.112279Z","steps":["trace[417543016] 'process raft request' (duration: 135.411716ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:11:57.112449Z","caller":"traceutil/trace.go:171","msg":"trace[694126616] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"135.214802ms","start":"2026-07-21T14:11:56.977207Z","end":"2026-07-21T14:11:57.112422Z","steps":["trace[694126616] 'process raft request' (duration: 135.020576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:11:57.293938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.477559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T14:11:57.294105Z","caller":"traceutil/trace.go:171","msg":"trace[1057199022] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24669; }","duration":"149.688516ms","start":"2026-07-21T14:11:57.144394Z","end":"2026-07-21T14:11:57.294083Z","steps":["trace[1057199022] 'count revisions from in-memory index tree' (duration: 149.389686ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:00.234114Z","caller":"traceutil/trace.go:171","msg":"trace[334366245] linearizableReadLoop","detail":"{readStateIndex:25498; appliedIndex:25497; }","duration":"105.89311ms","start":"2026-07-21T14:12:00.128193Z","end":"2026-07-21T14:12:00.234086Z","steps":["trace[334366245] 'read index received' (duration: 105.778447ms)","trace[334366245] 'applied index is now lower than readState.Index' (duration: 113.353µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:00.234236Z","caller":"traceutil/trace.go:171","msg":"trace[319685331] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"145.365009ms","start":"2026-07-21T14:12:00.088844Z","end":"2026-07-21T14:12:00.234209Z","steps":["trace[319685331] 'process raft request' (duration: 145.0713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:00.234374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.158058ms","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-07-21T14:12:00.234474Z","caller":"traceutil/trace.go:171","msg":"trace[1817608948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24690; }","duration":"106.270151ms","start":"2026-07-21T14:12:00.128187Z","end":"2026-07-21T14:12:00.234457Z","steps":["trace[1817608948] 'agreement among raft nodes before linearized reading' (duration: 106.036044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:00.615526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.802714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:00.615709Z","caller":"traceutil/trace.go:171","msg":"trace[1727509529] linearizableReadLoop","detail":"{readStateIndex:25499; appliedIndex:25498; }","duration":"381.506472ms","start":"2026-07-21T14:12:00.234187Z","end":"2026-07-21T14:12:00.615693Z","steps":["trace[1727509529] 'read index received' (duration: 20.42279ms)","trace[1727509529] 'applied index is now lower than readState.Index' (duration: 361.082582ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:00.61589Z","caller":"traceutil/trace.go:171","msg":"trace[443895751] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"383.446919ms","start":"2026-07-21T14:12:00.232429Z","end":"2026-07-21T14:12:00.615876Z","steps":["trace[443895751] 'process raft request' (duration: 22.203852ms)","trace[443895751] 'compare' (duration: 360.187007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:00.615966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:00.232399Z","time spent":"383.535332ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:00.616094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.160096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-07-21T14:12:00.616225Z","caller":"traceutil/trace.go:171","msg":"trace[881850458] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24691; }","duration":"405.3058ms","start":"2026-07-21T14:12:00.210868Z","end":"2026-07-21T14:12:00.616174Z","steps":["trace[881850458] 'agreement among raft nodes before linearized reading' (duration: 405.078333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:00.61629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:00.210848Z","time spent":"405.427014ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-21T14:12:00.616129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.631732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-21T14:12:00.616375Z","caller":"traceutil/trace.go:171","msg":"trace[1353053869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24692; }","duration":"449.918161ms","start":"2026-07-21T14:12:00.166442Z","end":"2026-07-21T14:12:00.61636Z","steps":["trace[1353053869] 'agreement among raft nodes before linearized reading' (duration: 449.525599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:00.616409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:00.166374Z","time spent":"450.025935ms","remote":"127.0.0.1:39580","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-07-21T14:12:00.616738Z","caller":"traceutil/trace.go:171","msg":"trace[277017845] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"376.108064ms","start":"2026-07-21T14:12:00.240613Z","end":"2026-07-21T14:12:00.616721Z","steps":["trace[277017845] 'process raft request' (duration: 375.013631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:00.616853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:00.24059Z","time spent":"376.197177ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:00.616824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.726215ms","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-07-21T14:12:00.616903Z","caller":"traceutil/trace.go:171","msg":"trace[1603525619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24692; }","duration":"137.855599ms","start":"2026-07-21T14:12:00.479028Z","end":"2026-07-21T14:12:00.616883Z","steps":["trace[1603525619] 'agreement among raft nodes before linearized reading' (duration: 137.645113ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:00.896736Z","caller":"traceutil/trace.go:171","msg":"trace[1252617759] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"276.216771ms","start":"2026-07-21T14:12:00.620498Z","end":"2026-07-21T14:12:00.896715Z","steps":["trace[1252617759] 'process raft request' (duration: 276.077927ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:00.899457Z","caller":"traceutil/trace.go:171","msg":"trace[1845146423] linearizableReadLoop","detail":"{readStateIndex:25502; appliedIndex:25501; }","duration":"218.316031ms","start":"2026-07-21T14:12:00.681117Z","end":"2026-07-21T14:12:00.899433Z","steps":["trace[1845146423] 'read index received' (duration: 215.636362ms)","trace[1845146423] 'applied index is now lower than readState.Index' (duration: 2.678588ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:00.899568Z","caller":"traceutil/trace.go:171","msg":"trace[131514430] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"278.479007ms","start":"2026-07-21T14:12:00.621052Z","end":"2026-07-21T14:12:00.899531Z","steps":["trace[131514430] 'process raft request' (duration: 278.212469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:00.899645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.517466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:00.89968Z","caller":"traceutil/trace.go:171","msg":"trace[1680076324] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24696; }","duration":"218.609639ms","start":"2026-07-21T14:12:00.681059Z","end":"2026-07-21T14:12:00.899669Z","steps":["trace[1680076324] 'agreement among raft nodes before linearized reading' (duration: 218.508656ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:00.899895Z","caller":"traceutil/trace.go:171","msg":"trace[1781462285] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"133.235953ms","start":"2026-07-21T14:12:00.766622Z","end":"2026-07-21T14:12:00.899858Z","steps":["trace[1781462285] 'process raft request' (duration: 132.77686ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:00.899916Z","caller":"traceutil/trace.go:171","msg":"trace[272051717] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"186.480736ms","start":"2026-07-21T14:12:00.713345Z","end":"2026-07-21T14:12:00.899826Z","steps":["trace[272051717] 'process raft request' (duration: 186.016152ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:09.234503Z","caller":"traceutil/trace.go:171","msg":"trace[1878402394] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"258.029216ms","start":"2026-07-21T14:12:08.976406Z","end":"2026-07-21T14:12:09.234435Z","steps":["trace[1878402394] 'process raft request' (duration: 257.858231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:09.557807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.632794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:09.558023Z","caller":"traceutil/trace.go:171","msg":"trace[725768995] linearizableReadLoop","detail":"{readStateIndex:25623; appliedIndex:25621; }","duration":"576.085175ms","start":"2026-07-21T14:12:08.981922Z","end":"2026-07-21T14:12:09.558007Z","steps":["trace[725768995] 'read index received' (duration: 252.395711ms)","trace[725768995] 'applied index is now lower than readState.Index' (duration: 323.687944ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:09.5581Z","caller":"traceutil/trace.go:171","msg":"trace[1780821551] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"397.081509ms","start":"2026-07-21T14:12:09.161007Z","end":"2026-07-21T14:12:09.558088Z","steps":["trace[1780821551] 'process raft request' (duration: 396.959956ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:09.55807Z","caller":"traceutil/trace.go:171","msg":"trace[348775708] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"581.324129ms","start":"2026-07-21T14:12:08.976691Z","end":"2026-07-21T14:12:09.558016Z","steps":["trace[348775708] 'process raft request' (duration: 386.408146ms)","trace[348775708] 'compare' (duration: 194.509941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:09.55817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.160974Z","time spent":"397.154541ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:09.558252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:08.976672Z","time spent":"581.488534ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:09.55836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.409794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34c15290-a8c9-4963-a5ce-d8da18733630\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-21T14:12:09.558085Z","caller":"traceutil/trace.go:171","msg":"trace[1126626363] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"402.525169ms","start":"2026-07-21T14:12:09.155527Z","end":"2026-07-21T14:12:09.558052Z","steps":["trace[1126626363] 'process raft request' (duration: 402.383535ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:09.558567Z","caller":"traceutil/trace.go:171","msg":"trace[2055796424] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34c15290-a8c9-4963-a5ce-d8da18733630; range_end:; response_count:1; response_revision:24814; }","duration":"576.532228ms","start":"2026-07-21T14:12:08.981882Z","end":"2026-07-21T14:12:09.558414Z","steps":["trace[2055796424] 'agreement among raft nodes before linearized reading' (duration: 576.305941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:09.558587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.155492Z","time spent":"403.033255ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:09.55861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:08.981866Z","time spent":"576.734615ms","remote":"127.0.0.1:41044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34c15290-a8c9-4963-a5ce-d8da18733630\" "} {"level":"warn","ts":"2026-07-21T14:12:09.574171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.499932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:09.574211Z","caller":"traceutil/trace.go:171","msg":"trace[842972496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24815; }","duration":"224.574955ms","start":"2026-07-21T14:12:09.349625Z","end":"2026-07-21T14:12:09.5742Z","steps":["trace[842972496] 'agreement among raft nodes before linearized reading' (duration: 224.505253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:09.574358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.111208ms","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-07-21T14:12:09.574525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.00653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:09.574592Z","caller":"traceutil/trace.go:171","msg":"trace[668483833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24815; }","duration":"478.295245ms","start":"2026-07-21T14:12:09.096178Z","end":"2026-07-21T14:12:09.574473Z","steps":["trace[668483833] 'agreement among raft nodes before linearized reading' (duration: 477.899603ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:09.574609Z","caller":"traceutil/trace.go:171","msg":"trace[702181038] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24815; }","duration":"377.130864ms","start":"2026-07-21T14:12:09.197456Z","end":"2026-07-21T14:12:09.574587Z","steps":["trace[702181038] 'agreement among raft nodes before linearized reading' (duration: 376.973649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:09.574622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.178691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-21T14:12:09.574655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.197438Z","time spent":"377.207506ms","remote":"127.0.0.1:54958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"info","ts":"2026-07-21T14:12:09.574687Z","caller":"traceutil/trace.go:171","msg":"trace[139579736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24815; }","duration":"477.280504ms","start":"2026-07-21T14:12:09.097388Z","end":"2026-07-21T14:12:09.574668Z","steps":["trace[139579736] 'agreement among raft nodes before linearized reading' (duration: 477.065168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:09.57474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.097365Z","time spent":"477.361727ms","remote":"127.0.0.1:39580","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":"warn","ts":"2026-07-21T14:12:09.574746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.096155Z","time spent":"478.571923ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T14:12:09.847144Z","caller":"traceutil/trace.go:171","msg":"trace[822122050] linearizableReadLoop","detail":"{readStateIndex:25627; appliedIndex:25626; }","duration":"271.966077ms","start":"2026-07-21T14:12:09.575152Z","end":"2026-07-21T14:12:09.847118Z","steps":["trace[822122050] 'read index received' (duration: 172.763763ms)","trace[822122050] 'applied index is now lower than readState.Index' (duration: 99.201224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:09.847516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.297066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:09.847518Z","caller":"traceutil/trace.go:171","msg":"trace[1926384883] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"280.891729ms","start":"2026-07-21T14:12:09.566597Z","end":"2026-07-21T14:12:09.847488Z","steps":["trace[1926384883] 'process raft request' (duration: 181.247052ms)","trace[1926384883] 'compare' (duration: 99.153912ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:09.847566Z","caller":"traceutil/trace.go:171","msg":"trace[1867627569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24816; }","duration":"272.435781ms","start":"2026-07-21T14:12:09.575117Z","end":"2026-07-21T14:12:09.847552Z","steps":["trace[1867627569] 'agreement among raft nodes before linearized reading' (duration: 272.099271ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:10.133821Z","caller":"traceutil/trace.go:171","msg":"trace[2118914962] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"554.988246ms","start":"2026-07-21T14:12:09.578798Z","end":"2026-07-21T14:12:10.133787Z","steps":["trace[2118914962] 'process raft request' (duration: 526.732357ms)","trace[2118914962] 'compare' (duration: 27.912439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:10.133976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.578778Z","time spent":"555.116ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:12:10.133963Z","caller":"traceutil/trace.go:171","msg":"trace[89671130] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"554.734649ms","start":"2026-07-21T14:12:09.579187Z","end":"2026-07-21T14:12:10.133921Z","steps":["trace[89671130] 'process raft request' (duration: 554.530153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:10.134134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.579161Z","time spent":"554.879844ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:12:10.134128Z","caller":"traceutil/trace.go:171","msg":"trace[1105510043] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"402.21057ms","start":"2026-07-21T14:12:09.731888Z","end":"2026-07-21T14:12:10.134098Z","steps":["trace[1105510043] 'process raft request' (duration: 402.070066ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:10.134168Z","caller":"traceutil/trace.go:171","msg":"trace[1664187413] linearizableReadLoop","detail":"{readStateIndex:25632; appliedIndex:25627; }","duration":"157.489155ms","start":"2026-07-21T14:12:09.976653Z","end":"2026-07-21T14:12:10.134143Z","steps":["trace[1664187413] 'read index received' (duration: 128.890045ms)","trace[1664187413] 'applied index is now lower than readState.Index' (duration: 28.59806ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:10.134294Z","caller":"traceutil/trace.go:171","msg":"trace[646510008] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"297.552867ms","start":"2026-07-21T14:12:09.83673Z","end":"2026-07-21T14:12:10.134283Z","steps":["trace[646510008] 'process raft request' (duration: 297.32112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:10.134291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.731866Z","time spent":"402.337864ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:12:10.1345Z","caller":"traceutil/trace.go:171","msg":"trace[1679359799] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"554.77801ms","start":"2026-07-21T14:12:09.579697Z","end":"2026-07-21T14:12:10.134475Z","steps":["trace[1679359799] 'process raft request' (duration: 554.115051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:10.134591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:09.579684Z","time spent":"554.861182ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:10.134592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.507715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T14:12:10.134625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.981488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34c15290-a8c9-4963-a5ce-d8da18733630\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-21T14:12:10.134669Z","caller":"traceutil/trace.go:171","msg":"trace[1952890014] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34c15290-a8c9-4963-a5ce-d8da18733630; range_end:; response_count:1; response_revision:24821; }","duration":"158.05988ms","start":"2026-07-21T14:12:09.976596Z","end":"2026-07-21T14:12:10.134656Z","steps":["trace[1952890014] 'agreement among raft nodes before linearized reading' (duration: 157.851804ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:10.134674Z","caller":"traceutil/trace.go:171","msg":"trace[1266129533] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24821; }","duration":"156.643029ms","start":"2026-07-21T14:12:09.978009Z","end":"2026-07-21T14:12:10.134652Z","steps":["trace[1266129533] 'agreement among raft nodes before linearized reading' (duration: 156.354321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:10.384118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.432422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-07-21T14:12:10.384202Z","caller":"traceutil/trace.go:171","msg":"trace[101526828] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24821; }","duration":"128.570366ms","start":"2026-07-21T14:12:10.255612Z","end":"2026-07-21T14:12:10.384182Z","steps":["trace[101526828] 'range keys from in-memory index tree' (duration: 128.180845ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:10.384402Z","caller":"traceutil/trace.go:171","msg":"trace[925903335] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"106.757495ms","start":"2026-07-21T14:12:10.277608Z","end":"2026-07-21T14:12:10.384366Z","steps":["trace[925903335] 'process raft request' (duration: 28.170837ms)","trace[925903335] 'compare' (duration: 78.249788ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:12.284032Z","caller":"traceutil/trace.go:171","msg":"trace[1510079681] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"143.978268ms","start":"2026-07-21T14:12:12.140031Z","end":"2026-07-21T14:12:12.284009Z","steps":["trace[1510079681] 'process raft request' (duration: 143.826853ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:12.34521Z","caller":"traceutil/trace.go:171","msg":"trace[1058726344] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"202.166756ms","start":"2026-07-21T14:12:12.143025Z","end":"2026-07-21T14:12:12.345191Z","steps":["trace[1058726344] 'process raft request' (duration: 202.082834ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:12.345266Z","caller":"traceutil/trace.go:171","msg":"trace[1045055464] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"203.911617ms","start":"2026-07-21T14:12:12.141328Z","end":"2026-07-21T14:12:12.34524Z","steps":["trace[1045055464] 'process raft request' (duration: 203.653699ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:12.345423Z","caller":"traceutil/trace.go:171","msg":"trace[886591628] linearizableReadLoop","detail":"{readStateIndex:25648; appliedIndex:25645; }","duration":"201.94941ms","start":"2026-07-21T14:12:12.143434Z","end":"2026-07-21T14:12:12.345383Z","steps":["trace[886591628] 'read index received' (duration: 140.439944ms)","trace[886591628] 'applied index is now lower than readState.Index' (duration: 61.506636ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:12.345518Z","caller":"traceutil/trace.go:171","msg":"trace[1190839812] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"200.740864ms","start":"2026-07-21T14:12:12.144667Z","end":"2026-07-21T14:12:12.345407Z","steps":["trace[1190839812] 'process raft request' (duration: 200.485136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:12.34563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.175466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T14:12:12.345702Z","caller":"traceutil/trace.go:171","msg":"trace[440586863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24838; }","duration":"202.269579ms","start":"2026-07-21T14:12:12.143415Z","end":"2026-07-21T14:12:12.345684Z","steps":["trace[440586863] 'agreement among raft nodes before linearized reading' (duration: 202.084163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:12.345757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.031573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34c15290-a8c9-4963-a5ce-d8da18733630\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-07-21T14:12:12.345818Z","caller":"traceutil/trace.go:171","msg":"trace[226062527] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34c15290-a8c9-4963-a5ce-d8da18733630; range_end:; response_count:1; response_revision:24838; }","duration":"202.155107ms","start":"2026-07-21T14:12:12.143648Z","end":"2026-07-21T14:12:12.345803Z","steps":["trace[226062527] 'agreement among raft nodes before linearized reading' (duration: 201.925599ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:12.466636Z","caller":"traceutil/trace.go:171","msg":"trace[901346684] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"115.071238ms","start":"2026-07-21T14:12:12.351545Z","end":"2026-07-21T14:12:12.466617Z","steps":["trace[901346684] 'process raft request' (duration: 114.808021ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:15.456799Z","caller":"traceutil/trace.go:171","msg":"trace[302164783] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"317.926145ms","start":"2026-07-21T14:12:15.138843Z","end":"2026-07-21T14:12:15.456769Z","steps":["trace[302164783] 'process raft request' (duration: 317.714288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:15.456964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:15.138821Z","time spent":"318.051909ms","remote":"127.0.0.1:41044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10631,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:12:15.46376Z","caller":"traceutil/trace.go:171","msg":"trace[430705970] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25689; }","duration":"269.112862ms","start":"2026-07-21T14:12:15.194626Z","end":"2026-07-21T14:12:15.463739Z","steps":["trace[430705970] 'read index received' (duration: 269.091801ms)","trace[430705970] 'applied index is now lower than readState.Index' (duration: 19.291µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:15.46392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.294398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-21T14:12:15.463999Z","caller":"traceutil/trace.go:171","msg":"trace[235018811] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24877; }","duration":"269.412302ms","start":"2026-07-21T14:12:15.194575Z","end":"2026-07-21T14:12:15.463987Z","steps":["trace[235018811] 'agreement among raft nodes before linearized reading' (duration: 269.240837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:15.463984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.65459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-21T14:12:15.464076Z","caller":"traceutil/trace.go:171","msg":"trace[904328317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24877; }","duration":"203.743053ms","start":"2026-07-21T14:12:15.260316Z","end":"2026-07-21T14:12:15.464059Z","steps":["trace[904328317] 'agreement among raft nodes before linearized reading' (duration: 203.553087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:15.464249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.13719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:15.464276Z","caller":"traceutil/trace.go:171","msg":"trace[1318369512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24877; }","duration":"115.220793ms","start":"2026-07-21T14:12:15.349048Z","end":"2026-07-21T14:12:15.464269Z","steps":["trace[1318369512] 'agreement among raft nodes before linearized reading' (duration: 115.175472ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:15.67368Z","caller":"traceutil/trace.go:171","msg":"trace[1245043047] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"207.636617ms","start":"2026-07-21T14:12:15.466013Z","end":"2026-07-21T14:12:15.673649Z","steps":["trace[1245043047] 'process raft request' (duration: 141.836905ms)","trace[1245043047] 'compare' (duration: 65.491623ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:15.675646Z","caller":"traceutil/trace.go:171","msg":"trace[1276018100] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"208.861802ms","start":"2026-07-21T14:12:15.466762Z","end":"2026-07-21T14:12:15.675623Z","steps":["trace[1276018100] 'process raft request' (duration: 208.763939ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:15.675724Z","caller":"traceutil/trace.go:171","msg":"trace[2090308917] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"208.916114ms","start":"2026-07-21T14:12:15.46672Z","end":"2026-07-21T14:12:15.675636Z","steps":["trace[2090308917] 'process raft request' (duration: 208.754009ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:15.675924Z","caller":"traceutil/trace.go:171","msg":"trace[598199689] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"206.367229ms","start":"2026-07-21T14:12:15.469539Z","end":"2026-07-21T14:12:15.675906Z","steps":["trace[598199689] 'process raft request' (duration: 206.027909ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:15.676066Z","caller":"traceutil/trace.go:171","msg":"trace[1614008035] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"209.229402ms","start":"2026-07-21T14:12:15.466625Z","end":"2026-07-21T14:12:15.675855Z","steps":["trace[1614008035] 'process raft request' (duration: 208.719288ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:15.676095Z","caller":"traceutil/trace.go:171","msg":"trace[86008155] linearizableReadLoop","detail":"{readStateIndex:25694; appliedIndex:25689; }","duration":"203.442943ms","start":"2026-07-21T14:12:15.472643Z","end":"2026-07-21T14:12:15.676086Z","steps":["trace[86008155] 'read index received' (duration: 135.245271ms)","trace[86008155] 'applied index is now lower than readState.Index' (duration: 68.197022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:15.676275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.618558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-34c15290-a8c9-4963-a5ce-d8da18733630\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-21T14:12:15.676329Z","caller":"traceutil/trace.go:171","msg":"trace[253420279] range","detail":"{range_begin:/registry/persistentvolumes/pvc-34c15290-a8c9-4963-a5ce-d8da18733630; range_end:; response_count:1; response_revision:24885; }","duration":"203.717741ms","start":"2026-07-21T14:12:15.472597Z","end":"2026-07-21T14:12:15.676315Z","steps":["trace[253420279] 'agreement among raft nodes before linearized reading' (duration: 203.532975ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:41.878194Z","caller":"traceutil/trace.go:171","msg":"trace[1341097670] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"113.888944ms","start":"2026-07-21T14:12:41.764292Z","end":"2026-07-21T14:12:41.878181Z","steps":["trace[1341097670] 'process raft request' (duration: 113.752029ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:41.878137Z","caller":"traceutil/trace.go:171","msg":"trace[1515705880] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"115.784131ms","start":"2026-07-21T14:12:41.762331Z","end":"2026-07-21T14:12:41.878115Z","steps":["trace[1515705880] 'process raft request' (duration: 115.588144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:42.164311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.604294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:42.164606Z","caller":"traceutil/trace.go:171","msg":"trace[533681407] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"281.25976ms","start":"2026-07-21T14:12:41.883322Z","end":"2026-07-21T14:12:42.164582Z","steps":["trace[533681407] 'process raft request' (duration: 116.271475ms)","trace[533681407] 'compare' (duration: 164.053527ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:42.164627Z","caller":"traceutil/trace.go:171","msg":"trace[385539432] linearizableReadLoop","detail":"{readStateIndex:25913; appliedIndex:25911; }","duration":"266.156516ms","start":"2026-07-21T14:12:41.898447Z","end":"2026-07-21T14:12:42.164603Z","steps":["trace[385539432] 'read index received' (duration: 101.178781ms)","trace[385539432] 'applied index is now lower than readState.Index' (duration: 164.976515ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:42.164736Z","caller":"traceutil/trace.go:171","msg":"trace[793663322] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"280.997781ms","start":"2026-07-21T14:12:41.883695Z","end":"2026-07-21T14:12:42.164693Z","steps":["trace[793663322] 'process raft request' (duration: 280.785055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:42.164824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.361681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T14:12:42.164854Z","caller":"traceutil/trace.go:171","msg":"trace[1165734237] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25096; }","duration":"266.405103ms","start":"2026-07-21T14:12:41.898439Z","end":"2026-07-21T14:12:42.164844Z","steps":["trace[1165734237] 'agreement among raft nodes before linearized reading' (duration: 266.283049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:42.164892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.900113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-21T14:12:42.164903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.434281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:42.164944Z","caller":"traceutil/trace.go:171","msg":"trace[1714557750] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25096; }","duration":"165.021316ms","start":"2026-07-21T14:12:41.999907Z","end":"2026-07-21T14:12:42.164928Z","steps":["trace[1714557750] 'agreement among raft nodes before linearized reading' (duration: 164.835401ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:42.164982Z","caller":"traceutil/trace.go:171","msg":"trace[32072616] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25096; }","duration":"143.565535ms","start":"2026-07-21T14:12:42.021396Z","end":"2026-07-21T14:12:42.164961Z","steps":["trace[32072616] 'agreement among raft nodes before linearized reading' (duration: 143.432461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:42.478887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.015309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:42.47897Z","caller":"traceutil/trace.go:171","msg":"trace[1909049026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25096; }","duration":"127.136023ms","start":"2026-07-21T14:12:42.351816Z","end":"2026-07-21T14:12:42.478952Z","steps":["trace[1909049026] 'range keys from in-memory index tree' (duration: 126.933837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:42.479045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.117635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-21T14:12:42.479101Z","caller":"traceutil/trace.go:171","msg":"trace[1083143018] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:25096; }","duration":"139.210518ms","start":"2026-07-21T14:12:42.339876Z","end":"2026-07-21T14:12:42.479087Z","steps":["trace[1083143018] 'range keys from in-memory index tree' (duration: 138.90286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:42.775317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.500102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:42.775403Z","caller":"traceutil/trace.go:171","msg":"trace[1995422662] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"255.238994ms","start":"2026-07-21T14:12:42.520151Z","end":"2026-07-21T14:12:42.77539Z","steps":["trace[1995422662] 'process raft request' (duration: 124.604888ms)","trace[1995422662] 'compare' (duration: 130.312417ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:42.779071Z","caller":"traceutil/trace.go:171","msg":"trace[1601978149] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25916; }","duration":"180.443818ms","start":"2026-07-21T14:12:42.598616Z","end":"2026-07-21T14:12:42.779059Z","steps":["trace[1601978149] 'read index received' (duration: 46.159735ms)","trace[1601978149] 'applied index is now lower than readState.Index' (duration: 134.283393ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:42.779245Z","caller":"traceutil/trace.go:171","msg":"trace[1647507078] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"256.831791ms","start":"2026-07-21T14:12:42.522397Z","end":"2026-07-21T14:12:42.779229Z","steps":["trace[1647507078] 'process raft request' (duration: 256.530262ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:42.779252Z","caller":"traceutil/trace.go:171","msg":"trace[1463770727] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"213.494039ms","start":"2026-07-21T14:12:42.565714Z","end":"2026-07-21T14:12:42.779208Z","steps":["trace[1463770727] 'process raft request' (duration: 213.323154ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:42.779376Z","caller":"traceutil/trace.go:171","msg":"trace[2117737206] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"255.671327ms","start":"2026-07-21T14:12:42.523679Z","end":"2026-07-21T14:12:42.77935Z","steps":["trace[2117737206] 'process raft request' (duration: 255.332108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:42.779391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.761487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-21T14:12:42.779442Z","caller":"traceutil/trace.go:171","msg":"trace[1504764813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25102; }","duration":"180.84694ms","start":"2026-07-21T14:12:42.59858Z","end":"2026-07-21T14:12:42.779427Z","steps":["trace[1504764813] 'agreement among raft nodes before linearized reading' (duration: 180.672145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:43.104582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.117578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:43.104667Z","caller":"traceutil/trace.go:171","msg":"trace[635935091] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"289.179581ms","start":"2026-07-21T14:12:42.815472Z","end":"2026-07-21T14:12:43.104652Z","steps":["trace[635935091] 'process raft request' (duration: 139.923969ms)","trace[635935091] 'compare' (duration: 148.884942ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:43.350053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.868298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:43.350123Z","caller":"traceutil/trace.go:171","msg":"trace[1538750237] linearizableReadLoop","detail":"{readStateIndex:25923; appliedIndex:25922; }","duration":"192.733649ms","start":"2026-07-21T14:12:43.157379Z","end":"2026-07-21T14:12:43.350112Z","steps":["trace[1538750237] 'read index received' (duration: 52.736628ms)","trace[1538750237] 'applied index is now lower than readState.Index' (duration: 139.996201ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:43.350249Z","caller":"traceutil/trace.go:171","msg":"trace[201248250] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"265.945649ms","start":"2026-07-21T14:12:43.084296Z","end":"2026-07-21T14:12:43.350242Z","steps":["trace[201248250] 'process raft request' (duration: 125.832024ms)","trace[201248250] 'compare' (duration: 139.800066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:43.350554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.155522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-07-21T14:12:43.350648Z","caller":"traceutil/trace.go:171","msg":"trace[1618052550] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25105; }","duration":"193.292156ms","start":"2026-07-21T14:12:43.157333Z","end":"2026-07-21T14:12:43.350625Z","steps":["trace[1618052550] 'agreement among raft nodes before linearized reading' (duration: 192.864044ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:43.493702Z","caller":"traceutil/trace.go:171","msg":"trace[491925504] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"132.004516ms","start":"2026-07-21T14:12:43.36168Z","end":"2026-07-21T14:12:43.493684Z","steps":["trace[491925504] 'process raft request' (duration: 131.896533ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:44.040212Z","caller":"traceutil/trace.go:171","msg":"trace[218492012] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"152.776036ms","start":"2026-07-21T14:12:43.887407Z","end":"2026-07-21T14:12:44.040183Z","steps":["trace[218492012] 'process raft request' (duration: 144.75582ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:44.360497Z","caller":"traceutil/trace.go:171","msg":"trace[1909453398] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"101.633235ms","start":"2026-07-21T14:12:44.258833Z","end":"2026-07-21T14:12:44.360467Z","steps":["trace[1909453398] 'process raft request' (duration: 101.420729ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:44.450919Z","caller":"traceutil/trace.go:171","msg":"trace[135477404] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"190.878235ms","start":"2026-07-21T14:12:44.260009Z","end":"2026-07-21T14:12:44.450887Z","steps":["trace[135477404] 'process raft request' (duration: 190.631718ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:44.747712Z","caller":"traceutil/trace.go:171","msg":"trace[513193440] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"244.946774ms","start":"2026-07-21T14:12:44.502731Z","end":"2026-07-21T14:12:44.747678Z","steps":["trace[513193440] 'process raft request' (duration: 238.326268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:45.140555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.975826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:45.140724Z","caller":"traceutil/trace.go:171","msg":"trace[1091836896] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"615.604486ms","start":"2026-07-21T14:12:44.525089Z","end":"2026-07-21T14:12:45.140693Z","steps":["trace[1091836896] 'process raft request' (duration: 343.373173ms)","trace[1091836896] 'compare' (duration: 270.984267ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:45.140776Z","caller":"traceutil/trace.go:171","msg":"trace[718809394] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"356.228589ms","start":"2026-07-21T14:12:44.784528Z","end":"2026-07-21T14:12:45.140757Z","steps":["trace[718809394] 'process raft request' (duration: 356.133747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:45.140852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:44.784516Z","time spent":"356.300432ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:45.14085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:44.525062Z","time spent":"615.7214ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:45.141074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.477544ms","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:6"} {"level":"info","ts":"2026-07-21T14:12:45.14112Z","caller":"traceutil/trace.go:171","msg":"trace[439321268] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25115; }","duration":"612.571706ms","start":"2026-07-21T14:12:44.528534Z","end":"2026-07-21T14:12:45.141105Z","steps":["trace[439321268] 'agreement among raft nodes before linearized reading' (duration: 612.496594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:45.141169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:44.528511Z","time spent":"612.649159ms","remote":"127.0.0.1:37432","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-07-21T14:12:45.141201Z","caller":"traceutil/trace.go:171","msg":"trace[613709803] linearizableReadLoop","detail":"{readStateIndex:25932; appliedIndex:25930; }","duration":"612.159755ms","start":"2026-07-21T14:12:44.528605Z","end":"2026-07-21T14:12:45.140764Z","steps":["trace[613709803] 'read index received' (duration: 212.568052ms)","trace[613709803] 'applied index is now lower than readState.Index' (duration: 399.588642ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:45.141465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.63475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-21T14:12:45.141485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.442765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-21T14:12:45.141734Z","caller":"traceutil/trace.go:171","msg":"trace[226407730] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25115; }","duration":"358.551088ms","start":"2026-07-21T14:12:44.782975Z","end":"2026-07-21T14:12:45.141526Z","steps":["trace[226407730] 'agreement among raft nodes before linearized reading' (duration: 358.355232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:45.141797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:44.782955Z","time spent":"358.827967ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-21T14:12:45.141947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.292713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-21T14:12:45.141975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.027602ms","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-07-21T14:12:45.141504Z","caller":"traceutil/trace.go:171","msg":"trace[590338270] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25115; }","duration":"249.706892ms","start":"2026-07-21T14:12:44.891788Z","end":"2026-07-21T14:12:45.141495Z","steps":["trace[590338270] 'agreement among raft nodes before linearized reading' (duration: 249.6392ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:45.142007Z","caller":"traceutil/trace.go:171","msg":"trace[701796145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25115; }","duration":"338.376696ms","start":"2026-07-21T14:12:44.803614Z","end":"2026-07-21T14:12:45.141991Z","steps":["trace[701796145] 'agreement among raft nodes before linearized reading' (duration: 338.204231ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:45.142008Z","caller":"traceutil/trace.go:171","msg":"trace[2041426217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25115; }","duration":"358.072904ms","start":"2026-07-21T14:12:44.783927Z","end":"2026-07-21T14:12:45.142Z","steps":["trace[2041426217] 'agreement among raft nodes before linearized reading' (duration: 357.992282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:45.142042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:44.803603Z","time spent":"338.431707ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T14:12:45.142013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.050675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T14:12:45.142051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:44.783922Z","time spent":"358.122196ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T14:12:45.142096Z","caller":"traceutil/trace.go:171","msg":"trace[547153449] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25115; }","duration":"293.155808ms","start":"2026-07-21T14:12:44.848918Z","end":"2026-07-21T14:12:45.142073Z","steps":["trace[547153449] 'agreement among raft nodes before linearized reading' (duration: 293.012054ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:45.390675Z","caller":"traceutil/trace.go:171","msg":"trace[1224664971] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"245.373854ms","start":"2026-07-21T14:12:45.145278Z","end":"2026-07-21T14:12:45.390652Z","steps":["trace[1224664971] 'process raft request' (duration: 238.728319ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:45.395719Z","caller":"traceutil/trace.go:171","msg":"trace[1100456697] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"250.170595ms","start":"2026-07-21T14:12:45.145531Z","end":"2026-07-21T14:12:45.395702Z","steps":["trace[1100456697] 'process raft request' (duration: 250.031631ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:45.395736Z","caller":"traceutil/trace.go:171","msg":"trace[967601519] linearizableReadLoop","detail":"{readStateIndex:25937; appliedIndex:25933; }","duration":"230.55944ms","start":"2026-07-21T14:12:45.165165Z","end":"2026-07-21T14:12:45.395724Z","steps":["trace[967601519] 'read index received' (duration: 218.856557ms)","trace[967601519] 'applied index is now lower than readState.Index' (duration: 11.702023ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:45.395797Z","caller":"traceutil/trace.go:171","msg":"trace[1525461914] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"250.237177ms","start":"2026-07-21T14:12:45.145552Z","end":"2026-07-21T14:12:45.395789Z","steps":["trace[1525461914] 'process raft request' (duration: 250.079042ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:45.395917Z","caller":"traceutil/trace.go:171","msg":"trace[1821803692] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"250.33413ms","start":"2026-07-21T14:12:45.145573Z","end":"2026-07-21T14:12:45.395907Z","steps":["trace[1821803692] 'process raft request' (duration: 250.088173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:45.396078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.89426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:45.396112Z","caller":"traceutil/trace.go:171","msg":"trace[1653645476] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25120; }","duration":"231.018183ms","start":"2026-07-21T14:12:45.165085Z","end":"2026-07-21T14:12:45.396103Z","steps":["trace[1653645476] 'agreement among raft nodes before linearized reading' (duration: 230.945581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:45.396295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.865616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-07-21T14:12:45.396338Z","caller":"traceutil/trace.go:171","msg":"trace[580970260] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25120; }","duration":"107.938389ms","start":"2026-07-21T14:12:45.288385Z","end":"2026-07-21T14:12:45.396324Z","steps":["trace[580970260] 'agreement among raft nodes before linearized reading' (duration: 107.823785ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:45.826256Z","caller":"traceutil/trace.go:171","msg":"trace[1641530817] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"112.320977ms","start":"2026-07-21T14:12:45.713904Z","end":"2026-07-21T14:12:45.826225Z","steps":["trace[1641530817] 'process raft request' (duration: 112.156393ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:46.202638Z","caller":"traceutil/trace.go:171","msg":"trace[386857647] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"322.791158ms","start":"2026-07-21T14:12:45.879823Z","end":"2026-07-21T14:12:46.202614Z","steps":["trace[386857647] 'process raft request' (duration: 322.615703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:46.202821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:45.879798Z","time spent":"322.927402ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:12:46.26287Z","caller":"traceutil/trace.go:171","msg":"trace[140531479] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"343.311981ms","start":"2026-07-21T14:12:45.919531Z","end":"2026-07-21T14:12:46.262843Z","steps":["trace[140531479] 'process raft request' (duration: 342.842558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:46.26302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:45.91949Z","time spent":"343.453975ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:12:46.263457Z","caller":"traceutil/trace.go:171","msg":"trace[1211716148] linearizableReadLoop","detail":"{readStateIndex:25942; appliedIndex:25939; }","duration":"126.314728ms","start":"2026-07-21T14:12:46.137087Z","end":"2026-07-21T14:12:46.263402Z","steps":["trace[1211716148] 'read index received' (duration: 65.368659ms)","trace[1211716148] 'applied index is now lower than readState.Index' (duration: 60.941799ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:46.263532Z","caller":"traceutil/trace.go:171","msg":"trace[849458613] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"218.106345ms","start":"2026-07-21T14:12:46.045406Z","end":"2026-07-21T14:12:46.263512Z","steps":["trace[849458613] 'process raft request' (duration: 217.156417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:46.26374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.649769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:46.263789Z","caller":"traceutil/trace.go:171","msg":"trace[1014871548] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25124; }","duration":"126.733851ms","start":"2026-07-21T14:12:46.137043Z","end":"2026-07-21T14:12:46.263777Z","steps":["trace[1014871548] 'agreement among raft nodes before linearized reading' (duration: 126.652409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:46.753119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.460094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:46.753309Z","caller":"traceutil/trace.go:171","msg":"trace[289441605] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"250.410433ms","start":"2026-07-21T14:12:46.502877Z","end":"2026-07-21T14:12:46.753287Z","steps":["trace[289441605] 'process raft request' (duration: 60.692912ms)","trace[289441605] 'compare' (duration: 188.696631ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:46.961674Z","caller":"traceutil/trace.go:171","msg":"trace[898595181] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"208.842961ms","start":"2026-07-21T14:12:46.752812Z","end":"2026-07-21T14:12:46.961655Z","steps":["trace[898595181] 'process raft request' (duration: 208.698767ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:47.264127Z","caller":"traceutil/trace.go:171","msg":"trace[1083084381] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"117.049627ms","start":"2026-07-21T14:12:47.147046Z","end":"2026-07-21T14:12:47.264096Z","steps":["trace[1083084381] 'process raft request' (duration: 116.827801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:47.521253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.497956ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T14:12:47.521537Z","caller":"traceutil/trace.go:171","msg":"trace[36545048] linearizableReadLoop","detail":"{readStateIndex:25948; appliedIndex:25947; }","duration":"126.727071ms","start":"2026-07-21T14:12:47.394788Z","end":"2026-07-21T14:12:47.521515Z","steps":["trace[36545048] 'read index received' (duration: 16.990779ms)","trace[36545048] 'applied index is now lower than readState.Index' (duration: 109.735272ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:47.522096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.300768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-21T14:12:47.522117Z","caller":"traceutil/trace.go:171","msg":"trace[1287775490] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"121.653962ms","start":"2026-07-21T14:12:47.400431Z","end":"2026-07-21T14:12:47.522085Z","steps":["trace[1287775490] 'process raft request' (duration: 120.929091ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:47.522164Z","caller":"traceutil/trace.go:171","msg":"trace[372526254] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25129; }","duration":"127.435421ms","start":"2026-07-21T14:12:47.394709Z","end":"2026-07-21T14:12:47.522145Z","steps":["trace[372526254] 'agreement among raft nodes before linearized reading' (duration: 127.252956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:47.663333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.814127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-21T14:12:47.663399Z","caller":"traceutil/trace.go:171","msg":"trace[553744572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25130; }","duration":"262.89623ms","start":"2026-07-21T14:12:47.400482Z","end":"2026-07-21T14:12:47.663379Z","steps":["trace[553744572] 'agreement among raft nodes before linearized reading' (duration: 121.57374ms)","trace[553744572] 'range keys from in-memory index tree' (duration: 141.162856ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:47.66353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.348871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-21T14:12:47.66363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.472045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:47.663701Z","caller":"traceutil/trace.go:171","msg":"trace[1041560604] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25130; }","duration":"251.580608ms","start":"2026-07-21T14:12:47.412102Z","end":"2026-07-21T14:12:47.663682Z","steps":["trace[1041560604] 'agreement among raft nodes before linearized reading' (duration: 110.081653ms)","trace[1041560604] 'range keys from in-memory index tree' (duration: 141.388662ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:47.663725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.209969ms","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-07-21T14:12:47.663751Z","caller":"traceutil/trace.go:171","msg":"trace[1051534102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25130; }","duration":"263.258471ms","start":"2026-07-21T14:12:47.400484Z","end":"2026-07-21T14:12:47.663743Z","steps":["trace[1051534102] 'agreement among raft nodes before linearized reading' (duration: 121.752565ms)","trace[1051534102] 'range keys from in-memory index tree' (duration: 141.442654ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:47.66387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.322492ms","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-07-21T14:12:47.663936Z","caller":"traceutil/trace.go:171","msg":"trace[1234202647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25130; }","duration":"263.429866ms","start":"2026-07-21T14:12:47.400489Z","end":"2026-07-21T14:12:47.663919Z","steps":["trace[1234202647] 'agreement among raft nodes before linearized reading' (duration: 121.737755ms)","trace[1234202647] 'range keys from in-memory index tree' (duration: 141.518586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:47.663972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.766068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:47.664088Z","caller":"traceutil/trace.go:171","msg":"trace[564967734] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"136.728695ms","start":"2026-07-21T14:12:47.527334Z","end":"2026-07-21T14:12:47.664063Z","steps":["trace[564967734] 'process raft request' (duration: 33.800972ms)","trace[564967734] 'compare' (duration: 102.170571ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:47.663643Z","caller":"traceutil/trace.go:171","msg":"trace[32136563] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25130; }","duration":"172.481155ms","start":"2026-07-21T14:12:47.491134Z","end":"2026-07-21T14:12:47.663615Z","steps":["trace[32136563] 'agreement among raft nodes before linearized reading' (duration: 31.081642ms)","trace[32136563] 'count revisions from in-memory index tree' (duration: 141.243277ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:47.936059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.699912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:47.936271Z","caller":"traceutil/trace.go:171","msg":"trace[2059432757] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25951; }","duration":"163.250223ms","start":"2026-07-21T14:12:47.773003Z","end":"2026-07-21T14:12:47.936253Z","steps":["trace[2059432757] 'read index received' (duration: 54.132929ms)","trace[2059432757] 'applied index is now lower than readState.Index' (duration: 109.116614ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:47.936363Z","caller":"traceutil/trace.go:171","msg":"trace[361790990] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"267.895157ms","start":"2026-07-21T14:12:47.668457Z","end":"2026-07-21T14:12:47.936352Z","steps":["trace[361790990] 'process raft request' (duration: 158.807374ms)","trace[361790990] 'compare' (duration: 108.428474ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:47.936564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.545512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-21T14:12:47.936646Z","caller":"traceutil/trace.go:171","msg":"trace[1091167503] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25134; }","duration":"163.667476ms","start":"2026-07-21T14:12:47.772961Z","end":"2026-07-21T14:12:47.936629Z","steps":["trace[1091167503] 'agreement among raft nodes before linearized reading' (duration: 163.417509ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:47.93672Z","caller":"traceutil/trace.go:171","msg":"trace[88031304] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"265.98425ms","start":"2026-07-21T14:12:47.670721Z","end":"2026-07-21T14:12:47.936705Z","steps":["trace[88031304] 'process raft request' (duration: 265.434284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:49.339392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.604528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:49.339466Z","caller":"traceutil/trace.go:171","msg":"trace[336293884] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25142; }","duration":"156.721892ms","start":"2026-07-21T14:12:49.182727Z","end":"2026-07-21T14:12:49.339449Z","steps":["trace[336293884] 'count revisions from in-memory index tree' (duration: 156.548517ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:49.373686Z","caller":"traceutil/trace.go:171","msg":"trace[567164424] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"163.689806ms","start":"2026-07-21T14:12:49.209969Z","end":"2026-07-21T14:12:49.373659Z","steps":["trace[567164424] 'process raft request' (duration: 163.46105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:49.721174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.346042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:49.721346Z","caller":"traceutil/trace.go:171","msg":"trace[756992285] linearizableReadLoop","detail":"{readStateIndex:25964; appliedIndex:25962; }","duration":"136.003783ms","start":"2026-07-21T14:12:49.585329Z","end":"2026-07-21T14:12:49.721333Z","steps":["trace[756992285] 'read index received' (duration: 81.886544ms)","trace[756992285] 'applied index is now lower than readState.Index' (duration: 54.116529ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:12:49.721413Z","caller":"traceutil/trace.go:171","msg":"trace[1672177487] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"453.327301ms","start":"2026-07-21T14:12:49.268078Z","end":"2026-07-21T14:12:49.721406Z","steps":["trace[1672177487] 'process raft request' (duration: 219.673591ms)","trace[1672177487] 'compare' (duration: 233.111765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:49.721453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:49.268056Z","time spent":"453.375623ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T14:12:49.721568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.214979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:49.721665Z","caller":"traceutil/trace.go:171","msg":"trace[6299667] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25145; }","duration":"136.323622ms","start":"2026-07-21T14:12:49.585313Z","end":"2026-07-21T14:12:49.721637Z","steps":["trace[6299667] 'agreement among raft nodes before linearized reading' (duration: 136.114886ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:49.721728Z","caller":"traceutil/trace.go:171","msg":"trace[1407387684] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"194.404368ms","start":"2026-07-21T14:12:49.5273Z","end":"2026-07-21T14:12:49.721704Z","steps":["trace[1407387684] 'process raft request' (duration: 193.956615ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:50.100823Z","caller":"traceutil/trace.go:171","msg":"trace[953016607] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"154.832487ms","start":"2026-07-21T14:12:49.945964Z","end":"2026-07-21T14:12:50.100797Z","steps":["trace[953016607] 'process raft request' (duration: 154.683983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:50.268115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.281167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T14:12:50.268196Z","caller":"traceutil/trace.go:171","msg":"trace[836672131] linearizableReadLoop","detail":"{readStateIndex:25968; appliedIndex:25967; }","duration":"315.211655ms","start":"2026-07-21T14:12:49.952973Z","end":"2026-07-21T14:12:50.268184Z","steps":["trace[836672131] 'read index received' (duration: 147.834211ms)","trace[836672131] 'applied index is now lower than readState.Index' (duration: 167.376564ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:50.268249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.273698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T14:12:50.268265Z","caller":"traceutil/trace.go:171","msg":"trace[2138243721] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25149; }","duration":"315.310129ms","start":"2026-07-21T14:12:49.952947Z","end":"2026-07-21T14:12:50.268258Z","steps":["trace[2138243721] 'agreement among raft nodes before linearized reading' (duration: 315.265707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T14:12:50.268287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:49.952937Z","time spent":"315.34485ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-07-21T14:12:50.268292Z","caller":"traceutil/trace.go:171","msg":"trace[239131861] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"322.035886ms","start":"2026-07-21T14:12:49.946215Z","end":"2026-07-21T14:12:50.268251Z","steps":["trace[239131861] 'process raft request' (duration: 209.549432ms)","trace[239131861] 'compare' (duration: 111.947928ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T14:12:50.268434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T14:12:49.946192Z","time spent":"322.16989ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T14:12:50.350509Z","caller":"traceutil/trace.go:171","msg":"trace[297630213] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"135.361254ms","start":"2026-07-21T14:12:50.215114Z","end":"2026-07-21T14:12:50.350475Z","steps":["trace[297630213] 'process raft request' (duration: 135.163369ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:50.387249Z","caller":"traceutil/trace.go:171","msg":"trace[1038997568] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"113.008928ms","start":"2026-07-21T14:12:50.274215Z","end":"2026-07-21T14:12:50.387224Z","steps":["trace[1038997568] 'process raft request' (duration: 107.586239ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:50.387523Z","caller":"traceutil/trace.go:171","msg":"trace[398043876] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"111.105813ms","start":"2026-07-21T14:12:50.276383Z","end":"2026-07-21T14:12:50.387489Z","steps":["trace[398043876] 'process raft request' (duration: 110.798954ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:52.522549Z","caller":"traceutil/trace.go:171","msg":"trace[302595592] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"127.871354ms","start":"2026-07-21T14:12:52.394656Z","end":"2026-07-21T14:12:52.522528Z","steps":["trace[302595592] 'process raft request' (duration: 127.675859ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:52.522561Z","caller":"traceutil/trace.go:171","msg":"trace[2068963682] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"127.982777ms","start":"2026-07-21T14:12:52.394547Z","end":"2026-07-21T14:12:52.522529Z","steps":["trace[2068963682] 'process raft request' (duration: 127.73743ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T14:12:52.52286Z","caller":"traceutil/trace.go:171","msg":"trace[1295283890] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"143.312939ms","start":"2026-07-21T14:12:52.379533Z","end":"2026-07-21T14:12:52.522846Z","steps":["trace[1295283890] 'process raft request' (duration: 71.172831ms)","trace[1295283890] 'compare' (duration: 71.400546ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T14:14:07.474622Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006886.wal"} {"level":"info","ts":"2026-07-21T14:14:49.137969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23418} {"level":"info","ts":"2026-07-21T14:14:49.277159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23418,"took":"135.693315ms","hash":957502688,"current-db-size-bytes":59731968,"current-db-size":"60 MB","current-db-size-in-use-bytes":37126144,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-21T14:14:49.27726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":957502688,"revision":23418,"compact-revision":20126} {"level":"info","ts":"2026-07-21T14:19:49.143988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26302} {"level":"info","ts":"2026-07-21T14:19:49.291492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26302,"took":"143.629961ms","hash":2844875042,"current-db-size-bytes":59731968,"current-db-size":"60 MB","current-db-size-in-use-bytes":33886208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T14:19:49.291594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2844875042,"revision":26302,"compact-revision":23418} {"level":"info","ts":"2026-07-21T14:20:09.808115Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T14:20:09.813857Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-21T14:20:09.813959Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-21T14:24:49.15033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28898} {"level":"info","ts":"2026-07-21T14:24:49.287599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28898,"took":"133.280669ms","hash":250489419,"current-db-size-bytes":59731968,"current-db-size":"60 MB","current-db-size-in-use-bytes":30584832,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-21T14:24:49.28768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":250489419,"revision":28898,"compact-revision":26302} {"level":"info","ts":"2026-07-21T14:29:49.161178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31116} {"level":"info","ts":"2026-07-21T14:29:49.286705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31116,"took":"121.62851ms","hash":417105308,"current-db-size-bytes":59731968,"current-db-size":"60 MB","current-db-size-in-use-bytes":30642176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-21T14:29:49.286783Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":417105308,"revision":31116,"compact-revision":28898} {"level":"info","ts":"2026-07-21T14:34:49.17031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33383} {"level":"info","ts":"2026-07-21T14:34:49.329227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33383,"took":"154.799714ms","hash":2806842380,"current-db-size-bytes":60776448,"current-db-size":"61 MB","current-db-size-in-use-bytes":32460800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-21T14:34:49.329294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2806842380,"revision":33383,"compact-revision":31116} {"level":"info","ts":"2026-07-21T14:39:49.176685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35676} {"level":"info","ts":"2026-07-21T14:39:49.322004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35676,"took":"139.426662ms","hash":970411487,"current-db-size-bytes":60776448,"current-db-size":"61 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-21T14:39:49.322084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":970411487,"revision":35676,"compact-revision":33383} {"level":"info","ts":"2026-07-21T14:41:39.801413Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T14:41:39.806121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-21T14:41:39.806226Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-21T14:44:49.183228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37906} {"level":"info","ts":"2026-07-21T14:44:49.296511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37906,"took":"108.222685ms","hash":1418570788,"current-db-size-bytes":60776448,"current-db-size":"61 MB","current-db-size-in-use-bytes":31649792,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-21T14:44:49.296564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1418570788,"revision":37906,"compact-revision":35676}