{"level":"warn","ts":"2026-10-15T11:17:11.332962Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:17:11.333098Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T11:17:11.333198Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:17:11.333213Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-10-15T11:17:11.333242Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:17:11.334161Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-10-15T11:17:11.33426Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T11:17:11.339373Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.81841ms"} {"level":"info","ts":"2026-10-15T11:17:11.34989Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-10-15T11:17:11.350021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T11:17:11.350063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-10-15T11:17:11.350081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T11:17:11.3501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-10-15T11:17:11.350164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-10-15T11:17:11.356333Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T11:17:11.358838Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T11:17:11.360433Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T11:17:11.362689Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T11:17:11.363011Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T11:17:11.363199Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:17:11.363374Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:17:11.363399Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:17:11.364489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-10-15T11:17:11.365885Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-10-15T11:17:11.369075Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:17:11.369533Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T11:17:11.369627Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T11:17:11.370166Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-10-15T11:17:11.370225Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-10-15T11:17:11.653408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T11:17:11.653455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T11:17:11.653476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-10-15T11:17:11.653489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-10-15T11:17:11.653496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-10-15T11:17:11.653505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-10-15T11:17:11.653514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-10-15T11:17:11.655573Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:11.656276Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T11:17:11.656449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:17:11.656493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:17:11.656633Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T11:17:11.656711Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T11:17:11.658928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-10-15T11:17:11.659301Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:11.65939Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:11.659436Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:11.659531Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T11:17:18.569451Z","caller":"traceutil/trace.go:171","msg":"trace[1093450447] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"110.704517ms","start":"2026-10-15T11:17:18.45866Z","end":"2026-10-15T11:17:18.569364Z","steps":["trace[1093450447] 'process raft request' (duration: 110.469102ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:17:18.574399Z","caller":"traceutil/trace.go:171","msg":"trace[300042732] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"114.946112ms","start":"2026-10-15T11:17:18.459429Z","end":"2026-10-15T11:17:18.574375Z","steps":["trace[300042732] 'process raft request' (duration: 114.8537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:30.557965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.762774ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T11:17:58.70043Z","caller":"traceutil/trace.go:171","msg":"trace[133215297] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"107.602478ms","start":"2026-10-15T11:17:58.592765Z","end":"2026-10-15T11:17:58.700367Z","steps":["trace[133215297] 'process raft request' (duration: 107.438304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:05.453431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.910116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-15T11:18:05.453557Z","caller":"traceutil/trace.go:171","msg":"trace[2011234122] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:658; }","duration":"157.184813ms","start":"2026-10-15T11:18:05.296346Z","end":"2026-10-15T11:18:05.453531Z","steps":["trace[2011234122] 'range keys from in-memory index tree' (duration: 156.678491ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:05.91302Z","caller":"traceutil/trace.go:171","msg":"trace[665615071] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"347.518325ms","start":"2026-10-15T11:18:05.565465Z","end":"2026-10-15T11:18:05.912984Z","steps":["trace[665615071] 'process raft request' (duration: 347.256789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:05.913824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:05.565402Z","time spent":"347.728121ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:18:06.529823Z","caller":"traceutil/trace.go:171","msg":"trace[1811610015] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"754.706936ms","start":"2026-10-15T11:18:05.775046Z","end":"2026-10-15T11:18:06.529753Z","steps":["trace[1811610015] 'read index received' (duration: 137.808125ms)","trace[1811610015] 'applied index is now lower than readState.Index' (duration: 616.897251ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:06.529944Z","caller":"traceutil/trace.go:171","msg":"trace[1971385384] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"931.903612ms","start":"2026-10-15T11:18:05.597987Z","end":"2026-10-15T11:18:06.529891Z","steps":["trace[1971385384] 'process raft request' (duration: 902.493875ms)","trace[1971385384] 'compare' (duration: 28.70262ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:18:06.530128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:05.59795Z","time spent":"932.080247ms","remote":"127.0.0.1:41102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:18:06.530188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.118656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-15T11:18:06.530241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.021607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:18:06.530274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.44695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T11:18:06.530301Z","caller":"traceutil/trace.go:171","msg":"trace[979544629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"456.136409ms","start":"2026-10-15T11:18:06.074144Z","end":"2026-10-15T11:18:06.530281Z","steps":["trace[979544629] 'agreement among raft nodes before linearized reading' (duration: 455.994355ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:06.530311Z","caller":"traceutil/trace.go:171","msg":"trace[279612750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"755.2813ms","start":"2026-10-15T11:18:05.774993Z","end":"2026-10-15T11:18:06.530274Z","steps":["trace[279612750] 'agreement among raft nodes before linearized reading' (duration: 754.980992ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:06.530338Z","caller":"traceutil/trace.go:171","msg":"trace[1677244930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"359.592204ms","start":"2026-10-15T11:18:06.170725Z","end":"2026-10-15T11:18:06.530317Z","steps":["trace[1677244930] 'agreement among raft nodes before linearized reading' (duration: 359.43247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:06.530359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:06.074124Z","time spent":"456.225802ms","remote":"127.0.0.1:41036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T11:18:06.530377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:06.170699Z","time spent":"359.670926ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T11:18:06.530207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.859344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-15T11:18:06.530384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:05.77497Z","time spent":"755.398633ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T11:18:06.530479Z","caller":"traceutil/trace.go:171","msg":"trace[1321120362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"333.15783ms","start":"2026-10-15T11:18:06.197278Z","end":"2026-10-15T11:18:06.530436Z","steps":["trace[1321120362] 'agreement among raft nodes before linearized reading' (duration: 332.764151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:06.530542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:06.197257Z","time spent":"333.269642ms","remote":"127.0.0.1:57286","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-10-15T11:18:06.530624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.67981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T11:18:06.530677Z","caller":"traceutil/trace.go:171","msg":"trace[1189467911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"747.766313ms","start":"2026-10-15T11:18:05.782896Z","end":"2026-10-15T11:18:06.530662Z","steps":["trace[1189467911] 'agreement among raft nodes before linearized reading' (duration: 747.093907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:06.530721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:05.782875Z","time spent":"747.836405ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T11:18:11.796767Z","caller":"traceutil/trace.go:171","msg":"trace[1102805892] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"107.795052ms","start":"2026-10-15T11:18:11.688925Z","end":"2026-10-15T11:18:11.79672Z","steps":["trace[1102805892] 'process raft request' (duration: 87.623645ms)","trace[1102805892] 'compare' (duration: 19.920321ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:12.143412Z","caller":"traceutil/trace.go:171","msg":"trace[428692716] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"158.155898ms","start":"2026-10-15T11:18:11.9852Z","end":"2026-10-15T11:18:12.143356Z","steps":["trace[428692716] 'process raft request' (duration: 157.85747ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:40.461071Z","caller":"traceutil/trace.go:171","msg":"trace[1445846352] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"117.941143ms","start":"2026-10-15T11:18:40.343088Z","end":"2026-10-15T11:18:40.461029Z","steps":["trace[1445846352] 'process raft request' (duration: 75.823033ms)","trace[1445846352] 'compare' (duration: 41.836104ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:49.38422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.976575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-15T11:19:49.384299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.694934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T11:19:49.384318Z","caller":"traceutil/trace.go:171","msg":"trace[1304756997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1932; }","duration":"158.11335ms","start":"2026-10-15T11:19:49.226188Z","end":"2026-10-15T11:19:49.384301Z","steps":["trace[1304756997] 'range keys from in-memory index tree' (duration: 157.815541ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:49.384347Z","caller":"traceutil/trace.go:171","msg":"trace[1311560663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1932; }","duration":"119.769726ms","start":"2026-10-15T11:19:49.264563Z","end":"2026-10-15T11:19:49.384333Z","steps":["trace[1311560663] 'range keys from in-memory index tree' (duration: 119.560921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.3843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.508351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:19:49.384452Z","caller":"traceutil/trace.go:171","msg":"trace[1776292470] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1932; }","duration":"330.755087ms","start":"2026-10-15T11:19:49.053672Z","end":"2026-10-15T11:19:49.384427Z","steps":["trace[1776292470] 'count revisions from in-memory index tree' (duration: 330.380017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.38449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.130811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T11:19:49.384527Z","caller":"traceutil/trace.go:171","msg":"trace[2099902806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1932; }","duration":"117.221554ms","start":"2026-10-15T11:19:49.267293Z","end":"2026-10-15T11:19:49.384515Z","steps":["trace[2099902806] 'range keys from in-memory index tree' (duration: 116.988288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.384534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:49.053649Z","time spent":"330.85976ms","remote":"127.0.0.1:60910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-15T11:19:49.384556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.882433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:19:49.384582Z","caller":"traceutil/trace.go:171","msg":"trace[1132450250] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1932; }","duration":"183.961505ms","start":"2026-10-15T11:19:49.200614Z","end":"2026-10-15T11:19:49.384575Z","steps":["trace[1132450250] 'count revisions from in-memory index tree' (duration: 183.801131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.384594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.791715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:19:49.384875Z","caller":"traceutil/trace.go:171","msg":"trace[511646708] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1932; }","duration":"237.202485ms","start":"2026-10-15T11:19:49.14764Z","end":"2026-10-15T11:19:49.384842Z","steps":["trace[511646708] 'count revisions from in-memory index tree' (duration: 236.669541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.384891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.169532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-15T11:19:49.38491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.566297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b833764d-b81d-4bbc-a4a9-88d8cb421945\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-15T11:19:49.384751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.514293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-15T11:19:49.384963Z","caller":"traceutil/trace.go:171","msg":"trace[742134004] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b833764d-b81d-4bbc-a4a9-88d8cb421945; range_end:; response_count:1; response_revision:1932; }","duration":"381.666379ms","start":"2026-10-15T11:19:49.00328Z","end":"2026-10-15T11:19:49.384946Z","steps":["trace[742134004] 'range keys from in-memory index tree' (duration: 381.429353ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:49.384977Z","caller":"traceutil/trace.go:171","msg":"trace[330158331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1932; }","duration":"172.746109ms","start":"2026-10-15T11:19:49.212216Z","end":"2026-10-15T11:19:49.384962Z","steps":["trace[330158331] 'range keys from in-memory index tree' (duration: 172.456712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.385021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:49.003255Z","time spent":"381.746891ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b833764d-b81d-4bbc-a4a9-88d8cb421945\" "} {"level":"info","ts":"2026-10-15T11:19:49.385047Z","caller":"traceutil/trace.go:171","msg":"trace[262338299] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1932; }","duration":"130.303436ms","start":"2026-10-15T11:19:49.25463Z","end":"2026-10-15T11:19:49.384933Z","steps":["trace[262338299] 'range keys from in-memory index tree' (duration: 129.565908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.385081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.531084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-10-15T11:19:49.385189Z","caller":"traceutil/trace.go:171","msg":"trace[959717930] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1932; }","duration":"235.718809ms","start":"2026-10-15T11:19:49.149446Z","end":"2026-10-15T11:19:49.385165Z","steps":["trace[959717930] 'range keys from in-memory index tree' (duration: 235.276957ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:49.75457Z","caller":"traceutil/trace.go:171","msg":"trace[1017996382] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"364.176976ms","start":"2026-10-15T11:19:49.390367Z","end":"2026-10-15T11:19:49.754544Z","steps":["trace[1017996382] 'process raft request' (duration: 364.010342ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:49.7546Z","caller":"traceutil/trace.go:171","msg":"trace[1656501246] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"363.823048ms","start":"2026-10-15T11:19:49.390744Z","end":"2026-10-15T11:19:49.754567Z","steps":["trace[1656501246] 'process raft request' (duration: 363.697845ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:49.754576Z","caller":"traceutil/trace.go:171","msg":"trace[1598612301] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"362.928747ms","start":"2026-10-15T11:19:49.391611Z","end":"2026-10-15T11:19:49.754539Z","steps":["trace[1598612301] 'process raft request' (duration: 362.871396ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:49.754591Z","caller":"traceutil/trace.go:171","msg":"trace[413259535] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"364.306759ms","start":"2026-10-15T11:19:49.390239Z","end":"2026-10-15T11:19:49.754546Z","steps":["trace[413259535] 'process raft request' (duration: 288.855609ms)","trace[413259535] 'compare' (duration: 75.095721ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:49.754712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:49.390337Z","time spent":"364.301639ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:19:49.754791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:49.390203Z","time spent":"364.484314ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:19:49.754757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:49.391603Z","time spent":"363.06679ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:19:49.755129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:49.390737Z","time spent":"363.92861ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:19:49.795819Z","caller":"traceutil/trace.go:171","msg":"trace[656048715] linearizableReadLoop","detail":"{readStateIndex:2011; appliedIndex:2006; }","duration":"267.697385ms","start":"2026-10-15T11:19:49.528081Z","end":"2026-10-15T11:19:49.795778Z","steps":["trace[656048715] 'read index received' (duration: 150.970724ms)","trace[656048715] 'applied index is now lower than readState.Index' (duration: 116.725821ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:49.795967Z","caller":"traceutil/trace.go:171","msg":"trace[1408211387] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"236.429086ms","start":"2026-10-15T11:19:49.559525Z","end":"2026-10-15T11:19:49.795954Z","steps":["trace[1408211387] 'process raft request' (duration: 236.21188ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:49.79607Z","caller":"traceutil/trace.go:171","msg":"trace[1210198131] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"395.259461ms","start":"2026-10-15T11:19:49.400784Z","end":"2026-10-15T11:19:49.796043Z","steps":["trace[1210198131] 'process raft request' (duration: 394.80837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.796188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:49.400751Z","time spent":"395.377744ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:19:49.796497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.386932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-15T11:19:49.796605Z","caller":"traceutil/trace.go:171","msg":"trace[734431189] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1939; }","duration":"268.551946ms","start":"2026-10-15T11:19:49.528014Z","end":"2026-10-15T11:19:49.796566Z","steps":["trace[734431189] 'agreement among raft nodes before linearized reading' (duration: 268.123625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:49.797199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.114846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T11:19:49.797271Z","caller":"traceutil/trace.go:171","msg":"trace[19032136] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1940; }","duration":"207.241979ms","start":"2026-10-15T11:19:49.59001Z","end":"2026-10-15T11:19:49.797252Z","steps":["trace[19032136] 'agreement among raft nodes before linearized reading' (duration: 206.969232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:03.435891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.990232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:20:03.436052Z","caller":"traceutil/trace.go:171","msg":"trace[432722935] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2057; }","duration":"268.210748ms","start":"2026-10-15T11:20:03.167814Z","end":"2026-10-15T11:20:03.436025Z","steps":["trace[432722935] 'count revisions from in-memory index tree' (duration: 267.896109ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:20:40.137203Z","caller":"traceutil/trace.go:171","msg":"trace[971345718] linearizableReadLoop","detail":"{readStateIndex:2346; appliedIndex:2345; }","duration":"103.383898ms","start":"2026-10-15T11:20:40.033791Z","end":"2026-10-15T11:20:40.137175Z","steps":["trace[971345718] 'read index received' (duration: 103.189743ms)","trace[971345718] 'applied index is now lower than readState.Index' (duration: 192.815µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:20:40.137257Z","caller":"traceutil/trace.go:171","msg":"trace[412154217] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"133.56711ms","start":"2026-10-15T11:20:40.003664Z","end":"2026-10-15T11:20:40.137231Z","steps":["trace[412154217] 'process raft request' (duration: 133.348725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:40.137496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.679656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T11:20:40.137567Z","caller":"traceutil/trace.go:171","msg":"trace[350499785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2261; }","duration":"103.829769ms","start":"2026-10-15T11:20:40.033719Z","end":"2026-10-15T11:20:40.137549Z","steps":["trace[350499785] 'agreement among raft nodes before linearized reading' (duration: 103.595433ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:20:45.547632Z","caller":"traceutil/trace.go:171","msg":"trace[2044371130] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"137.773613ms","start":"2026-10-15T11:20:45.40982Z","end":"2026-10-15T11:20:45.547593Z","steps":["trace[2044371130] 'process raft request' (duration: 112.810866ms)","trace[2044371130] 'compare' (duration: 24.810293ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:20:45.569886Z","caller":"traceutil/trace.go:171","msg":"trace[2000610070] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"157.261846ms","start":"2026-10-15T11:20:45.412592Z","end":"2026-10-15T11:20:45.569854Z","steps":["trace[2000610070] 'process raft request' (duration: 157.0166ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:22:06.176137Z","caller":"traceutil/trace.go:171","msg":"trace[52600685] transaction","detail":"{read_only:false; response_revision:2919; number_of_response:1; }","duration":"129.671917ms","start":"2026-10-15T11:22:06.046427Z","end":"2026-10-15T11:22:06.176098Z","steps":["trace[52600685] 'process raft request' (duration: 104.419784ms)","trace[52600685] 'compare' (duration: 25.102399ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:22:06.609491Z","caller":"traceutil/trace.go:171","msg":"trace[155814104] transaction","detail":"{read_only:false; response_revision:2920; number_of_response:1; }","duration":"129.119463ms","start":"2026-10-15T11:22:06.480346Z","end":"2026-10-15T11:22:06.609465Z","steps":["trace[155814104] 'process raft request' (duration: 128.56002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:29.87789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.629422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:25:29.878335Z","caller":"traceutil/trace.go:171","msg":"trace[972112939] linearizableReadLoop","detail":"{readStateIndex:4350; appliedIndex:4349; }","duration":"168.066299ms","start":"2026-10-15T11:25:29.710247Z","end":"2026-10-15T11:25:29.878313Z","steps":["trace[972112939] 'read index received' (duration: 73.442µs)","trace[972112939] 'applied index is now lower than readState.Index' (duration: 167.990747ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:25:29.878376Z","caller":"traceutil/trace.go:171","msg":"trace[1764661542] transaction","detail":"{read_only:false; response_revision:4191; number_of_response:1; }","duration":"254.986248ms","start":"2026-10-15T11:25:29.623331Z","end":"2026-10-15T11:25:29.878317Z","steps":["trace[1764661542] 'process raft request' (duration: 69.826882ms)","trace[1764661542] 'compare' (duration: 184.295054ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:25:29.878589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.320305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-15T11:25:29.878616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.859395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T11:25:29.878655Z","caller":"traceutil/trace.go:171","msg":"trace[1700202977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4191; }","duration":"168.440787ms","start":"2026-10-15T11:25:29.710195Z","end":"2026-10-15T11:25:29.878636Z","steps":["trace[1700202977] 'agreement among raft nodes before linearized reading' (duration: 168.203802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:29.878667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.217133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:25:29.878697Z","caller":"traceutil/trace.go:171","msg":"trace[1608896121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4191; }","duration":"150.302805ms","start":"2026-10-15T11:25:29.728384Z","end":"2026-10-15T11:25:29.878687Z","steps":["trace[1608896121] 'agreement among raft nodes before linearized reading' (duration: 150.193933ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:29.878658Z","caller":"traceutil/trace.go:171","msg":"trace[206412740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4191; }","duration":"158.977307ms","start":"2026-10-15T11:25:29.719668Z","end":"2026-10-15T11:25:29.878646Z","steps":["trace[206412740] 'agreement among raft nodes before linearized reading' (duration: 158.760033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:29.878773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.442059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:25:29.878809Z","caller":"traceutil/trace.go:171","msg":"trace[1923277409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4191; }","duration":"150.49415ms","start":"2026-10-15T11:25:29.728302Z","end":"2026-10-15T11:25:29.878796Z","steps":["trace[1923277409] 'agreement among raft nodes before linearized reading' (duration: 150.373867ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:30.022681Z","caller":"traceutil/trace.go:171","msg":"trace[1308447938] transaction","detail":"{read_only:false; response_revision:4192; number_of_response:1; }","duration":"139.408479ms","start":"2026-10-15T11:25:29.883244Z","end":"2026-10-15T11:25:30.022652Z","steps":["trace[1308447938] 'process raft request' (duration: 101.741431ms)","trace[1308447938] 'compare' (duration: 37.497734ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:25:30.022954Z","caller":"traceutil/trace.go:171","msg":"trace[431164688] transaction","detail":"{read_only:false; response_revision:4193; number_of_response:1; }","duration":"139.115562ms","start":"2026-10-15T11:25:29.883788Z","end":"2026-10-15T11:25:30.022904Z","steps":["trace[431164688] 'process raft request' (duration: 138.823095ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:30.023041Z","caller":"traceutil/trace.go:171","msg":"trace[1244816575] transaction","detail":"{read_only:false; response_revision:4194; number_of_response:1; }","duration":"139.124613ms","start":"2026-10-15T11:25:29.883882Z","end":"2026-10-15T11:25:30.023007Z","steps":["trace[1244816575] 'process raft request' (duration: 138.964839ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:30.023196Z","caller":"traceutil/trace.go:171","msg":"trace[1679050727] transaction","detail":"{read_only:false; response_revision:4195; number_of_response:1; }","duration":"138.122829ms","start":"2026-10-15T11:25:29.885046Z","end":"2026-10-15T11:25:30.023169Z","steps":["trace[1679050727] 'process raft request' (duration: 137.915254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:01.911868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.250811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-15T11:27:01.911989Z","caller":"traceutil/trace.go:171","msg":"trace[932386082] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5277; }","duration":"246.415995ms","start":"2026-10-15T11:27:01.665548Z","end":"2026-10-15T11:27:01.911963Z","steps":["trace[932386082] 'range keys from in-memory index tree' (duration: 246.004886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:04.389737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.796889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-10-15T11:27:04.389862Z","caller":"traceutil/trace.go:171","msg":"trace[820625790] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5410; }","duration":"149.973433ms","start":"2026-10-15T11:27:04.239866Z","end":"2026-10-15T11:27:04.38984Z","steps":["trace[820625790] 'range keys from in-memory index tree' (duration: 149.532592ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:04.390139Z","caller":"traceutil/trace.go:171","msg":"trace[1339749360] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"124.311506ms","start":"2026-10-15T11:27:04.265791Z","end":"2026-10-15T11:27:04.390102Z","steps":["trace[1339749360] 'process raft request' (duration: 100.901122ms)","trace[1339749360] 'compare' (duration: 22.904831ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:27:04.389744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.968368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:27:04.390345Z","caller":"traceutil/trace.go:171","msg":"trace[510386153] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5410; }","duration":"191.671945ms","start":"2026-10-15T11:27:04.198626Z","end":"2026-10-15T11:27:04.390298Z","steps":["trace[510386153] 'count revisions from in-memory index tree' (duration: 190.804213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:04.62661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.518675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728723"} {"level":"info","ts":"2026-10-15T11:27:04.626722Z","caller":"traceutil/trace.go:171","msg":"trace[432225352] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5411; }","duration":"136.671067ms","start":"2026-10-15T11:27:04.49001Z","end":"2026-10-15T11:27:04.626681Z","steps":["trace[432225352] 'range keys from in-memory index tree' (duration: 135.430397ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:04.627813Z","caller":"traceutil/trace.go:171","msg":"trace[1421072234] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"128.557008ms","start":"2026-10-15T11:27:04.499209Z","end":"2026-10-15T11:27:04.627766Z","steps":["trace[1421072234] 'process raft request' (duration: 128.170979ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:11.848967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-10-15T11:27:11.95301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"101.982728ms","hash":4200412835,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":30633984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T11:27:11.953104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4200412835,"revision":2950,"compact-revision":-1} {"level":"info","ts":"2026-10-15T11:27:29.888244Z","caller":"traceutil/trace.go:171","msg":"trace[969708639] linearizableReadLoop","detail":"{readStateIndex:6095; appliedIndex:6094; }","duration":"118.711496ms","start":"2026-10-15T11:27:29.769469Z","end":"2026-10-15T11:27:29.888181Z","steps":["trace[969708639] 'read index received' (duration: 118.381868ms)","trace[969708639] 'applied index is now lower than readState.Index' (duration: 327.678µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:27:29.88831Z","caller":"traceutil/trace.go:171","msg":"trace[2084785018] transaction","detail":"{read_only:false; response_revision:5875; number_of_response:1; }","duration":"188.753697ms","start":"2026-10-15T11:27:29.699536Z","end":"2026-10-15T11:27:29.88829Z","steps":["trace[2084785018] 'process raft request' (duration: 188.416649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:29.888639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.137766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e5c4d4f-cd70-4633-8c38-f0b8f7b2754f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-15T11:27:29.88878Z","caller":"traceutil/trace.go:171","msg":"trace[28976139] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e5c4d4f-cd70-4633-8c38-f0b8f7b2754f; range_end:; response_count:1; response_revision:5875; }","duration":"119.377112ms","start":"2026-10-15T11:27:29.769374Z","end":"2026-10-15T11:27:29.888751Z","steps":["trace[28976139] 'agreement among raft nodes before linearized reading' (duration: 118.986872ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:30.283066Z","caller":"traceutil/trace.go:171","msg":"trace[171101700] linearizableReadLoop","detail":"{readStateIndex:6096; appliedIndex:6095; }","duration":"208.304867ms","start":"2026-10-15T11:27:30.074723Z","end":"2026-10-15T11:27:30.283028Z","steps":["trace[171101700] 'read index received' (duration: 208.078761ms)","trace[171101700] 'applied index is now lower than readState.Index' (duration: 224.016µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:27:30.283215Z","caller":"traceutil/trace.go:171","msg":"trace[689209706] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"267.251484ms","start":"2026-10-15T11:27:30.015905Z","end":"2026-10-15T11:27:30.283156Z","steps":["trace[689209706] 'process raft request' (duration: 266.846524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:30.283323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.589484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:27:30.283391Z","caller":"traceutil/trace.go:171","msg":"trace[324445220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5876; }","duration":"208.737648ms","start":"2026-10-15T11:27:30.074637Z","end":"2026-10-15T11:27:30.283374Z","steps":["trace[324445220] 'agreement among raft nodes before linearized reading' (duration: 208.594744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:30.439247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.236868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:27:30.439372Z","caller":"traceutil/trace.go:171","msg":"trace[31376128] linearizableReadLoop","detail":"{readStateIndex:6097; appliedIndex:6096; }","duration":"156.164085ms","start":"2026-10-15T11:27:30.283188Z","end":"2026-10-15T11:27:30.439352Z","steps":["trace[31376128] 'read index received' (duration: 47.605829ms)","trace[31376128] 'applied index is now lower than readState.Index' (duration: 108.556956ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:27:30.439463Z","caller":"traceutil/trace.go:171","msg":"trace[739945742] transaction","detail":"{read_only:false; response_revision:5877; number_of_response:1; }","duration":"322.670075ms","start":"2026-10-15T11:27:30.116759Z","end":"2026-10-15T11:27:30.439429Z","steps":["trace[739945742] 'process raft request' (duration: 214.125439ms)","trace[739945742] 'compare' (duration: 108.079725ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:27:30.439512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.663869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:27:30.439552Z","caller":"traceutil/trace.go:171","msg":"trace[296255724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5877; }","duration":"154.757081ms","start":"2026-10-15T11:27:30.284781Z","end":"2026-10-15T11:27:30.439539Z","steps":["trace[296255724] 'agreement among raft nodes before linearized reading' (duration: 154.685269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:30.439584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.968702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:27:30.439599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:27:30.116717Z","time spent":"322.817309ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:27:30.439647Z","caller":"traceutil/trace.go:171","msg":"trace[766760107] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5877; }","duration":"141.037164ms","start":"2026-10-15T11:27:30.298591Z","end":"2026-10-15T11:27:30.439628Z","steps":["trace[766760107] 'agreement among raft nodes before linearized reading' (duration: 140.933741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:30.440082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.173111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-15T11:27:30.440222Z","caller":"traceutil/trace.go:171","msg":"trace[287811425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5877; }","duration":"236.406597ms","start":"2026-10-15T11:27:30.203787Z","end":"2026-10-15T11:27:30.440194Z","steps":["trace[287811425] 'agreement among raft nodes before linearized reading' (duration: 235.671369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:05.991255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.159778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:32:05.991357Z","caller":"traceutil/trace.go:171","msg":"trace[1325495069] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7950; }","duration":"123.331932ms","start":"2026-10-15T11:32:05.868002Z","end":"2026-10-15T11:32:05.991334Z","steps":["trace[1325495069] 'range keys from in-memory index tree' (duration: 123.064485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:05.991411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.827225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:32:05.991552Z","caller":"traceutil/trace.go:171","msg":"trace[2058620516] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:0; response_revision:7950; }","duration":"124.03486ms","start":"2026-10-15T11:32:05.867489Z","end":"2026-10-15T11:32:05.991524Z","steps":["trace[2058620516] 'range keys from in-memory index tree' (duration: 123.698401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:05.991593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.02282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:32:05.991702Z","caller":"traceutil/trace.go:171","msg":"trace[1537557268] range","detail":"{range_begin:/registry/jobs/openstack/barbican-rabbit-init; range_end:; response_count:0; response_revision:7950; }","duration":"124.185544ms","start":"2026-10-15T11:32:05.867497Z","end":"2026-10-15T11:32:05.991682Z","steps":["trace[1537557268] 'range keys from in-memory index tree' (duration: 123.852855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:05.992282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.740378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-db-sync\" ","response":"range_response_count:1 size:5488"} {"level":"info","ts":"2026-10-15T11:32:05.992514Z","caller":"traceutil/trace.go:171","msg":"trace[1444908579] range","detail":"{range_begin:/registry/jobs/openstack/barbican-db-sync; range_end:; response_count:1; response_revision:7950; }","duration":"125.065106ms","start":"2026-10-15T11:32:05.867432Z","end":"2026-10-15T11:32:05.992497Z","steps":["trace[1444908579] 'range keys from in-memory index tree' (duration: 124.385998ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:11.855865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5555} {"level":"info","ts":"2026-10-15T11:32:11.949315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5555,"took":"91.774026ms","hash":1208537654,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":29126656,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T11:32:11.949912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1208537654,"revision":5555,"compact-revision":2950} {"level":"warn","ts":"2026-10-15T11:33:41.671205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.243706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:33:41.671439Z","caller":"traceutil/trace.go:171","msg":"trace[1501690016] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"320.18213ms","start":"2026-10-15T11:33:41.35124Z","end":"2026-10-15T11:33:41.671422Z","steps":["trace[1501690016] 'process raft request' (duration: 320.077167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:41.671501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:33:41.351205Z","time spent":"320.265692ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:33:41.671709Z","caller":"traceutil/trace.go:171","msg":"trace[1445013005] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"571.13188ms","start":"2026-10-15T11:33:41.100565Z","end":"2026-10-15T11:33:41.671697Z","steps":["trace[1445013005] 'process raft request' (duration: 279.306941ms)","trace[1445013005] 'compare' (duration: 290.806814ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:33:41.671757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:33:41.100534Z","time spent":"571.196241ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:33:41.693419Z","caller":"traceutil/trace.go:171","msg":"trace[1823407678] linearizableReadLoop","detail":"{readStateIndex:9050; appliedIndex:9047; }","duration":"132.765694ms","start":"2026-10-15T11:33:41.560595Z","end":"2026-10-15T11:33:41.693361Z","steps":["trace[1823407678] 'read index received' (duration: 63.293291ms)","trace[1823407678] 'applied index is now lower than readState.Index' (duration: 69.471413ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:33:41.693513Z","caller":"traceutil/trace.go:171","msg":"trace[447658657] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"212.481262ms","start":"2026-10-15T11:33:41.480985Z","end":"2026-10-15T11:33:41.693466Z","steps":["trace[447658657] 'process raft request' (duration: 212.216485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:41.693667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.020471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-15T11:33:41.693725Z","caller":"traceutil/trace.go:171","msg":"trace[869945685] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8737; }","duration":"133.160064ms","start":"2026-10-15T11:33:41.560552Z","end":"2026-10-15T11:33:41.693712Z","steps":["trace[869945685] 'agreement among raft nodes before linearized reading' (duration: 132.947809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:52.361261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.217465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:52.361422Z","caller":"traceutil/trace.go:171","msg":"trace[332924173] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8792; }","duration":"124.443241ms","start":"2026-10-15T11:33:52.236918Z","end":"2026-10-15T11:33:52.361362Z","steps":["trace[332924173] 'count revisions from in-memory index tree' (duration: 124.091031ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:53.149243Z","caller":"traceutil/trace.go:171","msg":"trace[1110151800] linearizableReadLoop","detail":"{readStateIndex:9110; appliedIndex:9109; }","duration":"145.327924ms","start":"2026-10-15T11:33:53.003883Z","end":"2026-10-15T11:33:53.149211Z","steps":["trace[1110151800] 'read index received' (duration: 145.218702ms)","trace[1110151800] 'applied index is now lower than readState.Index' (duration: 108.072µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:33:53.149837Z","caller":"traceutil/trace.go:171","msg":"trace[469551885] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"158.433117ms","start":"2026-10-15T11:33:52.991331Z","end":"2026-10-15T11:33:53.149764Z","steps":["trace[469551885] 'process raft request' (duration: 157.710959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:53.150198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.765333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:53.150351Z","caller":"traceutil/trace.go:171","msg":"trace[461970396] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8794; }","duration":"110.972678ms","start":"2026-10-15T11:33:53.039346Z","end":"2026-10-15T11:33:53.150319Z","steps":["trace[461970396] 'agreement among raft nodes before linearized reading' (duration: 110.680991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:53.150484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.981433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:53.150542Z","caller":"traceutil/trace.go:171","msg":"trace[86542733] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8794; }","duration":"110.113706ms","start":"2026-10-15T11:33:53.040415Z","end":"2026-10-15T11:33:53.150529Z","steps":["trace[86542733] 'agreement among raft nodes before linearized reading' (duration: 110.018144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:53.150534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.642487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-15T11:33:53.150552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.139542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:53.150651Z","caller":"traceutil/trace.go:171","msg":"trace[927154218] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8794; }","duration":"111.235074ms","start":"2026-10-15T11:33:53.039377Z","end":"2026-10-15T11:33:53.150612Z","steps":["trace[927154218] 'agreement among raft nodes before linearized reading' (duration: 111.124672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:53.150659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.304181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:53.150696Z","caller":"traceutil/trace.go:171","msg":"trace[1506808553] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8794; }","duration":"140.382802ms","start":"2026-10-15T11:33:53.010305Z","end":"2026-10-15T11:33:53.150688Z","steps":["trace[1506808553] 'agreement among raft nodes before linearized reading' (duration: 140.30466ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:53.150584Z","caller":"traceutil/trace.go:171","msg":"trace[245723188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8794; }","duration":"146.732409ms","start":"2026-10-15T11:33:53.003837Z","end":"2026-10-15T11:33:53.150569Z","steps":["trace[245723188] 'agreement among raft nodes before linearized reading' (duration: 146.550785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:53.150957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.833874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:53.151002Z","caller":"traceutil/trace.go:171","msg":"trace[2083119053] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8794; }","duration":"140.889176ms","start":"2026-10-15T11:33:53.010102Z","end":"2026-10-15T11:33:53.150991Z","steps":["trace[2083119053] 'agreement among raft nodes before linearized reading' (duration: 140.826484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:53.151092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.991678ms","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:5"} {"level":"info","ts":"2026-10-15T11:33:53.151158Z","caller":"traceutil/trace.go:171","msg":"trace[1632149778] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8794; }","duration":"141.07733ms","start":"2026-10-15T11:33:53.01007Z","end":"2026-10-15T11:33:53.151147Z","steps":["trace[1632149778] 'agreement among raft nodes before linearized reading' (duration: 139.777047ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:53.260859Z","caller":"traceutil/trace.go:171","msg":"trace[1352516571] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"101.991706ms","start":"2026-10-15T11:33:53.158839Z","end":"2026-10-15T11:33:53.260831Z","steps":["trace[1352516571] 'process raft request' (duration: 101.811251ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:53.954746Z","caller":"traceutil/trace.go:171","msg":"trace[1580346562] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"182.487682ms","start":"2026-10-15T11:33:53.77223Z","end":"2026-10-15T11:33:53.954718Z","steps":["trace[1580346562] 'process raft request' (duration: 182.286887ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:54.032812Z","caller":"traceutil/trace.go:171","msg":"trace[515535802] linearizableReadLoop","detail":"{readStateIndex:9116; appliedIndex:9113; }","duration":"198.833325ms","start":"2026-10-15T11:33:53.833952Z","end":"2026-10-15T11:33:54.032785Z","steps":["trace[515535802] 'read index received' (duration: 120.576055ms)","trace[515535802] 'applied index is now lower than readState.Index' (duration: 78.25613ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:33:54.033026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.04761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gsnpf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T11:33:54.033064Z","caller":"traceutil/trace.go:171","msg":"trace[165185923] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gsnpf; range_end:; response_count:1; response_revision:8800; }","duration":"199.130182ms","start":"2026-10-15T11:33:53.833923Z","end":"2026-10-15T11:33:54.033053Z","steps":["trace[165185923] 'agreement among raft nodes before linearized reading' (duration: 198.959097ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:54.03312Z","caller":"traceutil/trace.go:171","msg":"trace[1340654032] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"244.999694ms","start":"2026-10-15T11:33:53.788095Z","end":"2026-10-15T11:33:54.033094Z","steps":["trace[1340654032] 'process raft request' (duration: 244.44601ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:54.033289Z","caller":"traceutil/trace.go:171","msg":"trace[416009105] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"233.56071ms","start":"2026-10-15T11:33:53.799718Z","end":"2026-10-15T11:33:54.033278Z","steps":["trace[416009105] 'process raft request' (duration: 232.992297ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:54.852706Z","caller":"traceutil/trace.go:171","msg":"trace[700496730] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"154.261505ms","start":"2026-10-15T11:33:54.698418Z","end":"2026-10-15T11:33:54.85268Z","steps":["trace[700496730] 'process raft request' (duration: 154.105671ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:20.251409Z","caller":"traceutil/trace.go:171","msg":"trace[1693279999] transaction","detail":"{read_only:false; response_revision:8992; number_of_response:1; }","duration":"128.039979ms","start":"2026-10-15T11:34:20.123346Z","end":"2026-10-15T11:34:20.251386Z","steps":["trace[1693279999] 'process raft request' (duration: 127.858674ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:20.256799Z","caller":"traceutil/trace.go:171","msg":"trace[872177588] transaction","detail":"{read_only:false; response_revision:8993; number_of_response:1; }","duration":"109.605422ms","start":"2026-10-15T11:34:20.147167Z","end":"2026-10-15T11:34:20.256772Z","steps":["trace[872177588] 'process raft request' (duration: 109.418888ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:23.77295Z","caller":"traceutil/trace.go:171","msg":"trace[1100999580] linearizableReadLoop","detail":"{readStateIndex:9332; appliedIndex:9331; }","duration":"348.149077ms","start":"2026-10-15T11:34:23.424772Z","end":"2026-10-15T11:34:23.772921Z","steps":["trace[1100999580] 'read index received' (duration: 347.912132ms)","trace[1100999580] 'applied index is now lower than readState.Index' (duration: 235.805µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:34:23.773084Z","caller":"traceutil/trace.go:171","msg":"trace[862588576] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"454.8257ms","start":"2026-10-15T11:34:23.318245Z","end":"2026-10-15T11:34:23.77307Z","steps":["trace[862588576] 'process raft request' (duration: 454.498193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:34:23.773207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:34:23.318229Z","time spent":"454.884731ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:34:23.773422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.481515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:34:23.773475Z","caller":"traceutil/trace.go:171","msg":"trace[507002972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9008; }","duration":"348.546408ms","start":"2026-10-15T11:34:23.424916Z","end":"2026-10-15T11:34:23.773462Z","steps":["trace[507002972] 'agreement among raft nodes before linearized reading' (duration: 348.364033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:34:23.773498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:34:23.424907Z","time spent":"348.585968ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T11:34:23.773486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.685761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-15T11:34:23.7735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.63185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T11:34:23.773587Z","caller":"traceutil/trace.go:171","msg":"trace[783662989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9008; }","duration":"348.836464ms","start":"2026-10-15T11:34:23.424727Z","end":"2026-10-15T11:34:23.773564Z","steps":["trace[783662989] 'agreement among raft nodes before linearized reading' (duration: 348.537127ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:23.773597Z","caller":"traceutil/trace.go:171","msg":"trace[1547639568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9008; }","duration":"348.760642ms","start":"2026-10-15T11:34:23.424814Z","end":"2026-10-15T11:34:23.773574Z","steps":["trace[1547639568] 'agreement among raft nodes before linearized reading' (duration: 348.456115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:34:23.77366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:34:23.424706Z","time spent":"348.944187ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T11:34:23.773671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:34:23.424808Z","time spent":"348.851475ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T11:34:23.98682Z","caller":"traceutil/trace.go:171","msg":"trace[285046710] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"208.467982ms","start":"2026-10-15T11:34:23.77832Z","end":"2026-10-15T11:34:23.986788Z","steps":["trace[285046710] 'process raft request' (duration: 129.147945ms)","trace[285046710] 'compare' (duration: 79.198714ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:34:23.987365Z","caller":"traceutil/trace.go:171","msg":"trace[2056174051] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"208.633137ms","start":"2026-10-15T11:34:23.778708Z","end":"2026-10-15T11:34:23.987341Z","steps":["trace[2056174051] 'process raft request' (duration: 208.461633ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:23.987612Z","caller":"traceutil/trace.go:171","msg":"trace[1590697286] transaction","detail":"{read_only:false; response_revision:9011; number_of_response:1; }","duration":"208.097634ms","start":"2026-10-15T11:34:23.779486Z","end":"2026-10-15T11:34:23.987584Z","steps":["trace[1590697286] 'process raft request' (duration: 207.808887ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:26.220503Z","caller":"traceutil/trace.go:171","msg":"trace[397895302] transaction","detail":"{read_only:false; response_revision:9021; number_of_response:1; }","duration":"224.405276ms","start":"2026-10-15T11:34:25.996073Z","end":"2026-10-15T11:34:26.220478Z","steps":["trace[397895302] 'process raft request' (duration: 224.262902ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:26.27047Z","caller":"traceutil/trace.go:171","msg":"trace[1878904527] transaction","detail":"{read_only:false; response_revision:9022; number_of_response:1; }","duration":"273.110087ms","start":"2026-10-15T11:34:25.997326Z","end":"2026-10-15T11:34:26.270436Z","steps":["trace[1878904527] 'process raft request' (duration: 272.867651ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:26.27074Z","caller":"traceutil/trace.go:171","msg":"trace[1461916713] linearizableReadLoop","detail":"{readStateIndex:9348; appliedIndex:9346; }","duration":"197.8662ms","start":"2026-10-15T11:34:26.072856Z","end":"2026-10-15T11:34:26.270722Z","steps":["trace[1461916713] 'read index received' (duration: 147.60902ms)","trace[1461916713] 'applied index is now lower than readState.Index' (duration: 50.2558ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:34:26.270853Z","caller":"traceutil/trace.go:171","msg":"trace[1326814140] transaction","detail":"{read_only:false; response_revision:9023; number_of_response:1; }","duration":"273.068506ms","start":"2026-10-15T11:34:25.997771Z","end":"2026-10-15T11:34:26.27084Z","steps":["trace[1326814140] 'process raft request' (duration: 272.624555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:34:26.271019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.144378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:34:26.271055Z","caller":"traceutil/trace.go:171","msg":"trace[2047228755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9023; }","duration":"198.23724ms","start":"2026-10-15T11:34:26.072805Z","end":"2026-10-15T11:34:26.271042Z","steps":["trace[2047228755] 'agreement among raft nodes before linearized reading' (duration: 198.162439ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:29.062884Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002496.wal"} {"level":"info","ts":"2026-10-15T11:34:33.948573Z","caller":"traceutil/trace.go:171","msg":"trace[171340993] transaction","detail":"{read_only:false; response_revision:9062; number_of_response:1; }","duration":"119.249462ms","start":"2026-10-15T11:34:33.829292Z","end":"2026-10-15T11:34:33.948541Z","steps":["trace[171340993] 'process raft request' (duration: 119.052667ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:34.521815Z","caller":"traceutil/trace.go:171","msg":"trace[1059040597] transaction","detail":"{read_only:false; response_revision:9066; number_of_response:1; }","duration":"186.983072ms","start":"2026-10-15T11:34:34.334795Z","end":"2026-10-15T11:34:34.521778Z","steps":["trace[1059040597] 'process raft request' (duration: 164.531988ms)","trace[1059040597] 'compare' (duration: 22.014233ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:34:34.521887Z","caller":"traceutil/trace.go:171","msg":"trace[390912165] linearizableReadLoop","detail":"{readStateIndex:9395; appliedIndex:9390; }","duration":"160.51576ms","start":"2026-10-15T11:34:34.361344Z","end":"2026-10-15T11:34:34.52186Z","steps":["trace[390912165] 'read index received' (duration: 10.012728ms)","trace[390912165] 'applied index is now lower than readState.Index' (duration: 150.501542ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:34:34.521945Z","caller":"traceutil/trace.go:171","msg":"trace[2099762989] transaction","detail":"{read_only:false; response_revision:9069; number_of_response:1; }","duration":"165.018311ms","start":"2026-10-15T11:34:34.35691Z","end":"2026-10-15T11:34:34.521928Z","steps":["trace[2099762989] 'process raft request' (duration: 164.902428ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:34.521964Z","caller":"traceutil/trace.go:171","msg":"trace[180933957] transaction","detail":"{read_only:false; response_revision:9067; number_of_response:1; }","duration":"181.572429ms","start":"2026-10-15T11:34:34.34038Z","end":"2026-10-15T11:34:34.521952Z","steps":["trace[180933957] 'process raft request' (duration: 181.283532ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:34.521908Z","caller":"traceutil/trace.go:171","msg":"trace[444976227] transaction","detail":"{read_only:false; response_revision:9068; number_of_response:1; }","duration":"166.857776ms","start":"2026-10-15T11:34:34.355025Z","end":"2026-10-15T11:34:34.521882Z","steps":["trace[444976227] 'process raft request' (duration: 166.742263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:34:34.522172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.807027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T11:34:34.522203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.770193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gsnpf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T11:34:34.522227Z","caller":"traceutil/trace.go:171","msg":"trace[237367944] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9069; }","duration":"160.93206ms","start":"2026-10-15T11:34:34.361278Z","end":"2026-10-15T11:34:34.52221Z","steps":["trace[237367944] 'agreement among raft nodes before linearized reading' (duration: 160.745615ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:34.522333Z","caller":"traceutil/trace.go:171","msg":"trace[2088454429] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gsnpf; range_end:; response_count:1; response_revision:9069; }","duration":"138.896836ms","start":"2026-10-15T11:34:34.383368Z","end":"2026-10-15T11:34:34.522265Z","steps":["trace[2088454429] 'agreement among raft nodes before linearized reading' (duration: 138.713212ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:36.996107Z","caller":"traceutil/trace.go:171","msg":"trace[949383874] transaction","detail":"{read_only:false; response_revision:9093; number_of_response:1; }","duration":"168.159908ms","start":"2026-10-15T11:34:36.827914Z","end":"2026-10-15T11:34:36.996074Z","steps":["trace[949383874] 'process raft request' (duration: 167.917702ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:37.138539Z","caller":"traceutil/trace.go:171","msg":"trace[800965672] linearizableReadLoop","detail":"{readStateIndex:9421; appliedIndex:9420; }","duration":"138.749932ms","start":"2026-10-15T11:34:36.999738Z","end":"2026-10-15T11:34:37.138488Z","steps":["trace[800965672] 'read index received' (duration: 88.980105ms)","trace[800965672] 'applied index is now lower than readState.Index' (duration: 49.768347ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:34:37.138728Z","caller":"traceutil/trace.go:171","msg":"trace[689234111] transaction","detail":"{read_only:false; response_revision:9094; number_of_response:1; }","duration":"139.627345ms","start":"2026-10-15T11:34:36.999081Z","end":"2026-10-15T11:34:37.138708Z","steps":["trace[689234111] 'process raft request' (duration: 89.680863ms)","trace[689234111] 'compare' (duration: 49.550472ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:34:37.139068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.146923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m979g\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-10-15T11:34:37.139128Z","caller":"traceutil/trace.go:171","msg":"trace[1092193833] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-m979g; range_end:; response_count:1; response_revision:9094; }","duration":"139.260766ms","start":"2026-10-15T11:34:36.99985Z","end":"2026-10-15T11:34:37.139111Z","steps":["trace[1092193833] 'agreement among raft nodes before linearized reading' (duration: 139.02851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:34:37.139516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.739728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m979g\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-10-15T11:34:37.139621Z","caller":"traceutil/trace.go:171","msg":"trace[558228156] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-m979g; range_end:; response_count:1; response_revision:9094; }","duration":"139.899052ms","start":"2026-10-15T11:34:36.999693Z","end":"2026-10-15T11:34:37.139592Z","steps":["trace[558228156] 'agreement among raft nodes before linearized reading' (duration: 139.487241ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:35:36.24905Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:35:36.264147Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T11:35:36.264274Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T11:36:03.976087Z","caller":"traceutil/trace.go:171","msg":"trace[1728958295] transaction","detail":"{read_only:false; response_revision:9927; number_of_response:1; }","duration":"260.822591ms","start":"2026-10-15T11:36:03.715238Z","end":"2026-10-15T11:36:03.976061Z","steps":["trace[1728958295] 'process raft request' (duration: 260.683068ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:37:11.86343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8003} {"level":"info","ts":"2026-10-15T11:37:11.962046Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8003,"took":"96.216454ms","hash":873267721,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":25088000,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-15T11:37:11.962127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":873267721,"revision":8003,"compact-revision":5555} {"level":"info","ts":"2026-10-15T11:41:22.997457Z","caller":"traceutil/trace.go:171","msg":"trace[872672127] transaction","detail":"{read_only:false; response_revision:12254; number_of_response:1; }","duration":"171.338976ms","start":"2026-10-15T11:41:22.826078Z","end":"2026-10-15T11:41:22.997417Z","steps":["trace[872672127] 'process raft request' (duration: 150.837141ms)","trace[872672127] 'compare' (duration: 20.27636ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:41:43.082509Z","caller":"traceutil/trace.go:171","msg":"trace[1560958002] transaction","detail":"{read_only:false; response_revision:12365; number_of_response:1; }","duration":"123.376814ms","start":"2026-10-15T11:41:42.959107Z","end":"2026-10-15T11:41:43.082484Z","steps":["trace[1560958002] 'process raft request' (duration: 123.228691ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:42:11.87375Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10399} {"level":"info","ts":"2026-10-15T11:42:11.965063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10399,"took":"88.900866ms","hash":1345561195,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":25812992,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T11:42:11.965127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1345561195,"revision":10399,"compact-revision":8003} {"level":"info","ts":"2026-10-15T11:47:11.881707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12566} {"level":"info","ts":"2026-10-15T11:47:11.967739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12566,"took":"83.072091ms","hash":3638298831,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":27803648,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T11:47:11.967842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3638298831,"revision":12566,"compact-revision":10399} {"level":"info","ts":"2026-10-15T11:47:42.664655Z","caller":"traceutil/trace.go:171","msg":"trace[1195129778] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"147.567666ms","start":"2026-10-15T11:47:42.51702Z","end":"2026-10-15T11:47:42.664588Z","steps":["trace[1195129778] 'process raft request' (duration: 147.3272ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:04.415988Z","caller":"traceutil/trace.go:171","msg":"trace[645111946] linearizableReadLoop","detail":"{readStateIndex:15445; appliedIndex:15444; }","duration":"149.199329ms","start":"2026-10-15T11:48:04.266762Z","end":"2026-10-15T11:48:04.415961Z","steps":["trace[645111946] 'read index received' (duration: 149.051846ms)","trace[645111946] 'applied index is now lower than readState.Index' (duration: 146.363µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:48:04.416177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.349569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:48:04.41613Z","caller":"traceutil/trace.go:171","msg":"trace[1336311242] transaction","detail":"{read_only:false; response_revision:14921; number_of_response:1; }","duration":"331.78669ms","start":"2026-10-15T11:48:04.084297Z","end":"2026-10-15T11:48:04.416083Z","steps":["trace[1336311242] 'process raft request' (duration: 331.513843ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:04.41621Z","caller":"traceutil/trace.go:171","msg":"trace[759149151] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:14921; }","duration":"124.440491ms","start":"2026-10-15T11:48:04.29176Z","end":"2026-10-15T11:48:04.4162Z","steps":["trace[759149151] 'agreement among raft nodes before linearized reading' (duration: 124.360729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:04.416232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.443856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:48:04.416327Z","caller":"traceutil/trace.go:171","msg":"trace[461472463] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:14921; }","duration":"149.61952ms","start":"2026-10-15T11:48:04.266687Z","end":"2026-10-15T11:48:04.416307Z","steps":["trace[461472463] 'agreement among raft nodes before linearized reading' (duration: 149.400364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:04.41636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:04.084265Z","time spent":"331.979905ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:48:14.050074Z","caller":"traceutil/trace.go:171","msg":"trace[1485445208] transaction","detail":"{read_only:false; response_revision:14968; number_of_response:1; }","duration":"110.723843ms","start":"2026-10-15T11:48:13.939322Z","end":"2026-10-15T11:48:14.050045Z","steps":["trace[1485445208] 'process raft request' (duration: 110.519609ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:14.055555Z","caller":"traceutil/trace.go:171","msg":"trace[988308720] transaction","detail":"{read_only:false; response_revision:14969; number_of_response:1; }","duration":"113.38785ms","start":"2026-10-15T11:48:13.94213Z","end":"2026-10-15T11:48:14.055518Z","steps":["trace[988308720] 'process raft request' (duration: 113.063462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:23.616474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.804728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gsnpf\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-10-15T11:48:23.616482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.009977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-n4vqb\" ","response":"range_response_count:1 size:12364"} {"level":"warn","ts":"2026-10-15T11:48:23.616559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.699889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-15T11:48:23.616596Z","caller":"traceutil/trace.go:171","msg":"trace[2134434015] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gsnpf; range_end:; response_count:1; response_revision:15031; }","duration":"126.986682ms","start":"2026-10-15T11:48:23.489584Z","end":"2026-10-15T11:48:23.61657Z","steps":["trace[2134434015] 'range keys from in-memory index tree' (duration: 126.592143ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:23.616631Z","caller":"traceutil/trace.go:171","msg":"trace[1342561999] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-n4vqb; range_end:; response_count:1; response_revision:15031; }","duration":"278.225672ms","start":"2026-10-15T11:48:23.338376Z","end":"2026-10-15T11:48:23.616602Z","steps":["trace[1342561999] 'range keys from in-memory index tree' (duration: 277.76485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:23.616528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.208143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T11:48:23.616687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.854398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T11:48:23.616715Z","caller":"traceutil/trace.go:171","msg":"trace[1459392904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15031; }","duration":"178.471049ms","start":"2026-10-15T11:48:23.438227Z","end":"2026-10-15T11:48:23.616698Z","steps":["trace[1459392904] 'range keys from in-memory index tree' (duration: 177.931175ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:23.61674Z","caller":"traceutil/trace.go:171","msg":"trace[1009157258] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15031; }","duration":"167.91524ms","start":"2026-10-15T11:48:23.448801Z","end":"2026-10-15T11:48:23.616717Z","steps":["trace[1009157258] 'range keys from in-memory index tree' (duration: 167.728975ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:23.616633Z","caller":"traceutil/trace.go:171","msg":"trace[673557050] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15031; }","duration":"279.816001ms","start":"2026-10-15T11:48:23.336798Z","end":"2026-10-15T11:48:23.616614Z","steps":["trace[673557050] 'range keys from in-memory index tree' (duration: 279.517425ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:23.739407Z","caller":"traceutil/trace.go:171","msg":"trace[668496816] linearizableReadLoop","detail":"{readStateIndex:15562; appliedIndex:15561; }","duration":"117.52029ms","start":"2026-10-15T11:48:23.621869Z","end":"2026-10-15T11:48:23.73939Z","steps":["trace[668496816] 'read index received' (duration: 117.393368ms)","trace[668496816] 'applied index is now lower than readState.Index' (duration: 126.452µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:48:23.739472Z","caller":"traceutil/trace.go:171","msg":"trace[936682840] transaction","detail":"{read_only:false; response_revision:15032; number_of_response:1; }","duration":"117.749496ms","start":"2026-10-15T11:48:23.621698Z","end":"2026-10-15T11:48:23.739447Z","steps":["trace[936682840] 'process raft request' (duration: 117.552742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:23.739584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.714895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-15T11:48:23.739602Z","caller":"traceutil/trace.go:171","msg":"trace[384139886] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15032; }","duration":"117.807017ms","start":"2026-10-15T11:48:23.621789Z","end":"2026-10-15T11:48:23.739596Z","steps":["trace[384139886] 'agreement among raft nodes before linearized reading' (duration: 117.687154ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:23.83197Z","caller":"traceutil/trace.go:171","msg":"trace[1380875919] transaction","detail":"{read_only:false; response_revision:15033; number_of_response:1; }","duration":"208.756174ms","start":"2026-10-15T11:48:23.623191Z","end":"2026-10-15T11:48:23.831947Z","steps":["trace[1380875919] 'process raft request' (duration: 208.552019ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:23.832103Z","caller":"traceutil/trace.go:171","msg":"trace[1704197533] transaction","detail":"{read_only:false; response_revision:15034; number_of_response:1; }","duration":"199.183739ms","start":"2026-10-15T11:48:23.632904Z","end":"2026-10-15T11:48:23.832088Z","steps":["trace[1704197533] 'process raft request' (duration: 198.978933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:23.83233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.332002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:48:23.832458Z","caller":"traceutil/trace.go:171","msg":"trace[868015514] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:15034; }","duration":"149.560567ms","start":"2026-10-15T11:48:23.682875Z","end":"2026-10-15T11:48:23.832436Z","steps":["trace[868015514] 'agreement among raft nodes before linearized reading' (duration: 149.220929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:25.079824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.298175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:48:25.079905Z","caller":"traceutil/trace.go:171","msg":"trace[1511518287] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:15043; }","duration":"117.425378ms","start":"2026-10-15T11:48:24.96246Z","end":"2026-10-15T11:48:25.079885Z","steps":["trace[1511518287] 'count revisions from in-memory index tree' (duration: 117.178041ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:47.730021Z","caller":"traceutil/trace.go:171","msg":"trace[552197315] transaction","detail":"{read_only:false; response_revision:15352; number_of_response:1; }","duration":"474.109001ms","start":"2026-10-15T11:48:47.255882Z","end":"2026-10-15T11:48:47.729991Z","steps":["trace[552197315] 'process raft request' (duration: 473.926666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:47.730296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:47.255849Z","time spent":"474.349857ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:48:47.730347Z","caller":"traceutil/trace.go:171","msg":"trace[1887531786] linearizableReadLoop","detail":"{readStateIndex:15897; appliedIndex:15897; }","duration":"426.351626ms","start":"2026-10-15T11:48:47.303979Z","end":"2026-10-15T11:48:47.73033Z","steps":["trace[1887531786] 'read index received' (duration: 426.343956ms)","trace[1887531786] 'applied index is now lower than readState.Index' (duration: 6.61µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:48:47.730556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.574152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:48:47.730598Z","caller":"traceutil/trace.go:171","msg":"trace[1710512317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15352; }","duration":"426.620453ms","start":"2026-10-15T11:48:47.303967Z","end":"2026-10-15T11:48:47.730588Z","steps":["trace[1710512317] 'agreement among raft nodes before linearized reading' (duration: 426.454879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:47.730627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:47.303861Z","time spent":"426.758316ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T11:48:47.779103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.474119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:48:47.779218Z","caller":"traceutil/trace.go:171","msg":"trace[1038518497] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:15353; }","duration":"400.658293ms","start":"2026-10-15T11:48:47.378535Z","end":"2026-10-15T11:48:47.779193Z","steps":["trace[1038518497] 'agreement among raft nodes before linearized reading' (duration: 400.444129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:47.779272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:47.378511Z","time spent":"400.747246ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-15T11:48:47.779717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.141009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:48:47.779782Z","caller":"traceutil/trace.go:171","msg":"trace[1912604677] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:15353; }","duration":"160.250431ms","start":"2026-10-15T11:48:47.619514Z","end":"2026-10-15T11:48:47.779765Z","steps":["trace[1912604677] 'agreement among raft nodes before linearized reading' (duration: 160.088537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:47.779959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.300312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5q2hb\" ","response":"range_response_count:1 size:20659"} {"level":"info","ts":"2026-10-15T11:48:47.780052Z","caller":"traceutil/trace.go:171","msg":"trace[663952039] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5q2hb; range_end:; response_count:1; response_revision:15353; }","duration":"384.420895ms","start":"2026-10-15T11:48:47.395609Z","end":"2026-10-15T11:48:47.78003Z","steps":["trace[663952039] 'agreement among raft nodes before linearized reading' (duration: 384.165099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:47.780089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:47.395598Z","time spent":"384.483846ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20682,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5q2hb\" "} {"level":"info","ts":"2026-10-15T11:48:47.874718Z","caller":"traceutil/trace.go:171","msg":"trace[622422226] transaction","detail":"{read_only:false; response_revision:15354; number_of_response:1; }","duration":"138.034924ms","start":"2026-10-15T11:48:47.736655Z","end":"2026-10-15T11:48:47.87469Z","steps":["trace[622422226] 'process raft request' (duration: 137.830199ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:49:40.162664Z","caller":"traceutil/trace.go:171","msg":"trace[113232274] transaction","detail":"{read_only:false; response_revision:15984; number_of_response:1; }","duration":"164.460294ms","start":"2026-10-15T11:49:39.998177Z","end":"2026-10-15T11:49:40.162637Z","steps":["trace[113232274] 'process raft request' (duration: 164.196458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:50:14.529238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.739882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:50:14.529298Z","caller":"traceutil/trace.go:171","msg":"trace[2084040197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16163; }","duration":"164.834924ms","start":"2026-10-15T11:50:14.364451Z","end":"2026-10-15T11:50:14.529286Z","steps":["trace[2084040197] 'range keys from in-memory index tree' (duration: 164.626929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:50:14.529444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.438394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:50:14.529457Z","caller":"traceutil/trace.go:171","msg":"trace[271478065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16163; }","duration":"164.467945ms","start":"2026-10-15T11:50:14.364985Z","end":"2026-10-15T11:50:14.529453Z","steps":["trace[271478065] 'range keys from in-memory index tree' (duration: 164.382323ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:14.691071Z","caller":"traceutil/trace.go:171","msg":"trace[1087167083] transaction","detail":"{read_only:false; response_revision:16165; number_of_response:1; }","duration":"156.07961ms","start":"2026-10-15T11:50:14.534961Z","end":"2026-10-15T11:50:14.69104Z","steps":["trace[1087167083] 'process raft request' (duration: 154.665444ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:14.691824Z","caller":"traceutil/trace.go:171","msg":"trace[497193461] transaction","detail":"{read_only:false; response_revision:16166; number_of_response:1; }","duration":"156.794887ms","start":"2026-10-15T11:50:14.535011Z","end":"2026-10-15T11:50:14.691806Z","steps":["trace[497193461] 'process raft request' (duration: 155.982927ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:14.692167Z","caller":"traceutil/trace.go:171","msg":"trace[1193212364] transaction","detail":"{read_only:false; response_revision:16167; number_of_response:1; }","duration":"155.825082ms","start":"2026-10-15T11:50:14.536326Z","end":"2026-10-15T11:50:14.692151Z","steps":["trace[1193212364] 'process raft request' (duration: 155.271388ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:14.692445Z","caller":"traceutil/trace.go:171","msg":"trace[2030328639] transaction","detail":"{read_only:false; response_revision:16168; number_of_response:1; }","duration":"153.043604ms","start":"2026-10-15T11:50:14.539369Z","end":"2026-10-15T11:50:14.692412Z","steps":["trace[2030328639] 'process raft request' (duration: 152.719746ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:14.69257Z","caller":"traceutil/trace.go:171","msg":"trace[687319052] transaction","detail":"{read_only:false; response_revision:16169; number_of_response:1; }","duration":"128.847919ms","start":"2026-10-15T11:50:14.563709Z","end":"2026-10-15T11:50:14.692557Z","steps":["trace[687319052] 'process raft request' (duration: 128.629774ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:20.924599Z","caller":"traceutil/trace.go:171","msg":"trace[1809057249] linearizableReadLoop","detail":"{readStateIndex:16770; appliedIndex:16769; }","duration":"213.739477ms","start":"2026-10-15T11:50:20.710835Z","end":"2026-10-15T11:50:20.924574Z","steps":["trace[1809057249] 'read index received' (duration: 213.564313ms)","trace[1809057249] 'applied index is now lower than readState.Index' (duration: 173.784µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:50:20.924776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.936202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:50:20.924821Z","caller":"traceutil/trace.go:171","msg":"trace[1545622587] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16197; }","duration":"214.060155ms","start":"2026-10-15T11:50:20.71075Z","end":"2026-10-15T11:50:20.92481Z","steps":["trace[1545622587] 'agreement among raft nodes before linearized reading' (duration: 213.952682ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:20.924687Z","caller":"traceutil/trace.go:171","msg":"trace[248541448] transaction","detail":"{read_only:false; response_revision:16197; number_of_response:1; }","duration":"284.256732ms","start":"2026-10-15T11:50:20.640379Z","end":"2026-10-15T11:50:20.924636Z","steps":["trace[248541448] 'process raft request' (duration: 284.021846ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:20.962676Z","caller":"traceutil/trace.go:171","msg":"trace[1282682169] transaction","detail":"{read_only:false; response_revision:16198; number_of_response:1; }","duration":"245.106627ms","start":"2026-10-15T11:50:20.717538Z","end":"2026-10-15T11:50:20.962645Z","steps":["trace[1282682169] 'process raft request' (duration: 244.903332ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:20.962975Z","caller":"traceutil/trace.go:171","msg":"trace[1909914511] transaction","detail":"{read_only:false; response_revision:16200; number_of_response:1; }","duration":"244.176616ms","start":"2026-10-15T11:50:20.71878Z","end":"2026-10-15T11:50:20.962956Z","steps":["trace[1909914511] 'process raft request' (duration: 244.044943ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:20.963138Z","caller":"traceutil/trace.go:171","msg":"trace[2024679799] transaction","detail":"{read_only:false; response_revision:16199; number_of_response:1; }","duration":"244.947874ms","start":"2026-10-15T11:50:20.718176Z","end":"2026-10-15T11:50:20.963124Z","steps":["trace[2024679799] 'process raft request' (duration: 244.409011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:50:20.965765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.971431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T11:50:20.96579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.459116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6pp8h\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-15T11:50:20.965833Z","caller":"traceutil/trace.go:171","msg":"trace[1430216935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16200; }","duration":"244.114614ms","start":"2026-10-15T11:50:20.721702Z","end":"2026-10-15T11:50:20.965816Z","steps":["trace[1430216935] 'agreement among raft nodes before linearized reading' (duration: 243.892329ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:20.965845Z","caller":"traceutil/trace.go:171","msg":"trace[1397658753] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6pp8h; range_end:; response_count:1; response_revision:16200; }","duration":"184.57867ms","start":"2026-10-15T11:50:20.781252Z","end":"2026-10-15T11:50:20.96583Z","steps":["trace[1397658753] 'agreement among raft nodes before linearized reading' (duration: 184.309643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:50:20.965861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.246412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T11:50:20.965965Z","caller":"traceutil/trace.go:171","msg":"trace[1941763709] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16200; }","duration":"254.386096ms","start":"2026-10-15T11:50:20.71155Z","end":"2026-10-15T11:50:20.965936Z","steps":["trace[1941763709] 'agreement among raft nodes before linearized reading' (duration: 254.065478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:50:20.965987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.787259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T11:50:20.966143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.041033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T11:50:20.966222Z","caller":"traceutil/trace.go:171","msg":"trace[72175104] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16200; }","duration":"234.107518ms","start":"2026-10-15T11:50:20.732046Z","end":"2026-10-15T11:50:20.966154Z","steps":["trace[72175104] 'agreement among raft nodes before linearized reading' (duration: 233.571944ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:20.966222Z","caller":"traceutil/trace.go:171","msg":"trace[507217845] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16200; }","duration":"255.136675ms","start":"2026-10-15T11:50:20.711056Z","end":"2026-10-15T11:50:20.966193Z","steps":["trace[507217845] 'agreement among raft nodes before linearized reading' (duration: 254.876318ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:52:11.896479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14594} {"level":"info","ts":"2026-10-15T11:52:12.002914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14594,"took":"97.925409ms","hash":3847626531,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":31670272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T11:52:12.00301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3847626531,"revision":14594,"compact-revision":12566} {"level":"info","ts":"2026-10-15T11:53:17.222097Z","caller":"traceutil/trace.go:171","msg":"trace[1296758481] transaction","detail":"{read_only:false; response_revision:17623; number_of_response:1; }","duration":"119.33991ms","start":"2026-10-15T11:53:17.102728Z","end":"2026-10-15T11:53:17.222068Z","steps":["trace[1296758481] 'process raft request' (duration: 119.121596ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:53:18.124721Z","caller":"traceutil/trace.go:171","msg":"trace[1832323379] transaction","detail":"{read_only:false; response_revision:17637; number_of_response:1; }","duration":"102.964038ms","start":"2026-10-15T11:53:18.02172Z","end":"2026-10-15T11:53:18.124684Z","steps":["trace[1832323379] 'process raft request' (duration: 102.102597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:18.124818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.068488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:53:18.124913Z","caller":"traceutil/trace.go:171","msg":"trace[1712314900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17637; }","duration":"100.251522ms","start":"2026-10-15T11:53:18.024638Z","end":"2026-10-15T11:53:18.12489Z","steps":["trace[1712314900] 'agreement among raft nodes before linearized reading' (duration: 99.964675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:18.124985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.195911ms","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-10-15T11:53:18.125088Z","caller":"traceutil/trace.go:171","msg":"trace[722729162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17637; }","duration":"100.372436ms","start":"2026-10-15T11:53:18.024687Z","end":"2026-10-15T11:53:18.125059Z","steps":["trace[722729162] 'agreement among raft nodes before linearized reading' (duration: 99.997236ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:56:29.180035Z","caller":"traceutil/trace.go:171","msg":"trace[782497837] transaction","detail":"{read_only:false; response_revision:18882; number_of_response:1; }","duration":"163.096916ms","start":"2026-10-15T11:56:29.016904Z","end":"2026-10-15T11:56:29.180001Z","steps":["trace[782497837] 'process raft request' (duration: 162.929512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:29.651646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.464528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wzsvl\" ","response":"range_response_count:1 size:22133"} {"level":"warn","ts":"2026-10-15T11:56:29.651622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.113821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5q2hb\" ","response":"range_response_count:1 size:20762"} {"level":"info","ts":"2026-10-15T11:56:29.651751Z","caller":"traceutil/trace.go:171","msg":"trace[2137589502] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wzsvl; range_end:; response_count:1; response_revision:18884; }","duration":"295.639662ms","start":"2026-10-15T11:56:29.35609Z","end":"2026-10-15T11:56:29.651729Z","steps":["trace[2137589502] 'range keys from in-memory index tree' (duration: 295.235262ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:56:29.651781Z","caller":"traceutil/trace.go:171","msg":"trace[810672669] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5q2hb; range_end:; response_count:1; response_revision:18884; }","duration":"175.362997ms","start":"2026-10-15T11:56:29.476383Z","end":"2026-10-15T11:56:29.651746Z","steps":["trace[810672669] 'range keys from in-memory index tree' (duration: 174.852385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:29.651777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.673622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-15T11:56:29.651868Z","caller":"traceutil/trace.go:171","msg":"trace[696962226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18884; }","duration":"159.773655ms","start":"2026-10-15T11:56:29.492064Z","end":"2026-10-15T11:56:29.651837Z","steps":["trace[696962226] 'range keys from in-memory index tree' (duration: 159.496077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:30.158651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656056078747633844,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T11:56:30.203693Z","caller":"traceutil/trace.go:171","msg":"trace[1674869158] transaction","detail":"{read_only:false; response_revision:18885; number_of_response:1; }","duration":"547.973839ms","start":"2026-10-15T11:56:29.655689Z","end":"2026-10-15T11:56:30.203663Z","steps":["trace[1674869158] 'process raft request' (duration: 547.733774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:30.203878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:56:29.655666Z","time spent":"548.100301ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:56:30.358422Z","caller":"traceutil/trace.go:171","msg":"trace[1659716064] transaction","detail":"{read_only:false; response_revision:18886; number_of_response:1; }","duration":"702.152055ms","start":"2026-10-15T11:56:29.656244Z","end":"2026-10-15T11:56:30.358396Z","steps":["trace[1659716064] 'process raft request' (duration: 701.919819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:30.358574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:56:29.656213Z","time spent":"702.285008ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:56:30.358563Z","caller":"traceutil/trace.go:171","msg":"trace[15669332] linearizableReadLoop","detail":"{readStateIndex:19550; appliedIndex:19547; }","duration":"700.980616ms","start":"2026-10-15T11:56:29.657545Z","end":"2026-10-15T11:56:30.358525Z","steps":["trace[15669332] 'read index received' (duration: 545.873668ms)","trace[15669332] 'applied index is now lower than readState.Index' (duration: 155.104348ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:56:30.359036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.22109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T11:56:30.35924Z","caller":"traceutil/trace.go:171","msg":"trace[446148762] transaction","detail":"{read_only:false; response_revision:18887; number_of_response:1; }","duration":"684.328348ms","start":"2026-10-15T11:56:29.674886Z","end":"2026-10-15T11:56:30.359214Z","steps":["trace[446148762] 'process raft request' (duration: 683.437615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:30.359304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.962229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6pp8h\" ","response":"range_response_count:1 size:9800"} {"level":"warn","ts":"2026-10-15T11:56:30.359389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:56:29.674828Z","time spent":"684.464702ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20877,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:56:30.359401Z","caller":"traceutil/trace.go:171","msg":"trace[1984482720] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6pp8h; range_end:; response_count:1; response_revision:18887; }","duration":"217.150224ms","start":"2026-10-15T11:56:30.142226Z","end":"2026-10-15T11:56:30.359376Z","steps":["trace[1984482720] 'agreement among raft nodes before linearized reading' (duration: 216.796715ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:56:30.359256Z","caller":"traceutil/trace.go:171","msg":"trace[300268279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18887; }","duration":"286.496097ms","start":"2026-10-15T11:56:30.072739Z","end":"2026-10-15T11:56:30.359235Z","steps":["trace[300268279] 'agreement among raft nodes before linearized reading' (duration: 286.22425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:30.359895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.3583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946810"} {"level":"info","ts":"2026-10-15T11:56:30.36007Z","caller":"traceutil/trace.go:171","msg":"trace[1499037356] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18887; }","duration":"702.534975ms","start":"2026-10-15T11:56:29.657522Z","end":"2026-10-15T11:56:30.360057Z","steps":["trace[1499037356] 'agreement among raft nodes before linearized reading' (duration: 701.487048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:56:30.36011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:56:29.657494Z","time spent":"702.601387ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":946833,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-15T11:57:09.626567Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":20004,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:57:09.635571Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-10-15T11:57:09.635883Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-10-15T11:57:11.917632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16973} {"level":"info","ts":"2026-10-15T11:57:12.032444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16973,"took":"109.851716ms","hash":329652381,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":33013760,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T11:57:12.032525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":329652381,"revision":16973,"compact-revision":14594} {"level":"info","ts":"2026-10-15T12:02:06.114394Z","caller":"traceutil/trace.go:171","msg":"trace[648480649] transaction","detail":"{read_only:false; response_revision:21610; number_of_response:1; }","duration":"168.169633ms","start":"2026-10-15T12:02:05.946185Z","end":"2026-10-15T12:02:06.114355Z","steps":["trace[648480649] 'process raft request' (duration: 167.962098ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:06.335123Z","caller":"traceutil/trace.go:171","msg":"trace[923480460] linearizableReadLoop","detail":"{readStateIndex:22361; appliedIndex:22359; }","duration":"261.137166ms","start":"2026-10-15T12:02:06.073954Z","end":"2026-10-15T12:02:06.335091Z","steps":["trace[923480460] 'read index received' (duration: 40.230424ms)","trace[923480460] 'applied index is now lower than readState.Index' (duration: 220.905432ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:02:06.335249Z","caller":"traceutil/trace.go:171","msg":"trace[769439745] transaction","detail":"{read_only:false; response_revision:21611; number_of_response:1; }","duration":"296.074782ms","start":"2026-10-15T12:02:06.039108Z","end":"2026-10-15T12:02:06.335183Z","steps":["trace[769439745] 'process raft request' (duration: 265.525104ms)","trace[769439745] 'compare' (duration: 30.300821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:02:06.335418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.433763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:02:06.335535Z","caller":"traceutil/trace.go:171","msg":"trace[1010963657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21611; }","duration":"261.609578ms","start":"2026-10-15T12:02:06.073896Z","end":"2026-10-15T12:02:06.335506Z","steps":["trace[1010963657] 'agreement among raft nodes before linearized reading' (duration: 261.342921ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:06.338382Z","caller":"traceutil/trace.go:171","msg":"trace[314495086] transaction","detail":"{read_only:false; response_revision:21612; number_of_response:1; }","duration":"169.085735ms","start":"2026-10-15T12:02:06.169271Z","end":"2026-10-15T12:02:06.338357Z","steps":["trace[314495086] 'process raft request' (duration: 168.674855ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:06.338752Z","caller":"traceutil/trace.go:171","msg":"trace[568933437] transaction","detail":"{read_only:false; response_revision:21613; number_of_response:1; }","duration":"106.146526ms","start":"2026-10-15T12:02:06.232579Z","end":"2026-10-15T12:02:06.338726Z","steps":["trace[568933437] 'process raft request' (duration: 105.458409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:06.339004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.017743ms","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-10-15T12:02:06.339113Z","caller":"traceutil/trace.go:171","msg":"trace[1489406950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21613; }","duration":"207.202197ms","start":"2026-10-15T12:02:06.131886Z","end":"2026-10-15T12:02:06.339089Z","steps":["trace[1489406950] 'agreement among raft nodes before linearized reading' (duration: 206.832698ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:11.925131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19432} {"level":"info","ts":"2026-10-15T12:02:12.048594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19432,"took":"119.39186ms","hash":2483181392,"current-db-size-bytes":55062528,"current-db-size":"55 MB","current-db-size-in-use-bytes":29958144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T12:02:12.048673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2483181392,"revision":19432,"compact-revision":16973} {"level":"warn","ts":"2026-10-15T12:02:16.647214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.925291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:02:16.64799Z","caller":"traceutil/trace.go:171","msg":"trace[1336737540] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:21666; }","duration":"106.741151ms","start":"2026-10-15T12:02:16.541222Z","end":"2026-10-15T12:02:16.647963Z","steps":["trace[1336737540] 'count revisions from in-memory index tree' (duration: 105.861019ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:22.311902Z","caller":"traceutil/trace.go:171","msg":"trace[599802282] transaction","detail":"{read_only:false; response_revision:21695; number_of_response:1; }","duration":"132.548911ms","start":"2026-10-15T12:02:22.179325Z","end":"2026-10-15T12:02:22.311874Z","steps":["trace[599802282] 'process raft request' (duration: 132.333376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:22.948286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.900808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:02:22.94839Z","caller":"traceutil/trace.go:171","msg":"trace[267070960] transaction","detail":"{read_only:false; response_revision:21696; number_of_response:1; }","duration":"557.804132ms","start":"2026-10-15T12:02:22.390565Z","end":"2026-10-15T12:02:22.948369Z","steps":["trace[267070960] 'process raft request' (duration: 153.708579ms)","trace[267070960] 'compare' (duration: 403.606121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:02:22.948449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:22.390539Z","time spent":"557.881785ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:02:22.968403Z","caller":"traceutil/trace.go:171","msg":"trace[51965588] linearizableReadLoop","detail":"{readStateIndex:22452; appliedIndex:22450; }","duration":"558.649304ms","start":"2026-10-15T12:02:22.409729Z","end":"2026-10-15T12:02:22.968378Z","steps":["trace[51965588] 'read index received' (duration: 134.665134ms)","trace[51965588] 'applied index is now lower than readState.Index' (duration: 423.98343ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:02:22.968495Z","caller":"traceutil/trace.go:171","msg":"trace[158475483] transaction","detail":"{read_only:false; response_revision:21697; number_of_response:1; }","duration":"562.919998ms","start":"2026-10-15T12:02:22.405533Z","end":"2026-10-15T12:02:22.968453Z","steps":["trace[158475483] 'process raft request' (duration: 562.710773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:22.96858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.829598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-15T12:02:22.968608Z","caller":"traceutil/trace.go:171","msg":"trace[1360405946] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:21697; }","duration":"558.874789ms","start":"2026-10-15T12:02:22.409721Z","end":"2026-10-15T12:02:22.968596Z","steps":["trace[1360405946] 'agreement among raft nodes before linearized reading' (duration: 558.755506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:22.968629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:22.409686Z","time spent":"558.937101ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-10-15T12:02:22.968684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:22.405513Z","time spent":"563.064241ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:02:22.968823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.381432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-15T12:02:22.968822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.598488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T12:02:22.968908Z","caller":"traceutil/trace.go:171","msg":"trace[361660606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21697; }","duration":"290.509336ms","start":"2026-10-15T12:02:22.678382Z","end":"2026-10-15T12:02:22.968892Z","steps":["trace[361660606] 'agreement among raft nodes before linearized reading' (duration: 290.26317ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:22.968932Z","caller":"traceutil/trace.go:171","msg":"trace[1684536863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21697; }","duration":"530.759822ms","start":"2026-10-15T12:02:22.438146Z","end":"2026-10-15T12:02:22.968906Z","steps":["trace[1684536863] 'agreement among raft nodes before linearized reading' (duration: 530.457255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:22.968985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:22.438127Z","time spent":"530.846584ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T12:02:22.968873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T12:02:22.969261Z","caller":"traceutil/trace.go:171","msg":"trace[1241938756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21697; }","duration":"251.971652ms","start":"2026-10-15T12:02:22.717271Z","end":"2026-10-15T12:02:22.969243Z","steps":["trace[1241938756] 'agreement among raft nodes before linearized reading' (duration: 251.351007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:23.515944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.308292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:02:23.516216Z","caller":"traceutil/trace.go:171","msg":"trace[724250865] transaction","detail":"{read_only:false; response_revision:21699; number_of_response:1; }","duration":"542.164239ms","start":"2026-10-15T12:02:22.974023Z","end":"2026-10-15T12:02:23.516187Z","steps":["trace[724250865] 'process raft request' (duration: 192.499288ms)","trace[724250865] 'compare' (duration: 349.19144ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:02:23.516314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:22.974008Z","time spent":"542.261301ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:02:23.516614Z","caller":"traceutil/trace.go:171","msg":"trace[1469991075] transaction","detail":"{read_only:false; response_revision:21700; number_of_response:1; }","duration":"542.499928ms","start":"2026-10-15T12:02:22.97408Z","end":"2026-10-15T12:02:23.51658Z","steps":["trace[1469991075] 'process raft request' (duration: 541.967335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:23.516785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:22.974061Z","time spent":"542.634631ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:02:23.893825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.999818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:02:23.893991Z","caller":"traceutil/trace.go:171","msg":"trace[514676884] transaction","detail":"{read_only:false; response_revision:21701; number_of_response:1; }","duration":"372.725206ms","start":"2026-10-15T12:02:23.521238Z","end":"2026-10-15T12:02:23.893963Z","steps":["trace[514676884] 'process raft request' (duration: 175.488802ms)","trace[514676884] 'compare' (duration: 196.864825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:02:23.894086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:23.521216Z","time spent":"372.825899ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:02:23.968817Z","caller":"traceutil/trace.go:171","msg":"trace[162112323] linearizableReadLoop","detail":"{readStateIndex:22458; appliedIndex:22456; }","duration":"109.418987ms","start":"2026-10-15T12:02:23.859373Z","end":"2026-10-15T12:02:23.968792Z","steps":["trace[162112323] 'read index received' (duration: 109.176111ms)","trace[162112323] 'applied index is now lower than readState.Index' (duration: 241.946µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:02:23.969011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.61672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:02:23.968995Z","caller":"traceutil/trace.go:171","msg":"trace[271803215] transaction","detail":"{read_only:false; response_revision:21702; number_of_response:1; }","duration":"446.987541ms","start":"2026-10-15T12:02:23.52196Z","end":"2026-10-15T12:02:23.968948Z","steps":["trace[271803215] 'process raft request' (duration: 446.659484ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:23.969082Z","caller":"traceutil/trace.go:171","msg":"trace[1605578042] transaction","detail":"{read_only:false; response_revision:21703; number_of_response:1; }","duration":"446.313816ms","start":"2026-10-15T12:02:23.522759Z","end":"2026-10-15T12:02:23.969073Z","steps":["trace[1605578042] 'process raft request' (duration: 445.996239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:23.969174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:23.52273Z","time spent":"446.380667ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:02:23.969194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:02:23.521895Z","time spent":"447.193627ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:02:23.969048Z","caller":"traceutil/trace.go:171","msg":"trace[728862236] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:21703; }","duration":"109.729793ms","start":"2026-10-15T12:02:23.859305Z","end":"2026-10-15T12:02:23.969035Z","steps":["trace[728862236] 'agreement among raft nodes before linearized reading' (duration: 109.60554ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:30.333091Z","caller":"traceutil/trace.go:171","msg":"trace[559069409] transaction","detail":"{read_only:false; response_revision:24255; number_of_response:1; }","duration":"213.602ms","start":"2026-10-15T12:06:30.119453Z","end":"2026-10-15T12:06:30.333055Z","steps":["trace[559069409] 'process raft request' (duration: 213.385945ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:30.371381Z","caller":"traceutil/trace.go:171","msg":"trace[1343441325] transaction","detail":"{read_only:false; response_revision:24256; number_of_response:1; }","duration":"249.321249ms","start":"2026-10-15T12:06:30.122026Z","end":"2026-10-15T12:06:30.371347Z","steps":["trace[1343441325] 'process raft request' (duration: 248.681563ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:30.371724Z","caller":"traceutil/trace.go:171","msg":"trace[1355168989] transaction","detail":"{read_only:false; response_revision:24257; number_of_response:1; }","duration":"248.184432ms","start":"2026-10-15T12:06:30.123508Z","end":"2026-10-15T12:06:30.371693Z","steps":["trace[1355168989] 'process raft request' (duration: 247.770372ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:30.37177Z","caller":"traceutil/trace.go:171","msg":"trace[2116328102] transaction","detail":"{read_only:false; response_revision:24258; number_of_response:1; }","duration":"205.762431ms","start":"2026-10-15T12:06:30.165973Z","end":"2026-10-15T12:06:30.371736Z","steps":["trace[2116328102] 'process raft request' (duration: 205.591316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:30.371851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.095899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:06:30.371887Z","caller":"traceutil/trace.go:171","msg":"trace[782478712] linearizableReadLoop","detail":"{readStateIndex:25087; appliedIndex:25083; }","duration":"136.978376ms","start":"2026-10-15T12:06:30.234715Z","end":"2026-10-15T12:06:30.371693Z","steps":["trace[782478712] 'read index received' (duration: 98.227863ms)","trace[782478712] 'applied index is now lower than readState.Index' (duration: 38.748763ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:06:30.371935Z","caller":"traceutil/trace.go:171","msg":"trace[1392534230] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24258; }","duration":"137.262713ms","start":"2026-10-15T12:06:30.234653Z","end":"2026-10-15T12:06:30.371916Z","steps":["trace[1392534230] 'agreement among raft nodes before linearized reading' (duration: 137.045688ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:11.936013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21640} {"level":"info","ts":"2026-10-15T12:07:12.090922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21640,"took":"150.202344ms","hash":934921346,"current-db-size-bytes":55906304,"current-db-size":"56 MB","current-db-size-in-use-bytes":42106880,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-15T12:07:12.09099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":934921346,"revision":21640,"compact-revision":19432} {"level":"info","ts":"2026-10-15T12:08:11.655172Z","caller":"traceutil/trace.go:171","msg":"trace[235936461] linearizableReadLoop","detail":"{readStateIndex:26132; appliedIndex:26130; }","duration":"133.988313ms","start":"2026-10-15T12:08:11.521144Z","end":"2026-10-15T12:08:11.655132Z","steps":["trace[235936461] 'read index received' (duration: 52.207525ms)","trace[235936461] 'applied index is now lower than readState.Index' (duration: 81.779408ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:08:11.655266Z","caller":"traceutil/trace.go:171","msg":"trace[1335562087] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"138.540954ms","start":"2026-10-15T12:08:11.516658Z","end":"2026-10-15T12:08:11.655199Z","steps":["trace[1335562087] 'process raft request' (duration: 93.610023ms)","trace[1335562087] 'compare' (duration: 44.709706ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:08:11.65547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.308282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T12:08:11.655521Z","caller":"traceutil/trace.go:171","msg":"trace[331592864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25277; }","duration":"134.427405ms","start":"2026-10-15T12:08:11.52108Z","end":"2026-10-15T12:08:11.655507Z","steps":["trace[331592864] 'agreement among raft nodes before linearized reading' (duration: 134.203969ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:09:58.902604Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069d3.wal"} {"level":"info","ts":"2026-10-15T12:10:07.846647Z","caller":"traceutil/trace.go:171","msg":"trace[2032930612] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"180.167747ms","start":"2026-10-15T12:10:07.666456Z","end":"2026-10-15T12:10:07.846624Z","steps":["trace[2032930612] 'process raft request' (duration: 179.967392ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:10:09.973193Z","caller":"traceutil/trace.go:171","msg":"trace[1230409861] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"120.343598ms","start":"2026-10-15T12:10:09.852797Z","end":"2026-10-15T12:10:09.973141Z","steps":["trace[1230409861] 'process raft request' (duration: 120.105842ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:10:10.142567Z","caller":"traceutil/trace.go:171","msg":"trace[1085537557] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"163.176077ms","start":"2026-10-15T12:10:09.979331Z","end":"2026-10-15T12:10:10.142507Z","steps":["trace[1085537557] 'process raft request' (duration: 161.416404ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:10:10.315862Z","caller":"traceutil/trace.go:171","msg":"trace[1568903365] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"139.488112ms","start":"2026-10-15T12:10:10.176344Z","end":"2026-10-15T12:10:10.315832Z","steps":["trace[1568903365] 'process raft request' (duration: 127.994473ms)","trace[1568903365] 'compare' (duration: 11.381726ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:11:11.770168Z","caller":"traceutil/trace.go:171","msg":"trace[1609215037] linearizableReadLoop","detail":"{readStateIndex:27842; appliedIndex:27841; }","duration":"141.488561ms","start":"2026-10-15T12:11:11.628655Z","end":"2026-10-15T12:11:11.770143Z","steps":["trace[1609215037] 'read index received' (duration: 141.312617ms)","trace[1609215037] 'applied index is now lower than readState.Index' (duration: 175.014µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:11:11.770233Z","caller":"traceutil/trace.go:171","msg":"trace[1357684338] transaction","detail":"{read_only:false; response_revision:26945; number_of_response:1; }","duration":"141.808648ms","start":"2026-10-15T12:11:11.628382Z","end":"2026-10-15T12:11:11.77019Z","steps":["trace[1357684338] 'process raft request' (duration: 141.593193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:11.770377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.729357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:11:11.77041Z","caller":"traceutil/trace.go:171","msg":"trace[761152109] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26945; }","duration":"141.802088ms","start":"2026-10-15T12:11:11.628602Z","end":"2026-10-15T12:11:11.770404Z","steps":["trace[761152109] 'agreement among raft nodes before linearized reading' (duration: 141.690726ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:11.805542Z","caller":"traceutil/trace.go:171","msg":"trace[1950643792] transaction","detail":"{read_only:false; response_revision:26946; number_of_response:1; }","duration":"153.521502ms","start":"2026-10-15T12:11:11.651999Z","end":"2026-10-15T12:11:11.80552Z","steps":["trace[1950643792] 'process raft request' (duration: 151.064023ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:17.093737Z","caller":"traceutil/trace.go:171","msg":"trace[629569502] transaction","detail":"{read_only:false; response_revision:26984; number_of_response:1; }","duration":"139.131284ms","start":"2026-10-15T12:11:16.954553Z","end":"2026-10-15T12:11:17.093684Z","steps":["trace[629569502] 'process raft request' (duration: 138.869457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:17.307972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.753072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-15T12:11:17.308048Z","caller":"traceutil/trace.go:171","msg":"trace[574705121] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26984; }","duration":"187.901395ms","start":"2026-10-15T12:11:17.120126Z","end":"2026-10-15T12:11:17.308027Z","steps":["trace[574705121] 'range keys from in-memory index tree' (duration: 187.479946ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:17.473737Z","caller":"traceutil/trace.go:171","msg":"trace[1026683386] linearizableReadLoop","detail":"{readStateIndex:27883; appliedIndex:27882; }","duration":"339.883619ms","start":"2026-10-15T12:11:17.133828Z","end":"2026-10-15T12:11:17.473712Z","steps":["trace[1026683386] 'read index received' (duration: 339.612314ms)","trace[1026683386] 'applied index is now lower than readState.Index' (duration: 270.265µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:11:17.4738Z","caller":"traceutil/trace.go:171","msg":"trace[1024146838] transaction","detail":"{read_only:false; response_revision:26985; number_of_response:1; }","duration":"353.603662ms","start":"2026-10-15T12:11:17.120168Z","end":"2026-10-15T12:11:17.473771Z","steps":["trace[1024146838] 'process raft request' (duration: 353.362237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:17.473912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.28163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T12:11:17.473932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:11:17.120132Z","time spent":"353.728155ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:11:17.473951Z","caller":"traceutil/trace.go:171","msg":"trace[811860234] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:26985; }","duration":"293.400073ms","start":"2026-10-15T12:11:17.180539Z","end":"2026-10-15T12:11:17.473939Z","steps":["trace[811860234] 'agreement among raft nodes before linearized reading' (duration: 293.315271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:17.473986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.165437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T12:11:17.474043Z","caller":"traceutil/trace.go:171","msg":"trace[214698314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26985; }","duration":"340.274159ms","start":"2026-10-15T12:11:17.133754Z","end":"2026-10-15T12:11:17.474029Z","steps":["trace[214698314] 'agreement among raft nodes before linearized reading' (duration: 340.074404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:17.474074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:11:17.133728Z","time spent":"340.339181ms","remote":"127.0.0.1:57286","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-10-15T12:11:17.474083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.164679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:11:17.474098Z","caller":"traceutil/trace.go:171","msg":"trace[1320475550] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26985; }","duration":"232.246451ms","start":"2026-10-15T12:11:17.241845Z","end":"2026-10-15T12:11:17.474092Z","steps":["trace[1320475550] 'agreement among raft nodes before linearized reading' (duration: 232.181669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:17.4742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.244548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T12:11:17.474297Z","caller":"traceutil/trace.go:171","msg":"trace[558545396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26985; }","duration":"281.396381ms","start":"2026-10-15T12:11:17.192879Z","end":"2026-10-15T12:11:17.474276Z","steps":["trace[558545396] 'agreement among raft nodes before linearized reading' (duration: 281.227387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:17.904069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.476253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-10-15T12:11:17.904096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.591843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-15T12:11:17.904193Z","caller":"traceutil/trace.go:171","msg":"trace[1721667150] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26988; }","duration":"196.679409ms","start":"2026-10-15T12:11:17.707484Z","end":"2026-10-15T12:11:17.904164Z","steps":["trace[1721667150] 'range keys from in-memory index tree' (duration: 196.156676ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:17.904212Z","caller":"traceutil/trace.go:171","msg":"trace[550323075] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26988; }","duration":"160.767548ms","start":"2026-10-15T12:11:17.743422Z","end":"2026-10-15T12:11:17.90419Z","steps":["trace[550323075] 'range keys from in-memory index tree' (duration: 159.974759ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:17.904516Z","caller":"traceutil/trace.go:171","msg":"trace[982812070] transaction","detail":"{read_only:false; response_revision:26989; number_of_response:1; }","duration":"108.280036ms","start":"2026-10-15T12:11:17.79622Z","end":"2026-10-15T12:11:17.9045Z","steps":["trace[982812070] 'process raft request' (duration: 108.144443ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:18.102816Z","caller":"traceutil/trace.go:171","msg":"trace[2083391602] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"271.131733ms","start":"2026-10-15T12:11:17.831642Z","end":"2026-10-15T12:11:18.102774Z","steps":["trace[2083391602] 'process raft request' (duration: 270.865107ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:18.118696Z","caller":"traceutil/trace.go:171","msg":"trace[98247226] linearizableReadLoop","detail":"{readStateIndex:27889; appliedIndex:27887; }","duration":"206.277571ms","start":"2026-10-15T12:11:17.912391Z","end":"2026-10-15T12:11:18.118669Z","steps":["trace[98247226] 'read index received' (duration: 190.14741ms)","trace[98247226] 'applied index is now lower than readState.Index' (duration: 16.128781ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:11:18.118798Z","caller":"traceutil/trace.go:171","msg":"trace[1934044115] transaction","detail":"{read_only:false; response_revision:26991; number_of_response:1; }","duration":"207.498101ms","start":"2026-10-15T12:11:17.911249Z","end":"2026-10-15T12:11:18.118747Z","steps":["trace[1934044115] 'process raft request' (duration: 207.208934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:18.119014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.596369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-15T12:11:18.119121Z","caller":"traceutil/trace.go:171","msg":"trace[1778785383] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26991; }","duration":"206.781284ms","start":"2026-10-15T12:11:17.912315Z","end":"2026-10-15T12:11:18.119096Z","steps":["trace[1778785383] 'agreement among raft nodes before linearized reading' (duration: 206.464776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:18.521999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.474165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:11:18.522136Z","caller":"traceutil/trace.go:171","msg":"trace[1466973799] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26991; }","duration":"279.67277ms","start":"2026-10-15T12:11:18.242432Z","end":"2026-10-15T12:11:18.522104Z","steps":["trace[1466973799] 'count revisions from in-memory index tree' (duration: 279.369663ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:11:18.522151Z","caller":"traceutil/trace.go:171","msg":"trace[627218935] linearizableReadLoop","detail":"{readStateIndex:27890; appliedIndex:27889; }","duration":"113.739037ms","start":"2026-10-15T12:11:18.408362Z","end":"2026-10-15T12:11:18.522101Z","steps":["trace[627218935] 'read index received' (duration: 107.579038ms)","trace[627218935] 'applied index is now lower than readState.Index' (duration: 6.158719ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:11:18.522203Z","caller":"traceutil/trace.go:171","msg":"trace[2036876434] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"263.675902ms","start":"2026-10-15T12:11:18.258484Z","end":"2026-10-15T12:11:18.52216Z","steps":["trace[2036876434] 'process raft request' (duration: 257.538854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:11:18.522388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.015393ms","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-10-15T12:11:18.523574Z","caller":"traceutil/trace.go:171","msg":"trace[407834236] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26992; }","duration":"115.237933ms","start":"2026-10-15T12:11:18.408296Z","end":"2026-10-15T12:11:18.523533Z","steps":["trace[407834236] 'agreement among raft nodes before linearized reading' (duration: 113.934572ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:12:11.94393Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24648} {"level":"info","ts":"2026-10-15T12:12:12.107738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24648,"took":"158.669846ms","hash":1920372132,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":36904960,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-15T12:12:12.107821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1920372132,"revision":24648,"compact-revision":21640} {"level":"info","ts":"2026-10-15T12:12:36.144517Z","caller":"traceutil/trace.go:171","msg":"trace[2029601311] transaction","detail":"{read_only:false; response_revision:27740; number_of_response:1; }","duration":"141.048829ms","start":"2026-10-15T12:12:36.003436Z","end":"2026-10-15T12:12:36.144485Z","steps":["trace[2029601311] 'process raft request' (duration: 140.21187ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:12:36.144476Z","caller":"traceutil/trace.go:171","msg":"trace[2087229566] linearizableReadLoop","detail":"{readStateIndex:28657; appliedIndex:28655; }","duration":"139.739298ms","start":"2026-10-15T12:12:36.004687Z","end":"2026-10-15T12:12:36.144426Z","steps":["trace[2087229566] 'read index received' (duration: 12.445302ms)","trace[2087229566] 'applied index is now lower than readState.Index' (duration: 127.291996ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:12:36.145744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.987198ms","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-10-15T12:12:36.145918Z","caller":"traceutil/trace.go:171","msg":"trace[1106428654] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27740; }","duration":"141.191734ms","start":"2026-10-15T12:12:36.004655Z","end":"2026-10-15T12:12:36.145847Z","steps":["trace[1106428654] 'agreement among raft nodes before linearized reading' (duration: 139.924042ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:14:51.452998Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:14:51.460586Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-10-15T12:14:51.4607Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005} {"level":"warn","ts":"2026-10-15T12:15:04.687435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.04188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-15T12:15:04.687582Z","caller":"traceutil/trace.go:171","msg":"trace[175969616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29170; }","duration":"100.261285ms","start":"2026-10-15T12:15:04.587289Z","end":"2026-10-15T12:15:04.68755Z","steps":["trace[175969616] 'range keys from in-memory index tree' (duration: 99.776003ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:17:11.952459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27562} {"level":"info","ts":"2026-10-15T12:17:12.118432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27562,"took":"160.878286ms","hash":2898655627,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":34750464,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T12:17:12.11855Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2898655627,"revision":27562,"compact-revision":24648} {"level":"info","ts":"2026-10-15T12:20:56.232606Z","caller":"traceutil/trace.go:171","msg":"trace[1032150865] transaction","detail":"{read_only:false; response_revision:31949; number_of_response:1; }","duration":"104.68268ms","start":"2026-10-15T12:20:56.127873Z","end":"2026-10-15T12:20:56.232556Z","steps":["trace[1032150865] 'process raft request' (duration: 103.566382ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:20:57.358209Z","caller":"traceutil/trace.go:171","msg":"trace[1802921328] transaction","detail":"{read_only:false; response_revision:31958; number_of_response:1; }","duration":"170.088154ms","start":"2026-10-15T12:20:57.188083Z","end":"2026-10-15T12:20:57.358172Z","steps":["trace[1802921328] 'process raft request' (duration: 169.811967ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:20:57.358515Z","caller":"traceutil/trace.go:171","msg":"trace[1569133404] transaction","detail":"{read_only:false; response_revision:31959; number_of_response:1; }","duration":"166.781793ms","start":"2026-10-15T12:20:57.191723Z","end":"2026-10-15T12:20:57.358505Z","steps":["trace[1569133404] 'process raft request' (duration: 166.392273ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:20:57.358652Z","caller":"traceutil/trace.go:171","msg":"trace[3533395] linearizableReadLoop","detail":"{readStateIndex:32990; appliedIndex:32987; }","duration":"136.618098ms","start":"2026-10-15T12:20:57.222024Z","end":"2026-10-15T12:20:57.358642Z","steps":["trace[3533395] 'read index received' (duration: 40.906077ms)","trace[3533395] 'applied index is now lower than readState.Index' (duration: 95.710591ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:20:57.3588Z","caller":"traceutil/trace.go:171","msg":"trace[442475182] transaction","detail":"{read_only:false; response_revision:31960; number_of_response:1; }","duration":"119.029379ms","start":"2026-10-15T12:20:57.239695Z","end":"2026-10-15T12:20:57.358725Z","steps":["trace[442475182] 'process raft request' (duration: 118.763153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:20:57.358882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.840863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T12:20:57.35892Z","caller":"traceutil/trace.go:171","msg":"trace[825287243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:31960; }","duration":"136.961726ms","start":"2026-10-15T12:20:57.221946Z","end":"2026-10-15T12:20:57.358907Z","steps":["trace[825287243] 'agreement among raft nodes before linearized reading' (duration: 136.786293ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:20:58.390255Z","caller":"traceutil/trace.go:171","msg":"trace[1689943331] transaction","detail":"{read_only:false; response_revision:31965; number_of_response:1; }","duration":"106.74882ms","start":"2026-10-15T12:20:58.283472Z","end":"2026-10-15T12:20:58.390221Z","steps":["trace[1689943331] 'process raft request' (duration: 83.889774ms)","trace[1689943331] 'compare' (duration: 22.583649ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:20:58.390511Z","caller":"traceutil/trace.go:171","msg":"trace[1861438143] transaction","detail":"{read_only:false; response_revision:31966; number_of_response:1; }","duration":"103.893191ms","start":"2026-10-15T12:20:58.286587Z","end":"2026-10-15T12:20:58.390481Z","steps":["trace[1861438143] 'process raft request' (duration: 103.582643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:20:58.599843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.823439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:20:58.600178Z","caller":"traceutil/trace.go:171","msg":"trace[225437855] transaction","detail":"{read_only:false; response_revision:31967; number_of_response:1; }","duration":"113.577555ms","start":"2026-10-15T12:20:58.486577Z","end":"2026-10-15T12:20:58.600154Z","steps":["trace[225437855] 'process raft request' (duration: 11.299665ms)","trace[225437855] 'compare' (duration: 101.547132ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:20:59.604815Z","caller":"traceutil/trace.go:171","msg":"trace[1912670770] linearizableReadLoop","detail":"{readStateIndex:33007; appliedIndex:33006; }","duration":"121.059759ms","start":"2026-10-15T12:20:59.483724Z","end":"2026-10-15T12:20:59.604784Z","steps":["trace[1912670770] 'read index received' (duration: 120.467154ms)","trace[1912670770] 'applied index is now lower than readState.Index' (duration: 591.395µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:20:59.604955Z","caller":"traceutil/trace.go:171","msg":"trace[1720284110] transaction","detail":"{read_only:false; response_revision:31976; number_of_response:1; }","duration":"140.01658ms","start":"2026-10-15T12:20:59.464873Z","end":"2026-10-15T12:20:59.604889Z","steps":["trace[1720284110] 'process raft request' (duration: 139.394955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:20:59.605323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.554441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:20:59.605462Z","caller":"traceutil/trace.go:171","msg":"trace[1194084199] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:31976; }","duration":"121.784417ms","start":"2026-10-15T12:20:59.483652Z","end":"2026-10-15T12:20:59.605436Z","steps":["trace[1194084199] 'agreement among raft nodes before linearized reading' (duration: 121.242373ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:00.57507Z","caller":"traceutil/trace.go:171","msg":"trace[344943596] transaction","detail":"{read_only:false; response_revision:31980; number_of_response:1; }","duration":"162.554499ms","start":"2026-10-15T12:21:00.412471Z","end":"2026-10-15T12:21:00.575025Z","steps":["trace[344943596] 'process raft request' (duration: 160.963159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:01.707347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.690877ms","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:8"} {"level":"info","ts":"2026-10-15T12:21:01.707451Z","caller":"traceutil/trace.go:171","msg":"trace[140341716] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:31984; }","duration":"411.855121ms","start":"2026-10-15T12:21:01.29557Z","end":"2026-10-15T12:21:01.707425Z","steps":["trace[140341716] 'count revisions from in-memory index tree' (duration: 411.605935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:01.7075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:01.295546Z","time spent":"411.938323ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:21:01.708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.799192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:21:01.708165Z","caller":"traceutil/trace.go:171","msg":"trace[2029672879] linearizableReadLoop","detail":"{readStateIndex:33017; appliedIndex:33016; }","duration":"260.991427ms","start":"2026-10-15T12:21:01.447147Z","end":"2026-10-15T12:21:01.708139Z","steps":["trace[2029672879] 'read index received' (duration: 123.931539ms)","trace[2029672879] 'applied index is now lower than readState.Index' (duration: 137.058168ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:01.708297Z","caller":"traceutil/trace.go:171","msg":"trace[1169579225] transaction","detail":"{read_only:false; response_revision:31985; number_of_response:1; }","duration":"386.033432ms","start":"2026-10-15T12:21:01.322181Z","end":"2026-10-15T12:21:01.708214Z","steps":["trace[1169579225] 'process raft request' (duration: 248.879091ms)","trace[1169579225] 'compare' (duration: 136.548416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:01.708481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.319065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-15T12:21:01.708499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:01.322155Z","time spent":"386.228777ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:01.708557Z","caller":"traceutil/trace.go:171","msg":"trace[958933522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:31985; }","duration":"261.431448ms","start":"2026-10-15T12:21:01.447105Z","end":"2026-10-15T12:21:01.708537Z","steps":["trace[958933522] 'agreement among raft nodes before linearized reading' (duration: 261.12897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:01.760008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.628208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T12:21:01.76003Z","caller":"traceutil/trace.go:171","msg":"trace[854689198] transaction","detail":"{read_only:false; response_revision:31987; number_of_response:1; }","duration":"310.247296ms","start":"2026-10-15T12:21:01.449728Z","end":"2026-10-15T12:21:01.759975Z","steps":["trace[854689198] 'process raft request' (duration: 310.059191ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:01.760094Z","caller":"traceutil/trace.go:171","msg":"trace[2042324670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:31987; }","duration":"297.776012ms","start":"2026-10-15T12:21:01.462298Z","end":"2026-10-15T12:21:01.760074Z","steps":["trace[2042324670] 'agreement among raft nodes before linearized reading' (duration: 297.552127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:01.760267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:01.449723Z","time spent":"310.404349ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:01.760289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.527101ms","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-10-15T12:21:01.76039Z","caller":"traceutil/trace.go:171","msg":"trace[923751813] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:31987; }","duration":"149.689885ms","start":"2026-10-15T12:21:01.610673Z","end":"2026-10-15T12:21:01.760363Z","steps":["trace[923751813] 'agreement among raft nodes before linearized reading' (duration: 149.350157ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:01.759996Z","caller":"traceutil/trace.go:171","msg":"trace[1325090238] transaction","detail":"{read_only:false; response_revision:31986; number_of_response:1; }","duration":"310.321188ms","start":"2026-10-15T12:21:01.449594Z","end":"2026-10-15T12:21:01.759915Z","steps":["trace[1325090238] 'process raft request' (duration: 310.029442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:01.760552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:01.449582Z","time spent":"310.862351ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:02.089359Z","caller":"traceutil/trace.go:171","msg":"trace[1773230749] transaction","detail":"{read_only:false; response_revision:31992; number_of_response:1; }","duration":"193.090803ms","start":"2026-10-15T12:21:01.896223Z","end":"2026-10-15T12:21:02.089314Z","steps":["trace[1773230749] 'process raft request' (duration: 192.642122ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:04.439785Z","caller":"traceutil/trace.go:171","msg":"trace[956647672] transaction","detail":"{read_only:false; response_revision:32008; number_of_response:1; }","duration":"127.133486ms","start":"2026-10-15T12:21:04.312597Z","end":"2026-10-15T12:21:04.43973Z","steps":["trace[956647672] 'process raft request' (duration: 126.761967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:05.927203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.147645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T12:21:05.927288Z","caller":"traceutil/trace.go:171","msg":"trace[1484588724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32018; }","duration":"108.273247ms","start":"2026-10-15T12:21:05.818993Z","end":"2026-10-15T12:21:05.927267Z","steps":["trace[1484588724] 'range keys from in-memory index tree' (duration: 107.807576ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:06.094916Z","caller":"traceutil/trace.go:171","msg":"trace[1579388758] transaction","detail":"{read_only:false; response_revision:32019; number_of_response:1; }","duration":"162.714313ms","start":"2026-10-15T12:21:05.932168Z","end":"2026-10-15T12:21:06.094883Z","steps":["trace[1579388758] 'process raft request' (duration: 162.465917ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:06.115347Z","caller":"traceutil/trace.go:171","msg":"trace[514166536] transaction","detail":"{read_only:false; response_revision:32021; number_of_response:1; }","duration":"181.566232ms","start":"2026-10-15T12:21:05.933745Z","end":"2026-10-15T12:21:06.115311Z","steps":["trace[514166536] 'process raft request' (duration: 181.468709ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:06.115556Z","caller":"traceutil/trace.go:171","msg":"trace[345320378] transaction","detail":"{read_only:false; response_revision:32020; number_of_response:1; }","duration":"182.933576ms","start":"2026-10-15T12:21:05.93257Z","end":"2026-10-15T12:21:06.115503Z","steps":["trace[345320378] 'process raft request' (duration: 182.449534ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:06.88798Z","caller":"traceutil/trace.go:171","msg":"trace[1907488979] linearizableReadLoop","detail":"{readStateIndex:33058; appliedIndex:33057; }","duration":"106.131545ms","start":"2026-10-15T12:21:06.781815Z","end":"2026-10-15T12:21:06.887947Z","steps":["trace[1907488979] 'read index received' (duration: 105.880028ms)","trace[1907488979] 'applied index is now lower than readState.Index' (duration: 249.767µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:06.888021Z","caller":"traceutil/trace.go:171","msg":"trace[1717156456] transaction","detail":"{read_only:false; response_revision:32025; number_of_response:1; }","duration":"128.06764ms","start":"2026-10-15T12:21:06.759923Z","end":"2026-10-15T12:21:06.887991Z","steps":["trace[1717156456] 'process raft request' (duration: 127.784663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:06.88817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.35097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:21:06.888226Z","caller":"traceutil/trace.go:171","msg":"trace[1358995780] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:32025; }","duration":"106.475243ms","start":"2026-10-15T12:21:06.781732Z","end":"2026-10-15T12:21:06.888207Z","steps":["trace[1358995780] 'agreement among raft nodes before linearized reading' (duration: 106.3418ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:06.909064Z","caller":"traceutil/trace.go:171","msg":"trace[470708421] transaction","detail":"{read_only:false; response_revision:32026; number_of_response:1; }","duration":"101.975893ms","start":"2026-10-15T12:21:06.807066Z","end":"2026-10-15T12:21:06.909042Z","steps":["trace[470708421] 'process raft request' (duration: 101.831039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:07.118527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.268209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:21:07.11864Z","caller":"traceutil/trace.go:171","msg":"trace[647614842] linearizableReadLoop","detail":"{readStateIndex:33060; appliedIndex:33059; }","duration":"134.851634ms","start":"2026-10-15T12:21:06.98377Z","end":"2026-10-15T12:21:07.118621Z","steps":["trace[647614842] 'read index received' (duration: 109.663µs)","trace[647614842] 'applied index is now lower than readState.Index' (duration: 134.740501ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:07.118702Z","caller":"traceutil/trace.go:171","msg":"trace[835354609] transaction","detail":"{read_only:false; response_revision:32027; number_of_response:1; }","duration":"174.001478ms","start":"2026-10-15T12:21:06.944635Z","end":"2026-10-15T12:21:07.118636Z","steps":["trace[835354609] 'process raft request' (duration: 16.524243ms)","trace[835354609] 'compare' (duration: 157.146906ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:07.118762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.998518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:07.119082Z","caller":"traceutil/trace.go:171","msg":"trace[1150105856] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:32027; }","duration":"135.357747ms","start":"2026-10-15T12:21:06.983704Z","end":"2026-10-15T12:21:07.119062Z","steps":["trace[1150105856] 'agreement among raft nodes before linearized reading' (duration: 135.013249ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:08.303852Z","caller":"traceutil/trace.go:171","msg":"trace[1055043029] transaction","detail":"{read_only:false; response_revision:32037; number_of_response:1; }","duration":"127.757401ms","start":"2026-10-15T12:21:08.176009Z","end":"2026-10-15T12:21:08.303766Z","steps":["trace[1055043029] 'process raft request' (duration: 80.651364ms)","trace[1055043029] 'compare' (duration: 45.350855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:10.138214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.3034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:21:10.138572Z","caller":"traceutil/trace.go:171","msg":"trace[524702913] transaction","detail":"{read_only:false; response_revision:32046; number_of_response:1; }","duration":"237.428153ms","start":"2026-10-15T12:21:09.901097Z","end":"2026-10-15T12:21:10.138525Z","steps":["trace[524702913] 'process raft request' (duration: 93.719973ms)","trace[524702913] 'compare' (duration: 142.693735ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:10.139179Z","caller":"traceutil/trace.go:171","msg":"trace[1351781975] transaction","detail":"{read_only:false; response_revision:32047; number_of_response:1; }","duration":"236.137141ms","start":"2026-10-15T12:21:09.903022Z","end":"2026-10-15T12:21:10.139159Z","steps":["trace[1351781975] 'process raft request' (duration: 235.393043ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:10.682607Z","caller":"traceutil/trace.go:171","msg":"trace[1632314271] transaction","detail":"{read_only:false; response_revision:32051; number_of_response:1; }","duration":"145.976685ms","start":"2026-10-15T12:21:10.536598Z","end":"2026-10-15T12:21:10.682575Z","steps":["trace[1632314271] 'process raft request' (duration: 145.76576ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.285131Z","caller":"traceutil/trace.go:171","msg":"trace[1140782852] transaction","detail":"{read_only:false; response_revision:32055; number_of_response:1; }","duration":"113.527674ms","start":"2026-10-15T12:21:11.171578Z","end":"2026-10-15T12:21:11.285105Z","steps":["trace[1140782852] 'process raft request' (duration: 113.336869ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.586369Z","caller":"traceutil/trace.go:171","msg":"trace[511802739] linearizableReadLoop","detail":"{readStateIndex:33091; appliedIndex:33090; }","duration":"136.999887ms","start":"2026-10-15T12:21:11.449317Z","end":"2026-10-15T12:21:11.586317Z","steps":["trace[511802739] 'read index received' (duration: 57.746727ms)","trace[511802739] 'applied index is now lower than readState.Index' (duration: 79.25115ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:11.586418Z","caller":"traceutil/trace.go:171","msg":"trace[1708391458] transaction","detail":"{read_only:false; response_revision:32057; number_of_response:1; }","duration":"164.574208ms","start":"2026-10-15T12:21:11.421784Z","end":"2026-10-15T12:21:11.586358Z","steps":["trace[1708391458] 'process raft request' (duration: 85.37722ms)","trace[1708391458] 'compare' (duration: 78.910792ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:11.586631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.971202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:21:11.586677Z","caller":"traceutil/trace.go:171","msg":"trace[1579649329] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:32057; }","duration":"134.082835ms","start":"2026-10-15T12:21:11.452577Z","end":"2026-10-15T12:21:11.58666Z","steps":["trace[1579649329] 'agreement among raft nodes before linearized reading' (duration: 133.949432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:11.586721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.372486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329953"} {"level":"info","ts":"2026-10-15T12:21:11.586826Z","caller":"traceutil/trace.go:171","msg":"trace[1350909493] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:32057; }","duration":"137.520309ms","start":"2026-10-15T12:21:11.449266Z","end":"2026-10-15T12:21:11.586787Z","steps":["trace[1350909493] 'agreement among raft nodes before linearized reading' (duration: 137.166861ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:12.010962Z","caller":"traceutil/trace.go:171","msg":"trace[1830398031] transaction","detail":"{read_only:false; response_revision:32058; number_of_response:1; }","duration":"100.458727ms","start":"2026-10-15T12:21:11.910469Z","end":"2026-10-15T12:21:12.010928Z","steps":["trace[1830398031] 'process raft request' (duration: 100.217451ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:12.027786Z","caller":"traceutil/trace.go:171","msg":"trace[582988340] transaction","detail":"{read_only:false; response_revision:32059; number_of_response:1; }","duration":"111.894365ms","start":"2026-10-15T12:21:11.915848Z","end":"2026-10-15T12:21:12.027742Z","steps":["trace[582988340] 'process raft request' (duration: 111.538337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:12.191222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.697332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:12.191345Z","caller":"traceutil/trace.go:171","msg":"trace[1565323500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32059; }","duration":"116.900347ms","start":"2026-10-15T12:21:12.07441Z","end":"2026-10-15T12:21:12.19131Z","steps":["trace[1565323500] 'range keys from in-memory index tree' (duration: 116.554499ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:12.259674Z","caller":"traceutil/trace.go:171","msg":"trace[1220540698] transaction","detail":"{read_only:false; response_revision:32060; number_of_response:1; }","duration":"114.593401ms","start":"2026-10-15T12:21:12.145044Z","end":"2026-10-15T12:21:12.259638Z","steps":["trace[1220540698] 'process raft request' (duration: 114.308934ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.004038Z","caller":"traceutil/trace.go:171","msg":"trace[316304364] linearizableReadLoop","detail":"{readStateIndex:33095; appliedIndex:33093; }","duration":"785.798408ms","start":"2026-10-15T12:21:12.218212Z","end":"2026-10-15T12:21:13.00401Z","steps":["trace[316304364] 'read index received' (duration: 41.182492ms)","trace[316304364] 'applied index is now lower than readState.Index' (duration: 744.614066ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:13.004333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.105516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T12:21:13.004379Z","caller":"traceutil/trace.go:171","msg":"trace[1951762934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32061; }","duration":"786.215929ms","start":"2026-10-15T12:21:12.218148Z","end":"2026-10-15T12:21:13.004364Z","steps":["trace[1951762934] 'agreement among raft nodes before linearized reading' (duration: 785.988003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.004418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.21812Z","time spent":"786.288041ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T12:21:13.004778Z","caller":"traceutil/trace.go:171","msg":"trace[889685816] transaction","detail":"{read_only:false; response_revision:32061; number_of_response:1; }","duration":"859.061642ms","start":"2026-10-15T12:21:12.145705Z","end":"2026-10-15T12:21:13.004766Z","steps":["trace[889685816] 'process raft request' (duration: 858.120449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.004906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.145687Z","time spent":"859.154375ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:13.602224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.533109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:21:13.602525Z","caller":"traceutil/trace.go:171","msg":"trace[268200400] transaction","detail":"{read_only:false; response_revision:32062; number_of_response:1; }","duration":"972.587507ms","start":"2026-10-15T12:21:12.629916Z","end":"2026-10-15T12:21:13.602503Z","steps":["trace[268200400] 'process raft request' (duration: 749.676898ms)","trace[268200400] 'compare' (duration: 222.323434ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:13.602869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.629885Z","time spent":"972.914995ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:13.603314Z","caller":"traceutil/trace.go:171","msg":"trace[1405004780] transaction","detail":"{read_only:false; response_revision:32063; number_of_response:1; }","duration":"915.087837ms","start":"2026-10-15T12:21:12.688184Z","end":"2026-10-15T12:21:13.603272Z","steps":["trace[1405004780] 'process raft request' (duration: 914.242656ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.603466Z","caller":"traceutil/trace.go:171","msg":"trace[1302769627] transaction","detail":"{read_only:false; response_revision:32064; number_of_response:1; }","duration":"736.928378ms","start":"2026-10-15T12:21:12.86651Z","end":"2026-10-15T12:21:13.603438Z","steps":["trace[1302769627] 'process raft request' (duration: 736.703592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.60355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.688169Z","time spent":"915.263291ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:13.603634Z","caller":"traceutil/trace.go:171","msg":"trace[1728582791] transaction","detail":"{read_only:false; response_revision:32065; number_of_response:1; }","duration":"589.841395ms","start":"2026-10-15T12:21:13.013766Z","end":"2026-10-15T12:21:13.603607Z","steps":["trace[1728582791] 'process raft request' (duration: 589.607709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.603639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144032544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:13.603461Z","caller":"traceutil/trace.go:171","msg":"trace[1243676656] linearizableReadLoop","detail":"{readStateIndex:33098; appliedIndex:33095; }","duration":"599.302816ms","start":"2026-10-15T12:21:13.004121Z","end":"2026-10-15T12:21:13.603424Z","steps":["trace[1243676656] 'read index received' (duration: 1.400483ms)","trace[1243676656] 'applied index is now lower than readState.Index' (duration: 597.900503ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:13.603842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.942367ms","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-10-15T12:21:13.603885Z","caller":"traceutil/trace.go:171","msg":"trace[839560363] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:32065; }","duration":"640.032679ms","start":"2026-10-15T12:21:12.963841Z","end":"2026-10-15T12:21:13.603873Z","steps":["trace[839560363] 'agreement among raft nodes before linearized reading' (duration: 639.789073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.603925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.963796Z","time spent":"640.118491ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-10-15T12:21:13.603921Z","caller":"traceutil/trace.go:171","msg":"trace[687868775] transaction","detail":"{read_only:false; response_revision:32066; number_of_response:1; }","duration":"310.82734ms","start":"2026-10-15T12:21:13.293068Z","end":"2026-10-15T12:21:13.603896Z","steps":["trace[687868775] 'process raft request' (duration: 310.451601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.603703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.636659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T12:21:13.60405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.293035Z","time spent":"310.943783ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:13.60406Z","caller":"traceutil/trace.go:171","msg":"trace[1088363604] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:32065; }","duration":"902.04795ms","start":"2026-10-15T12:21:12.701982Z","end":"2026-10-15T12:21:13.604029Z","steps":["trace[1088363604] 'agreement among raft nodes before linearized reading' (duration: 901.470995ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.603743Z","caller":"traceutil/trace.go:171","msg":"trace[1864662538] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:32065; }","duration":"1.144225218s","start":"2026-10-15T12:21:12.459494Z","end":"2026-10-15T12:21:13.603719Z","steps":["trace[1864662538] 'agreement among raft nodes before linearized reading' (duration: 1.143978352s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.604134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.701962Z","time spent":"902.141392ms","remote":"127.0.0.1:60584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":80,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:21:13.604141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208096474s","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":"warn","ts":"2026-10-15T12:21:13.603758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.013724Z","time spent":"589.973788ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:13.604157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.459477Z","time spent":"1.144651138s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:21:13.604245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.315852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T12:21:13.6042Z","caller":"traceutil/trace.go:171","msg":"trace[970587685] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32065; }","duration":"1.208200426s","start":"2026-10-15T12:21:12.39598Z","end":"2026-10-15T12:21:13.604181Z","steps":["trace[970587685] 'agreement among raft nodes before linearized reading' (duration: 1.207582611s)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.604308Z","caller":"traceutil/trace.go:171","msg":"trace[1960430430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32065; }","duration":"832.535577ms","start":"2026-10-15T12:21:12.771753Z","end":"2026-10-15T12:21:13.604289Z","steps":["trace[1960430430] 'agreement among raft nodes before linearized reading' (duration: 831.900371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.604337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.395958Z","time spent":"1.208351631s","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T12:21:13.604352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.771725Z","time spent":"832.617169ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T12:21:13.604356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246335695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T12:21:13.604525Z","caller":"traceutil/trace.go:171","msg":"trace[1811309464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32065; }","duration":"1.246626862s","start":"2026-10-15T12:21:12.357876Z","end":"2026-10-15T12:21:13.604503Z","steps":["trace[1811309464] 'agreement among raft nodes before linearized reading' (duration: 1.245861683s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.604589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.357787Z","time spent":"1.246786075s","remote":"127.0.0.1:57286","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-10-15T12:21:13.604835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:12.866473Z","time spent":"737.079752ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:13.876597Z","caller":"traceutil/trace.go:171","msg":"trace[1410893637] linearizableReadLoop","detail":"{readStateIndex:33101; appliedIndex:33100; }","duration":"272.690651ms","start":"2026-10-15T12:21:13.603871Z","end":"2026-10-15T12:21:13.876561Z","steps":["trace[1410893637] 'read index received' (duration: 272.432615ms)","trace[1410893637] 'applied index is now lower than readState.Index' (duration: 256.316µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:13.876773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.700542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:13.87686Z","caller":"traceutil/trace.go:171","msg":"trace[1061876200] 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:32067; }","duration":"699.866936ms","start":"2026-10-15T12:21:13.176977Z","end":"2026-10-15T12:21:13.876844Z","steps":["trace[1061876200] 'agreement among raft nodes before linearized reading' (duration: 699.673931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.876808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.859887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T12:21:13.876906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.176908Z","time spent":"699.986659ms","remote":"127.0.0.1:51932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-10-15T12:21:13.876926Z","caller":"traceutil/trace.go:171","msg":"trace[563848441] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:32067; }","duration":"636.032151ms","start":"2026-10-15T12:21:13.24087Z","end":"2026-10-15T12:21:13.876902Z","steps":["trace[563848441] 'agreement among raft nodes before linearized reading' (duration: 635.740184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.87692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.171809ms","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":"warn","ts":"2026-10-15T12:21:13.876991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.240856Z","time spent":"636.122053ms","remote":"127.0.0.1:57266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:21:13.877059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.150246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T12:21:13.877057Z","caller":"traceutil/trace.go:171","msg":"trace[1323774051] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:32067; }","duration":"660.395304ms","start":"2026-10-15T12:21:13.216627Z","end":"2026-10-15T12:21:13.877023Z","steps":["trace[1323774051] 'agreement among raft nodes before linearized reading' (duration: 660.130538ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.877106Z","caller":"traceutil/trace.go:171","msg":"trace[1049989494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:32067; }","duration":"804.235928ms","start":"2026-10-15T12:21:13.072857Z","end":"2026-10-15T12:21:13.877093Z","steps":["trace[1049989494] 'agreement among raft nodes before linearized reading' (duration: 804.075894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.877141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.072835Z","time spent":"804.2986ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T12:21:13.877149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.216595Z","time spent":"660.518568ms","remote":"127.0.0.1:60752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:21:13.876797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.645793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T12:21:13.877414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.650121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-15T12:21:13.877422Z","caller":"traceutil/trace.go:171","msg":"trace[1723635449] transaction","detail":"{read_only:false; response_revision:32067; number_of_response:1; }","duration":"282.999583ms","start":"2026-10-15T12:21:13.594357Z","end":"2026-10-15T12:21:13.877357Z","steps":["trace[1723635449] 'process raft request' (duration: 281.949337ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.877456Z","caller":"traceutil/trace.go:171","msg":"trace[16166121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32067; }","duration":"451.733483ms","start":"2026-10-15T12:21:13.425707Z","end":"2026-10-15T12:21:13.877441Z","steps":["trace[16166121] 'agreement among raft nodes before linearized reading' (duration: 451.60935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.877483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.42568Z","time spent":"451.796684ms","remote":"127.0.0.1:57286","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-10-15T12:21:13.877455Z","caller":"traceutil/trace.go:171","msg":"trace[1266106759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32067; }","duration":"804.208127ms","start":"2026-10-15T12:21:13.073087Z","end":"2026-10-15T12:21:13.877296Z","steps":["trace[1266106759] 'agreement among raft nodes before linearized reading' (duration: 803.661924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.878057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:13.073062Z","time spent":"804.974976ms","remote":"127.0.0.1:41036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T12:21:13.900149Z","caller":"traceutil/trace.go:171","msg":"trace[952270241] transaction","detail":"{read_only:false; response_revision:32068; number_of_response:1; }","duration":"289.139692ms","start":"2026-10-15T12:21:13.61098Z","end":"2026-10-15T12:21:13.900119Z","steps":["trace[952270241] 'process raft request' (duration: 288.925627ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.900399Z","caller":"traceutil/trace.go:171","msg":"trace[825181713] transaction","detail":"{read_only:false; response_revision:32069; number_of_response:1; }","duration":"289.271755ms","start":"2026-10-15T12:21:13.611109Z","end":"2026-10-15T12:21:13.900381Z","steps":["trace[825181713] 'process raft request' (duration: 288.967008ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.900455Z","caller":"traceutil/trace.go:171","msg":"trace[1254298763] transaction","detail":"{read_only:false; response_revision:32070; number_of_response:1; }","duration":"288.543238ms","start":"2026-10-15T12:21:13.611899Z","end":"2026-10-15T12:21:13.900442Z","steps":["trace[1254298763] 'process raft request' (duration: 288.366863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:13.900585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.34428ms","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-10-15T12:21:13.900658Z","caller":"traceutil/trace.go:171","msg":"trace[776364323] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:32070; }","duration":"122.440283ms","start":"2026-10-15T12:21:13.778196Z","end":"2026-10-15T12:21:13.900637Z","steps":["trace[776364323] 'agreement among raft nodes before linearized reading' (duration: 122.268648ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:14.136401Z","caller":"traceutil/trace.go:171","msg":"trace[162187191] transaction","detail":"{read_only:false; response_revision:32073; number_of_response:1; }","duration":"118.072176ms","start":"2026-10-15T12:21:14.018291Z","end":"2026-10-15T12:21:14.136363Z","steps":["trace[162187191] 'process raft request' (duration: 117.604105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.769209Z","caller":"traceutil/trace.go:171","msg":"trace[981307836] transaction","detail":"{read_only:false; response_revision:32078; number_of_response:1; }","duration":"159.852773ms","start":"2026-10-15T12:21:15.609323Z","end":"2026-10-15T12:21:15.769176Z","steps":["trace[981307836] 'process raft request' (duration: 158.739647ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.769563Z","caller":"traceutil/trace.go:171","msg":"trace[1405723804] transaction","detail":"{read_only:false; response_revision:32081; number_of_response:1; }","duration":"131.432131ms","start":"2026-10-15T12:21:15.638103Z","end":"2026-10-15T12:21:15.769535Z","steps":["trace[1405723804] 'process raft request' (duration: 131.37041ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.76962Z","caller":"traceutil/trace.go:171","msg":"trace[1655393406] transaction","detail":"{read_only:false; response_revision:32080; number_of_response:1; }","duration":"159.099805ms","start":"2026-10-15T12:21:15.610473Z","end":"2026-10-15T12:21:15.769573Z","steps":["trace[1655393406] 'process raft request' (duration: 158.840119ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.769626Z","caller":"traceutil/trace.go:171","msg":"trace[845801722] transaction","detail":"{read_only:false; response_revision:32079; number_of_response:1; }","duration":"159.227388ms","start":"2026-10-15T12:21:15.610369Z","end":"2026-10-15T12:21:15.769597Z","steps":["trace[845801722] 'process raft request' (duration: 158.714955ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.769792Z","caller":"traceutil/trace.go:171","msg":"trace[1142574649] linearizableReadLoop","detail":"{readStateIndex:33115; appliedIndex:33111; }","duration":"158.759726ms","start":"2026-10-15T12:21:15.61101Z","end":"2026-10-15T12:21:15.76977Z","steps":["trace[1142574649] 'read index received' (duration: 25.86997ms)","trace[1142574649] 'applied index is now lower than readState.Index' (duration: 132.887476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:15.770082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.026432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T12:21:15.770175Z","caller":"traceutil/trace.go:171","msg":"trace[2024098318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32081; }","duration":"159.199637ms","start":"2026-10-15T12:21:15.610954Z","end":"2026-10-15T12:21:15.770153Z","steps":["trace[2024098318] 'agreement among raft nodes before linearized reading' (duration: 158.91543ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:16.019005Z","caller":"traceutil/trace.go:171","msg":"trace[1654782526] linearizableReadLoop","detail":"{readStateIndex:33118; appliedIndex:33117; }","duration":"113.871383ms","start":"2026-10-15T12:21:15.905096Z","end":"2026-10-15T12:21:16.018967Z","steps":["trace[1654782526] 'read index received' (duration: 46.386359ms)","trace[1654782526] 'applied index is now lower than readState.Index' (duration: 67.483034ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:16.019049Z","caller":"traceutil/trace.go:171","msg":"trace[2089133457] transaction","detail":"{read_only:false; response_revision:32083; number_of_response:1; }","duration":"134.799693ms","start":"2026-10-15T12:21:15.884198Z","end":"2026-10-15T12:21:16.018998Z","steps":["trace[2089133457] 'process raft request' (duration: 67.375531ms)","trace[2089133457] 'compare' (duration: 67.097054ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:16.019369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.256662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T12:21:16.019433Z","caller":"traceutil/trace.go:171","msg":"trace[1812669500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32083; }","duration":"114.404256ms","start":"2026-10-15T12:21:15.905009Z","end":"2026-10-15T12:21:16.019414Z","steps":["trace[1812669500] 'agreement among raft nodes before linearized reading' (duration: 114.13529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:16.01937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.680858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T12:21:16.019598Z","caller":"traceutil/trace.go:171","msg":"trace[700253448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32083; }","duration":"113.885843ms","start":"2026-10-15T12:21:15.905651Z","end":"2026-10-15T12:21:16.019537Z","steps":["trace[700253448] 'agreement among raft nodes before linearized reading' (duration: 113.604826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:16.019896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.991693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-15T12:21:16.019883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.90263ms","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-10-15T12:21:16.019947Z","caller":"traceutil/trace.go:171","msg":"trace[666128731] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:32083; }","duration":"104.069495ms","start":"2026-10-15T12:21:15.915865Z","end":"2026-10-15T12:21:16.019935Z","steps":["trace[666128731] 'agreement among raft nodes before linearized reading' (duration: 103.720677ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:16.019988Z","caller":"traceutil/trace.go:171","msg":"trace[746310708] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32083; }","duration":"113.075674ms","start":"2026-10-15T12:21:15.906885Z","end":"2026-10-15T12:21:16.019961Z","steps":["trace[746310708] 'agreement among raft nodes before linearized reading' (duration: 112.733545ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:16.179964Z","caller":"traceutil/trace.go:171","msg":"trace[1978076825] transaction","detail":"{read_only:false; response_revision:32086; number_of_response:1; }","duration":"154.072132ms","start":"2026-10-15T12:21:16.025856Z","end":"2026-10-15T12:21:16.179928Z","steps":["trace[1978076825] 'process raft request' (duration: 108.593674ms)","trace[1978076825] 'compare' (duration: 45.236132ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:16.179995Z","caller":"traceutil/trace.go:171","msg":"trace[360703316] transaction","detail":"{read_only:false; response_revision:32087; number_of_response:1; }","duration":"153.783316ms","start":"2026-10-15T12:21:16.026191Z","end":"2026-10-15T12:21:16.179975Z","steps":["trace[360703316] 'process raft request' (duration: 153.673814ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:16.180407Z","caller":"traceutil/trace.go:171","msg":"trace[87331295] transaction","detail":"{read_only:false; response_revision:32088; number_of_response:1; }","duration":"151.373067ms","start":"2026-10-15T12:21:16.028996Z","end":"2026-10-15T12:21:16.180369Z","steps":["trace[87331295] 'process raft request' (duration: 150.927637ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:16.180465Z","caller":"traceutil/trace.go:171","msg":"trace[553202802] linearizableReadLoop","detail":"{readStateIndex:33123; appliedIndex:33120; }","duration":"107.086098ms","start":"2026-10-15T12:21:16.073353Z","end":"2026-10-15T12:21:16.180439Z","steps":["trace[553202802] 'read index received' (duration: 16.263376ms)","trace[553202802] 'applied index is now lower than readState.Index' (duration: 90.820752ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:16.180579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.21205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:16.18062Z","caller":"traceutil/trace.go:171","msg":"trace[759930703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32089; }","duration":"107.331123ms","start":"2026-10-15T12:21:16.073276Z","end":"2026-10-15T12:21:16.180607Z","steps":["trace[759930703] 'agreement among raft nodes before linearized reading' (duration: 107.23185ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:22:11.960366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30255} {"level":"info","ts":"2026-10-15T12:22:12.112793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30255,"took":"147.243505ms","hash":1539445264,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":30584832,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T12:22:12.112894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1539445264,"revision":30255,"compact-revision":27562} {"level":"info","ts":"2026-10-15T12:27:11.968215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32489} {"level":"info","ts":"2026-10-15T12:27:12.143244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32489,"took":"169.238072ms","hash":1882886094,"current-db-size-bytes":59060224,"current-db-size":"59 MB","current-db-size-in-use-bytes":30162944,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T12:27:12.143331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1882886094,"revision":32489,"compact-revision":30255} {"level":"info","ts":"2026-10-15T12:32:11.974335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34716} {"level":"info","ts":"2026-10-15T12:32:12.11932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34716,"took":"139.369674ms","hash":3423995805,"current-db-size-bytes":59060224,"current-db-size":"59 MB","current-db-size-in-use-bytes":30982144,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T12:32:12.119399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3423995805,"revision":34716,"compact-revision":32489} {"level":"info","ts":"2026-10-15T12:36:02.870164Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":40006,"local-member-snapshot-index":30005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:36:02.875869Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40006} {"level":"info","ts":"2026-10-15T12:36:02.876026Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35006} {"level":"info","ts":"2026-10-15T12:37:11.985329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36975} {"level":"info","ts":"2026-10-15T12:37:12.146246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36975,"took":"153.996055ms","hash":1828726050,"current-db-size-bytes":59060224,"current-db-size":"59 MB","current-db-size-in-use-bytes":32993280,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T12:37:12.14634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1828726050,"revision":36975,"compact-revision":34716} {"level":"info","ts":"2026-10-15T12:42:11.996117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39284} {"level":"info","ts":"2026-10-15T12:42:12.145867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39284,"took":"143.305083ms","hash":1064584598,"current-db-size-bytes":60760064,"current-db-size":"61 MB","current-db-size-in-use-bytes":32063488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:42:12.145939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1064584598,"revision":39284,"compact-revision":36975}