{"level":"warn","ts":"2026-04-28T05:58:14.411438Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T05:58:14.411529Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-04-28T05:58:14.411594Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T05:58:14.411606Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-28T05:58:14.411625Z","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-04-28T05:58:14.412263Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-28T05:58:14.412368Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-04-28T05:58:14.416589Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.011426ms"} {"level":"info","ts":"2026-04-28T05:58:14.42475Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-28T05:58:14.424852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T05:58:14.424883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-28T05:58:14.424903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T05:58:14.424916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-28T05:58:14.424976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-28T05:58:14.431624Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T05:58:14.434497Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T05:58:14.435938Z","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-04-28T05:58:14.438377Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T05:58:14.438512Z","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-04-28T05:58:14.438665Z","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-04-28T05:58:14.438685Z","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-04-28T05:58:14.43864Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T05:58:14.441416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-28T05:58:14.4417Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-28T05:58:14.444066Z","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-04-28T05:58:14.444246Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-28T05:58:14.44437Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T05:58:14.444397Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-28T05:58:14.44442Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T05:58:14.92563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T05:58:14.925695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T05:58:14.925729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-28T05:58:14.925748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-28T05:58:14.925766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-28T05:58:14.925782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-28T05:58:14.925795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-28T05:58:14.926714Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T05:58:14.927948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T05:58:14.928026Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T05:58:14.928219Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:58:14.928419Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T05:58:14.928475Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T05:58:14.93098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T05:58:14.933262Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-28T05:58:14.948535Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:58:14.948636Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:58:14.948658Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:58:52.350851Z","caller":"traceutil/trace.go:171","msg":"trace[1822643462] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"118.807547ms","start":"2026-04-28T05:58:52.232017Z","end":"2026-04-28T05:58:52.350825Z","steps":["trace[1822643462] 'process raft request' (duration: 118.340796ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:58:52.785371Z","caller":"traceutil/trace.go:171","msg":"trace[1020331135] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"379.488471ms","start":"2026-04-28T05:58:52.405857Z","end":"2026-04-28T05:58:52.785346Z","steps":["trace[1020331135] 'process raft request' (duration: 334.347619ms)","trace[1020331135] 'compare' (duration: 44.646909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T05:58:52.785747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T05:58:52.405837Z","time spent":"379.598474ms","remote":"127.0.0.1:34794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:01:15.413508Z","caller":"traceutil/trace.go:171","msg":"trace[1993813424] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"122.304249ms","start":"2026-04-28T06:01:15.291151Z","end":"2026-04-28T06:01:15.413456Z","steps":["trace[1993813424] 'process raft request' (duration: 121.999942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:21.16903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.188843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:01:21.169147Z","caller":"traceutil/trace.go:171","msg":"trace[1038693653] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2217; }","duration":"121.434869ms","start":"2026-04-28T06:01:21.04769Z","end":"2026-04-28T06:01:21.169125Z","steps":["trace[1038693653] 'count revisions from in-memory index tree' (duration: 121.06968ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:55.224564Z","caller":"traceutil/trace.go:171","msg":"trace[2114495437] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"165.77411ms","start":"2026-04-28T06:01:55.058762Z","end":"2026-04-28T06:01:55.224536Z","steps":["trace[2114495437] 'process raft request' (duration: 165.623206ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:55.226495Z","caller":"traceutil/trace.go:171","msg":"trace[1711658874] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"135.916934ms","start":"2026-04-28T06:01:55.090555Z","end":"2026-04-28T06:01:55.226472Z","steps":["trace[1711658874] 'process raft request' (duration: 135.778841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:02:30.30236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.525213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:02:30.302479Z","caller":"traceutil/trace.go:171","msg":"trace[1714345515] linearizableReadLoop","detail":"{readStateIndex:2903; appliedIndex:2902; }","duration":"251.909778ms","start":"2026-04-28T06:02:30.050555Z","end":"2026-04-28T06:02:30.302464Z","steps":["trace[1714345515] 'read index received' (duration: 52.874083ms)","trace[1714345515] 'applied index is now lower than readState.Index' (duration: 199.034515ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:02:30.302525Z","caller":"traceutil/trace.go:171","msg":"trace[1153273714] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"266.736643ms","start":"2026-04-28T06:02:30.03574Z","end":"2026-04-28T06:02:30.302477Z","steps":["trace[1153273714] 'process raft request' (duration: 67.748639ms)","trace[1153273714] 'compare' (duration: 198.215156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:02:30.302692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.706513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T06:02:30.302687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.111753ms","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-04-28T06:02:30.30274Z","caller":"traceutil/trace.go:171","msg":"trace[4226024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2791; }","duration":"179.796106ms","start":"2026-04-28T06:02:30.12293Z","end":"2026-04-28T06:02:30.302726Z","steps":["trace[4226024] 'agreement among raft nodes before linearized reading' (duration: 179.625322ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:02:30.302783Z","caller":"traceutil/trace.go:171","msg":"trace[318706200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2791; }","duration":"252.261757ms","start":"2026-04-28T06:02:30.050503Z","end":"2026-04-28T06:02:30.302765Z","steps":["trace[318706200] 'agreement among raft nodes before linearized reading' (duration: 252.018191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:02:30.302823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.684403ms","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-04-28T06:02:30.302916Z","caller":"traceutil/trace.go:171","msg":"trace[1568490943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2791; }","duration":"179.815526ms","start":"2026-04-28T06:02:30.123065Z","end":"2026-04-28T06:02:30.30288Z","steps":["trace[1568490943] 'agreement among raft nodes before linearized reading' (duration: 179.53864ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:27.180972Z","caller":"traceutil/trace.go:171","msg":"trace[1905049860] linearizableReadLoop","detail":"{readStateIndex:5170; appliedIndex:5169; }","duration":"117.620745ms","start":"2026-04-28T06:06:27.063321Z","end":"2026-04-28T06:06:27.180942Z","steps":["trace[1905049860] 'read index received' (duration: 117.286557ms)","trace[1905049860] 'applied index is now lower than readState.Index' (duration: 332.958µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:27.181039Z","caller":"traceutil/trace.go:171","msg":"trace[792611900] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"127.640426ms","start":"2026-04-28T06:06:27.053359Z","end":"2026-04-28T06:06:27.181Z","steps":["trace[792611900] 'process raft request' (duration: 127.4073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:27.181218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.870981ms","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-04-28T06:06:27.181279Z","caller":"traceutil/trace.go:171","msg":"trace[783880091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4976; }","duration":"117.991814ms","start":"2026-04-28T06:06:27.063271Z","end":"2026-04-28T06:06:27.181263Z","steps":["trace[783880091] 'agreement among raft nodes before linearized reading' (duration: 117.793979ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:37.442009Z","caller":"traceutil/trace.go:171","msg":"trace[1098467911] linearizableReadLoop","detail":"{readStateIndex:5381; appliedIndex:5379; }","duration":"130.595637ms","start":"2026-04-28T06:06:37.311386Z","end":"2026-04-28T06:06:37.441981Z","steps":["trace[1098467911] 'read index received' (duration: 15.869451ms)","trace[1098467911] 'applied index is now lower than readState.Index' (duration: 114.725136ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:37.442266Z","caller":"traceutil/trace.go:171","msg":"trace[2002824096] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"132.273016ms","start":"2026-04-28T06:06:37.309978Z","end":"2026-04-28T06:06:37.442251Z","steps":["trace[2002824096] 'process raft request' (duration: 131.745534ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:37.442796Z","caller":"traceutil/trace.go:171","msg":"trace[1509900663] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"131.059268ms","start":"2026-04-28T06:06:37.311725Z","end":"2026-04-28T06:06:37.442784Z","steps":["trace[1509900663] 'process raft request' (duration: 130.193297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:37.443162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.742004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:37.443234Z","caller":"traceutil/trace.go:171","msg":"trace[1521165700] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:5180; }","duration":"131.862257ms","start":"2026-04-28T06:06:37.311351Z","end":"2026-04-28T06:06:37.443213Z","steps":["trace[1521165700] 'agreement among raft nodes before linearized reading' (duration: 131.749034ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:38.866655Z","caller":"traceutil/trace.go:171","msg":"trace[2146552223] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"198.153189ms","start":"2026-04-28T06:06:38.668471Z","end":"2026-04-28T06:06:38.866624Z","steps":["trace[2146552223] 'process raft request' (duration: 142.997265ms)","trace[2146552223] 'compare' (duration: 54.933138ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:38.866982Z","caller":"traceutil/trace.go:171","msg":"trace[129488004] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"198.286142ms","start":"2026-04-28T06:06:38.668676Z","end":"2026-04-28T06:06:38.866962Z","steps":["trace[129488004] 'process raft request' (duration: 198.162329ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:38.86699Z","caller":"traceutil/trace.go:171","msg":"trace[1051566020] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"198.390444ms","start":"2026-04-28T06:06:38.668568Z","end":"2026-04-28T06:06:38.866959Z","steps":["trace[1051566020] 'process raft request' (duration: 197.994984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:46.408332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.10037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:46.408518Z","caller":"traceutil/trace.go:171","msg":"trace[60370385] linearizableReadLoop","detail":"{readStateIndex:5565; appliedIndex:5563; }","duration":"146.758396ms","start":"2026-04-28T06:06:46.261737Z","end":"2026-04-28T06:06:46.408496Z","steps":["trace[60370385] 'read index received' (duration: 12.556993ms)","trace[60370385] 'applied index is now lower than readState.Index' (duration: 134.200293ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:46.40854Z","caller":"traceutil/trace.go:171","msg":"trace[1885385679] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"149.199275ms","start":"2026-04-28T06:06:46.259325Z","end":"2026-04-28T06:06:46.408524Z","steps":["trace[1885385679] 'process raft request' (duration: 149.117553ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.408671Z","caller":"traceutil/trace.go:171","msg":"trace[593303676] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"149.684836ms","start":"2026-04-28T06:06:46.258926Z","end":"2026-04-28T06:06:46.408611Z","steps":["trace[593303676] 'process raft request' (duration: 15.210246ms)","trace[593303676] 'compare' (duration: 133.966517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:46.409052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.289698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110431"} {"level":"info","ts":"2026-04-28T06:06:46.409241Z","caller":"traceutil/trace.go:171","msg":"trace[890499351] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5360; }","duration":"147.541684ms","start":"2026-04-28T06:06:46.261687Z","end":"2026-04-28T06:06:46.409228Z","steps":["trace[890499351] 'agreement among raft nodes before linearized reading' (duration: 146.865678ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.437837Z","caller":"traceutil/trace.go:171","msg":"trace[268797523] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"157.488552ms","start":"2026-04-28T06:06:46.280321Z","end":"2026-04-28T06:06:46.43781Z","steps":["trace[268797523] 'process raft request' (duration: 157.283378ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.438076Z","caller":"traceutil/trace.go:171","msg":"trace[1956000683] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"156.772815ms","start":"2026-04-28T06:06:46.281273Z","end":"2026-04-28T06:06:46.438046Z","steps":["trace[1956000683] 'process raft request' (duration: 156.492688ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.686678Z","caller":"traceutil/trace.go:171","msg":"trace[1228693729] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"272.081185ms","start":"2026-04-28T06:06:46.414566Z","end":"2026-04-28T06:06:46.686647Z","steps":["trace[1228693729] 'process raft request' (duration: 271.952772ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.686747Z","caller":"traceutil/trace.go:171","msg":"trace[1792744231] linearizableReadLoop","detail":"{readStateIndex:5573; appliedIndex:5565; }","duration":"267.979097ms","start":"2026-04-28T06:06:46.418747Z","end":"2026-04-28T06:06:46.686726Z","steps":["trace[1792744231] 'read index received' (duration: 18.823363ms)","trace[1792744231] 'applied index is now lower than readState.Index' (duration: 249.154904ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:46.686857Z","caller":"traceutil/trace.go:171","msg":"trace[1896181147] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"272.222328ms","start":"2026-04-28T06:06:46.414626Z","end":"2026-04-28T06:06:46.686849Z","steps":["trace[1896181147] 'process raft request' (duration: 271.962732ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.686946Z","caller":"traceutil/trace.go:171","msg":"trace[18252583] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"272.439013ms","start":"2026-04-28T06:06:46.414494Z","end":"2026-04-28T06:06:46.686933Z","steps":["trace[18252583] 'process raft request' (duration: 194.86586ms)","trace[18252583] 'compare' (duration: 76.815105ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:46.687034Z","caller":"traceutil/trace.go:171","msg":"trace[2109483151] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"272.26051ms","start":"2026-04-28T06:06:46.414726Z","end":"2026-04-28T06:06:46.686986Z","steps":["trace[2109483151] 'process raft request' (duration: 271.898471ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.687078Z","caller":"traceutil/trace.go:171","msg":"trace[784330104] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"271.990913ms","start":"2026-04-28T06:06:46.415061Z","end":"2026-04-28T06:06:46.687052Z","steps":["trace[784330104] 'process raft request' (duration: 271.585314ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.687106Z","caller":"traceutil/trace.go:171","msg":"trace[995059514] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"272.441873ms","start":"2026-04-28T06:06:46.414653Z","end":"2026-04-28T06:06:46.687095Z","steps":["trace[995059514] 'process raft request' (duration: 271.954352ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.687231Z","caller":"traceutil/trace.go:171","msg":"trace[1696614202] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"258.179541ms","start":"2026-04-28T06:06:46.42903Z","end":"2026-04-28T06:06:46.687209Z","steps":["trace[1696614202] 'process raft request' (duration: 257.640058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:46.687245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.475959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-450c5f15-2348-412b-9077-c24a44d9626c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-28T06:06:46.687302Z","caller":"traceutil/trace.go:171","msg":"trace[2119792381] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-450c5f15-2348-412b-9077-c24a44d9626c; range_end:; response_count:1; response_revision:5369; }","duration":"268.563161ms","start":"2026-04-28T06:06:46.41872Z","end":"2026-04-28T06:06:46.687283Z","steps":["trace[2119792381] 'agreement among raft nodes before linearized reading' (duration: 268.378366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:46.687356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.018508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4866"} {"level":"info","ts":"2026-04-28T06:06:46.688449Z","caller":"traceutil/trace.go:171","msg":"trace[383606690] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5369; }","duration":"264.113133ms","start":"2026-04-28T06:06:46.424286Z","end":"2026-04-28T06:06:46.688399Z","steps":["trace[383606690] 'agreement among raft nodes before linearized reading' (duration: 262.842223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:46.688788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.501162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2212884"} {"level":"info","ts":"2026-04-28T06:06:46.688859Z","caller":"traceutil/trace.go:171","msg":"trace[2049555160] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5369; }","duration":"215.161318ms","start":"2026-04-28T06:06:46.473677Z","end":"2026-04-28T06:06:46.688838Z","steps":["trace[2049555160] 'agreement among raft nodes before linearized reading' (duration: 213.550509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:47.127902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.372621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:47.128216Z","caller":"traceutil/trace.go:171","msg":"trace[1163540092] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"160.674849ms","start":"2026-04-28T06:06:46.967521Z","end":"2026-04-28T06:06:47.128196Z","steps":["trace[1163540092] 'process raft request' (duration: 160.626748ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:47.128352Z","caller":"traceutil/trace.go:171","msg":"trace[1804816139] linearizableReadLoop","detail":"{readStateIndex:5576; appliedIndex:5574; }","duration":"433.068292ms","start":"2026-04-28T06:06:46.695199Z","end":"2026-04-28T06:06:47.128267Z","steps":["trace[1804816139] 'read index received' (duration: 62.522821ms)","trace[1804816139] 'applied index is now lower than readState.Index' (duration: 370.541639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:47.128568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.93377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:06:47.128648Z","caller":"traceutil/trace.go:171","msg":"trace[1466322045] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:5373; }","duration":"152.068623ms","start":"2026-04-28T06:06:46.976567Z","end":"2026-04-28T06:06:47.128635Z","steps":["trace[1466322045] 'agreement among raft nodes before linearized reading' (duration: 151.952101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:47.128951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.719997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-450c5f15-2348-412b-9077-c24a44d9626c\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-04-28T06:06:47.128994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.62829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:47.129044Z","caller":"traceutil/trace.go:171","msg":"trace[1716775218] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-450c5f15-2348-412b-9077-c24a44d9626c; range_end:; response_count:1; response_revision:5373; }","duration":"433.855331ms","start":"2026-04-28T06:06:46.69517Z","end":"2026-04-28T06:06:47.129025Z","steps":["trace[1716775218] 'agreement among raft nodes before linearized reading' (duration: 433.272497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:47.129093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:46.695114Z","time spent":"433.968603ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-450c5f15-2348-412b-9077-c24a44d9626c\" "} {"level":"info","ts":"2026-04-28T06:06:47.129096Z","caller":"traceutil/trace.go:171","msg":"trace[2089994998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5373; }","duration":"250.798264ms","start":"2026-04-28T06:06:46.878274Z","end":"2026-04-28T06:06:47.129072Z","steps":["trace[2089994998] 'agreement among raft nodes before linearized reading' (duration: 250.316902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:47.129303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.762935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-28T06:06:47.129356Z","caller":"traceutil/trace.go:171","msg":"trace[114948191] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5373; }","duration":"400.814296ms","start":"2026-04-28T06:06:46.728526Z","end":"2026-04-28T06:06:47.12934Z","steps":["trace[114948191] 'agreement among raft nodes before linearized reading' (duration: 399.997257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:47.129403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:46.728463Z","time spent":"400.930899ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1845,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-04-28T06:06:47.128397Z","caller":"traceutil/trace.go:171","msg":"trace[47448155] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"423.632815ms","start":"2026-04-28T06:06:46.704696Z","end":"2026-04-28T06:06:47.128329Z","steps":["trace[47448155] 'process raft request' (duration: 423.356607ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:47.129602Z","caller":"traceutil/trace.go:171","msg":"trace[268213130] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"433.620053ms","start":"2026-04-28T06:06:46.694557Z","end":"2026-04-28T06:06:47.128177Z","steps":["trace[268213130] 'process raft request' (duration: 220.482584ms)","trace[268213130] 'compare' (duration: 211.729236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:47.129889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:46.694533Z","time spent":"435.269184ms","remote":"127.0.0.1:41428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":902,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:06:47.12969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:46.704665Z","time spent":"424.889425ms","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7059,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:08:15.028556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3037} {"level":"info","ts":"2026-04-28T06:08:15.113277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3037,"took":"83.065476ms","hash":2874680084,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T06:08:15.113354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2874680084,"revision":3037,"compact-revision":-1} {"level":"info","ts":"2026-04-28T06:13:05.314974Z","caller":"traceutil/trace.go:171","msg":"trace[1885771881] linearizableReadLoop","detail":"{readStateIndex:8581; appliedIndex:8580; }","duration":"276.423933ms","start":"2026-04-28T06:13:05.038519Z","end":"2026-04-28T06:13:05.314943Z","steps":["trace[1885771881] 'read index received' (duration: 276.27385ms)","trace[1885771881] 'applied index is now lower than readState.Index' (duration: 149.013µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:13:05.315042Z","caller":"traceutil/trace.go:171","msg":"trace[763110422] transaction","detail":"{read_only:false; response_revision:8282; number_of_response:1; }","duration":"276.960616ms","start":"2026-04-28T06:13:05.038043Z","end":"2026-04-28T06:13:05.315004Z","steps":["trace[763110422] 'process raft request' (duration: 276.73113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:05.315171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.77834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:13:05.315218Z","caller":"traceutil/trace.go:171","msg":"trace[1203325229] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8282; }","duration":"152.852731ms","start":"2026-04-28T06:13:05.162354Z","end":"2026-04-28T06:13:05.315207Z","steps":["trace[1203325229] 'agreement among raft nodes before linearized reading' (duration: 152.747819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:05.315266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.72563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ec645984-603a-4c1b-8130-ec00bf378d2c\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-04-28T06:13:05.315348Z","caller":"traceutil/trace.go:171","msg":"trace[929780215] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ec645984-603a-4c1b-8130-ec00bf378d2c; range_end:; response_count:1; response_revision:8282; }","duration":"276.833012ms","start":"2026-04-28T06:13:05.038497Z","end":"2026-04-28T06:13:05.31533Z","steps":["trace[929780215] 'agreement among raft nodes before linearized reading' (duration: 276.597417ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:05.450771Z","caller":"traceutil/trace.go:171","msg":"trace[156463770] transaction","detail":"{read_only:false; response_revision:8283; number_of_response:1; }","duration":"130.763593ms","start":"2026-04-28T06:13:05.319988Z","end":"2026-04-28T06:13:05.450752Z","steps":["trace[156463770] 'process raft request' (duration: 130.64334ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:15.035506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6165} {"level":"info","ts":"2026-04-28T06:13:15.134039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6165,"took":"95.965402ms","hash":999309075,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":23154688,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-28T06:13:15.134102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":999309075,"revision":6165,"compact-revision":3037} {"level":"info","ts":"2026-04-28T06:13:18.332031Z","caller":"traceutil/trace.go:171","msg":"trace[1065965237] linearizableReadLoop","detail":"{readStateIndex:8652; appliedIndex:8651; }","duration":"275.323906ms","start":"2026-04-28T06:13:18.056684Z","end":"2026-04-28T06:13:18.332008Z","steps":["trace[1065965237] 'read index received' (duration: 274.830565ms)","trace[1065965237] 'applied index is now lower than readState.Index' (duration: 492.491µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:13:18.332201Z","caller":"traceutil/trace.go:171","msg":"trace[1151699373] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"485.281621ms","start":"2026-04-28T06:13:17.846905Z","end":"2026-04-28T06:13:18.332186Z","steps":["trace[1151699373] 'process raft request' (duration: 484.673506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:18.332569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.921313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-28T06:13:18.332645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.933571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-99kjr\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-04-28T06:13:18.332762Z","caller":"traceutil/trace.go:171","msg":"trace[1347654045] range","detail":"{range_begin:/registry/pods/openstack/keepalived-99kjr; range_end:; response_count:1; response_revision:8350; }","duration":"276.101475ms","start":"2026-04-28T06:13:18.056632Z","end":"2026-04-28T06:13:18.332734Z","steps":["trace[1347654045] 'agreement among raft nodes before linearized reading' (duration: 275.782258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:18.332418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:13:17.846878Z","time spent":"485.440006ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:13:18.332787Z","caller":"traceutil/trace.go:171","msg":"trace[1234596534] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:8350; }","duration":"189.116588ms","start":"2026-04-28T06:13:18.143583Z","end":"2026-04-28T06:13:18.3327Z","steps":["trace[1234596534] 'agreement among raft nodes before linearized reading' (duration: 188.80656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:19.767969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.714694ms","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-04-28T06:13:19.768056Z","caller":"traceutil/trace.go:171","msg":"trace[1970465145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8356; }","duration":"164.901558ms","start":"2026-04-28T06:13:19.603134Z","end":"2026-04-28T06:13:19.768035Z","steps":["trace[1970465145] 'range keys from in-memory index tree' (duration: 164.516139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:19.768102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.799812ms","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-04-28T06:13:19.768106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.017818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T06:13:19.768182Z","caller":"traceutil/trace.go:171","msg":"trace[2145466336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8356; }","duration":"150.940765ms","start":"2026-04-28T06:13:19.617224Z","end":"2026-04-28T06:13:19.768165Z","steps":["trace[2145466336] 'range keys from in-memory index tree' (duration: 150.602108ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:19.768209Z","caller":"traceutil/trace.go:171","msg":"trace[721106379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8356; }","duration":"249.156871ms","start":"2026-04-28T06:13:19.519029Z","end":"2026-04-28T06:13:19.768186Z","steps":["trace[721106379] 'range keys from in-memory index tree' (duration: 248.806233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:19.768268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.090374ms","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-04-28T06:13:19.768373Z","caller":"traceutil/trace.go:171","msg":"trace[1644216030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8356; }","duration":"150.229408ms","start":"2026-04-28T06:13:19.618118Z","end":"2026-04-28T06:13:19.768348Z","steps":["trace[1644216030] 'range keys from in-memory index tree' (duration: 149.89413ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:46.580509Z","caller":"traceutil/trace.go:171","msg":"trace[540116950] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"138.527818ms","start":"2026-04-28T06:13:46.441952Z","end":"2026-04-28T06:13:46.58048Z","steps":["trace[540116950] 'process raft request' (duration: 137.879223ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:59.467653Z","caller":"traceutil/trace.go:171","msg":"trace[441667272] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"171.229761ms","start":"2026-04-28T06:13:59.296391Z","end":"2026-04-28T06:13:59.467621Z","steps":["trace[441667272] 'process raft request' (duration: 171.052935ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:14:31.953086Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002465.wal"} {"level":"info","ts":"2026-04-28T06:15:12.609194Z","caller":"traceutil/trace.go:171","msg":"trace[1882066055] linearizableReadLoop","detail":"{readStateIndex:9733; appliedIndex:9732; }","duration":"166.556791ms","start":"2026-04-28T06:15:12.442608Z","end":"2026-04-28T06:15:12.609165Z","steps":["trace[1882066055] 'read index received' (duration: 166.287564ms)","trace[1882066055] 'applied index is now lower than readState.Index' (duration: 268.277µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:15:12.609254Z","caller":"traceutil/trace.go:171","msg":"trace[1081405820] transaction","detail":"{read_only:false; response_revision:9396; number_of_response:1; }","duration":"233.319146ms","start":"2026-04-28T06:15:12.375896Z","end":"2026-04-28T06:15:12.609215Z","steps":["trace[1081405820] 'process raft request' (duration: 233.030999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:12.609499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.377897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-28T06:15:12.609511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.446116ms","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-04-28T06:15:12.609551Z","caller":"traceutil/trace.go:171","msg":"trace[1955607217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9396; }","duration":"161.486ms","start":"2026-04-28T06:15:12.448051Z","end":"2026-04-28T06:15:12.609537Z","steps":["trace[1955607217] 'agreement among raft nodes before linearized reading' (duration: 161.280295ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:12.609567Z","caller":"traceutil/trace.go:171","msg":"trace[943949184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9396; }","duration":"109.528377ms","start":"2026-04-28T06:15:12.500024Z","end":"2026-04-28T06:15:12.609553Z","steps":["trace[943949184] 'agreement among raft nodes before linearized reading' (duration: 109.370343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:12.609705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.130804ms","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-04-28T06:15:12.609779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.158105ms","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-04-28T06:15:12.609818Z","caller":"traceutil/trace.go:171","msg":"trace[390052431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9396; }","duration":"113.294997ms","start":"2026-04-28T06:15:12.496496Z","end":"2026-04-28T06:15:12.609791Z","steps":["trace[390052431] 'agreement among raft nodes before linearized reading' (duration: 112.954369ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:12.609863Z","caller":"traceutil/trace.go:171","msg":"trace[1064698224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9396; }","duration":"167.291838ms","start":"2026-04-28T06:15:12.442554Z","end":"2026-04-28T06:15:12.609846Z","steps":["trace[1064698224] 'agreement among raft nodes before linearized reading' (duration: 166.754795ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:12.806658Z","caller":"traceutil/trace.go:171","msg":"trace[1451424153] transaction","detail":"{read_only:false; response_revision:9398; number_of_response:1; }","duration":"192.220353ms","start":"2026-04-28T06:15:12.614409Z","end":"2026-04-28T06:15:12.806629Z","steps":["trace[1451424153] 'process raft request' (duration: 191.994908ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:12.806867Z","caller":"traceutil/trace.go:171","msg":"trace[203836047] transaction","detail":"{read_only:false; response_revision:9399; number_of_response:1; }","duration":"192.285314ms","start":"2026-04-28T06:15:12.614558Z","end":"2026-04-28T06:15:12.806843Z","steps":["trace[203836047] 'process raft request' (duration: 192.027768ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:12.807281Z","caller":"traceutil/trace.go:171","msg":"trace[1587680312] transaction","detail":"{read_only:false; response_revision:9400; number_of_response:1; }","duration":"192.309935ms","start":"2026-04-28T06:15:12.614947Z","end":"2026-04-28T06:15:12.807257Z","steps":["trace[1587680312] 'process raft request' (duration: 191.823753ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:47.113384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T06:15:47.117666Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-28T06:15:47.117767Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-28T06:18:15.044845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8334} {"level":"info","ts":"2026-04-28T06:18:15.123615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8334,"took":"76.525328ms","hash":382230307,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":25321472,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-28T06:18:15.123682Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":382230307,"revision":8334,"compact-revision":6165} {"level":"info","ts":"2026-04-28T06:23:15.053448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10827} {"level":"info","ts":"2026-04-28T06:23:15.130702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10827,"took":"74.565481ms","hash":3839412049,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":27824128,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-28T06:23:15.130846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3839412049,"revision":10827,"compact-revision":8334} {"level":"info","ts":"2026-04-28T06:24:45.771707Z","caller":"traceutil/trace.go:171","msg":"trace[1962782642] transaction","detail":"{read_only:false; response_revision:13637; number_of_response:1; }","duration":"101.126669ms","start":"2026-04-28T06:24:45.670548Z","end":"2026-04-28T06:24:45.771675Z","steps":["trace[1962782642] 'process raft request' (duration: 100.903744ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:25:04.991706Z","caller":"traceutil/trace.go:171","msg":"trace[1135369885] linearizableReadLoop","detail":"{readStateIndex:14216; appliedIndex:14215; }","duration":"115.03287ms","start":"2026-04-28T06:25:04.876651Z","end":"2026-04-28T06:25:04.991684Z","steps":["trace[1135369885] 'read index received' (duration: 114.861766ms)","trace[1135369885] 'applied index is now lower than readState.Index' (duration: 170.294µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:25:04.991861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.206064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:25:04.991884Z","caller":"traceutil/trace.go:171","msg":"trace[1529949022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13736; }","duration":"115.286106ms","start":"2026-04-28T06:25:04.876591Z","end":"2026-04-28T06:25:04.991877Z","steps":["trace[1529949022] 'agreement among raft nodes before linearized reading' (duration: 115.183463ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:25:04.992171Z","caller":"traceutil/trace.go:171","msg":"trace[992678068] transaction","detail":"{read_only:false; response_revision:13736; number_of_response:1; }","duration":"151.248653ms","start":"2026-04-28T06:25:04.840914Z","end":"2026-04-28T06:25:04.992163Z","steps":["trace[992678068] 'process raft request' (duration: 150.65636ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:25:20.054367Z","caller":"traceutil/trace.go:171","msg":"trace[1910236488] transaction","detail":"{read_only:false; response_revision:13828; number_of_response:1; }","duration":"114.351474ms","start":"2026-04-28T06:25:19.939982Z","end":"2026-04-28T06:25:20.054333Z","steps":["trace[1910236488] 'process raft request' (duration: 92.801781ms)","trace[1910236488] 'compare' (duration: 21.42376ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:25:20.069149Z","caller":"traceutil/trace.go:171","msg":"trace[180583136] transaction","detail":"{read_only:false; response_revision:13830; number_of_response:1; }","duration":"126.848511ms","start":"2026-04-28T06:25:19.942269Z","end":"2026-04-28T06:25:20.069118Z","steps":["trace[180583136] 'process raft request' (duration: 126.80512ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:25:20.069204Z","caller":"traceutil/trace.go:171","msg":"trace[1057170038] transaction","detail":"{read_only:false; response_revision:13829; number_of_response:1; }","duration":"126.995375ms","start":"2026-04-28T06:25:19.942181Z","end":"2026-04-28T06:25:20.069176Z","steps":["trace[1057170038] 'process raft request' (duration: 126.757049ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:25:20.796279Z","caller":"traceutil/trace.go:171","msg":"trace[910116464] transaction","detail":"{read_only:false; response_revision:13831; number_of_response:1; }","duration":"125.946661ms","start":"2026-04-28T06:25:20.670314Z","end":"2026-04-28T06:25:20.79626Z","steps":["trace[910116464] 'process raft request' (duration: 125.840768ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:25:30.629279Z","caller":"traceutil/trace.go:171","msg":"trace[142173676] transaction","detail":"{read_only:false; response_revision:14002; number_of_response:1; }","duration":"196.897591ms","start":"2026-04-28T06:25:30.432358Z","end":"2026-04-28T06:25:30.629256Z","steps":["trace[142173676] 'process raft request' (duration: 196.732427ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:27:08.834321Z","caller":"traceutil/trace.go:171","msg":"trace[1617708780] linearizableReadLoop","detail":"{readStateIndex:15600; appliedIndex:15598; }","duration":"189.011794ms","start":"2026-04-28T06:27:08.645279Z","end":"2026-04-28T06:27:08.834291Z","steps":["trace[1617708780] 'read index received' (duration: 63.35199ms)","trace[1617708780] 'applied index is now lower than readState.Index' (duration: 125.658814ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:27:08.834438Z","caller":"traceutil/trace.go:171","msg":"trace[741892900] transaction","detail":"{read_only:false; response_revision:15079; number_of_response:1; }","duration":"191.366ms","start":"2026-04-28T06:27:08.643005Z","end":"2026-04-28T06:27:08.834371Z","steps":["trace[741892900] 'process raft request' (duration: 168.401463ms)","trace[741892900] 'compare' (duration: 22.734441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:27:08.834814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.215586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-99kjr\" ","response":"range_response_count:1 size:6441"} {"level":"warn","ts":"2026-04-28T06:27:08.834854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.520215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T06:27:08.834949Z","caller":"traceutil/trace.go:171","msg":"trace[2008811619] range","detail":"{range_begin:/registry/pods/openstack/keepalived-99kjr; range_end:; response_count:1; response_revision:15079; }","duration":"160.35392ms","start":"2026-04-28T06:27:08.674533Z","end":"2026-04-28T06:27:08.834887Z","steps":["trace[2008811619] 'agreement among raft nodes before linearized reading' (duration: 160.050333ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:27:08.835058Z","caller":"traceutil/trace.go:171","msg":"trace[1543151541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15079; }","duration":"189.655999ms","start":"2026-04-28T06:27:08.645238Z","end":"2026-04-28T06:27:08.834894Z","steps":["trace[1543151541] 'agreement among raft nodes before linearized reading' (duration: 189.227179ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:28:15.060165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13026} {"level":"info","ts":"2026-04-28T06:28:15.167543Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13026,"took":"102.643476ms","hash":23574298,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":30941184,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T06:28:15.167784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":23574298,"revision":13026,"compact-revision":10827} {"level":"info","ts":"2026-04-28T06:32:53.415072Z","caller":"traceutil/trace.go:171","msg":"trace[862597959] transaction","detail":"{read_only:false; response_revision:17725; number_of_response:1; }","duration":"191.594394ms","start":"2026-04-28T06:32:53.22345Z","end":"2026-04-28T06:32:53.415044Z","steps":["trace[862597959] 'process raft request' (duration: 177.605089ms)","trace[862597959] 'compare' (duration: 13.689467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:32:54.157223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.649857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:32:54.157312Z","caller":"traceutil/trace.go:171","msg":"trace[95469101] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:17726; }","duration":"296.811281ms","start":"2026-04-28T06:32:53.860483Z","end":"2026-04-28T06:32:54.157294Z","steps":["trace[95469101] 'count revisions from in-memory index tree' (duration: 296.519483ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:33:15.066789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15515} {"level":"info","ts":"2026-04-28T06:33:15.16179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15515,"took":"91.914519ms","hash":144078646,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":32960512,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-28T06:33:15.16186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":144078646,"revision":15515,"compact-revision":13026} {"level":"info","ts":"2026-04-28T06:36:10.749443Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T06:36:10.756494Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-28T06:36:10.756567Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-28T06:38:15.072454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18099} {"level":"info","ts":"2026-04-28T06:38:15.191638Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18099,"took":"116.07475ms","hash":1673641858,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":30257152,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-28T06:38:15.191719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1673641858,"revision":18099,"compact-revision":15515} {"level":"info","ts":"2026-04-28T06:38:19.77335Z","caller":"traceutil/trace.go:171","msg":"trace[1293105103] transaction","detail":"{read_only:false; response_revision:20207; number_of_response:1; }","duration":"146.721255ms","start":"2026-04-28T06:38:19.626601Z","end":"2026-04-28T06:38:19.773323Z","steps":["trace[1293105103] 'process raft request' (duration: 146.50385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:39:16.340816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.783792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T06:39:16.341299Z","caller":"traceutil/trace.go:171","msg":"trace[636490553] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20496; }","duration":"146.286294ms","start":"2026-04-28T06:39:16.194991Z","end":"2026-04-28T06:39:16.341277Z","steps":["trace[636490553] 'range keys from in-memory index tree' (duration: 145.578668ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:39:21.783734Z","caller":"traceutil/trace.go:171","msg":"trace[280810516] transaction","detail":"{read_only:false; response_revision:20523; number_of_response:1; }","duration":"137.263798ms","start":"2026-04-28T06:39:21.646441Z","end":"2026-04-28T06:39:21.783705Z","steps":["trace[280810516] 'process raft request' (duration: 137.087084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:39:22.121857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.541785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T06:39:22.1219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.673685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:39:22.121952Z","caller":"traceutil/trace.go:171","msg":"trace[1037337180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20523; }","duration":"244.682399ms","start":"2026-04-28T06:39:21.877248Z","end":"2026-04-28T06:39:22.12193Z","steps":["trace[1037337180] 'range keys from in-memory index tree' (duration: 244.443902ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:39:22.121982Z","caller":"traceutil/trace.go:171","msg":"trace[1505665815] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:20523; }","duration":"156.811988ms","start":"2026-04-28T06:39:21.96515Z","end":"2026-04-28T06:39:22.121962Z","steps":["trace[1505665815] 'count revisions from in-memory index tree' (duration: 156.579032ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:39:22.122074Z","caller":"traceutil/trace.go:171","msg":"trace[1963667395] transaction","detail":"{read_only:false; response_revision:20524; number_of_response:1; }","duration":"143.337514ms","start":"2026-04-28T06:39:21.978687Z","end":"2026-04-28T06:39:22.122025Z","steps":["trace[1963667395] 'process raft request' (duration: 122.675637ms)","trace[1963667395] 'compare' (duration: 20.168815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:39:22.678751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.487523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:39:22.678966Z","caller":"traceutil/trace.go:171","msg":"trace[1939573027] linearizableReadLoop","detail":"{readStateIndex:21232; appliedIndex:21230; }","duration":"301.991114ms","start":"2026-04-28T06:39:22.376954Z","end":"2026-04-28T06:39:22.678945Z","steps":["trace[1939573027] 'read index received' (duration: 159.376698ms)","trace[1939573027] 'applied index is now lower than readState.Index' (duration: 142.613196ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:39:22.678969Z","caller":"traceutil/trace.go:171","msg":"trace[232011442] transaction","detail":"{read_only:false; response_revision:20526; number_of_response:1; }","duration":"481.150419ms","start":"2026-04-28T06:39:22.197787Z","end":"2026-04-28T06:39:22.678937Z","steps":["trace[232011442] 'process raft request' (duration: 338.370149ms)","trace[232011442] 'compare' (duration: 142.35005ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:39:22.679001Z","caller":"traceutil/trace.go:171","msg":"trace[824374712] transaction","detail":"{read_only:false; response_revision:20527; number_of_response:1; }","duration":"479.313845ms","start":"2026-04-28T06:39:22.199655Z","end":"2026-04-28T06:39:22.678969Z","steps":["trace[824374712] 'process raft request' (duration: 479.201192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:39:22.679146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:39:22.199627Z","time spent":"479.437868ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:39:22.679141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:39:22.197758Z","time spent":"481.286202ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:39:22.679218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.983538ms","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-04-28T06:39:22.679261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.280752ms","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-04-28T06:39:22.679347Z","caller":"traceutil/trace.go:171","msg":"trace[2048713662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20527; }","duration":"288.111912ms","start":"2026-04-28T06:39:22.391217Z","end":"2026-04-28T06:39:22.679329Z","steps":["trace[2048713662] 'agreement among raft nodes before linearized reading' (duration: 287.859225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:39:22.679254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.821726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T06:39:22.679477Z","caller":"traceutil/trace.go:171","msg":"trace[64243944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20527; }","duration":"291.059333ms","start":"2026-04-28T06:39:22.388384Z","end":"2026-04-28T06:39:22.679443Z","steps":["trace[64243944] 'agreement among raft nodes before linearized reading' (duration: 290.690743ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:39:22.679391Z","caller":"traceutil/trace.go:171","msg":"trace[859896854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20527; }","duration":"302.465997ms","start":"2026-04-28T06:39:22.376889Z","end":"2026-04-28T06:39:22.679355Z","steps":["trace[859896854] 'agreement among raft nodes before linearized reading' (duration: 302.115018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:39:22.679592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:39:22.376861Z","time spent":"302.715472ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:39:23.070822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.644279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:39:23.070993Z","caller":"traceutil/trace.go:171","msg":"trace[844510687] transaction","detail":"{read_only:false; response_revision:20528; number_of_response:1; }","duration":"387.933169ms","start":"2026-04-28T06:39:22.683042Z","end":"2026-04-28T06:39:23.070975Z","steps":["trace[844510687] 'process raft request' (duration: 155.054535ms)","trace[844510687] 'compare' (duration: 232.507716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:39:23.071055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:39:22.683024Z","time spent":"388.002541ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:39:23.071263Z","caller":"traceutil/trace.go:171","msg":"trace[1017749387] transaction","detail":"{read_only:false; response_revision:20529; number_of_response:1; }","duration":"388.130054ms","start":"2026-04-28T06:39:22.683109Z","end":"2026-04-28T06:39:23.071239Z","steps":["trace[1017749387] 'process raft request' (duration: 387.809716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:39:23.07139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:39:22.683081Z","time spent":"388.237157ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:39:23.071461Z","caller":"traceutil/trace.go:171","msg":"trace[232393951] transaction","detail":"{read_only:false; response_revision:20530; number_of_response:1; }","duration":"387.790826ms","start":"2026-04-28T06:39:22.683636Z","end":"2026-04-28T06:39:23.071427Z","steps":["trace[232393951] 'process raft request' (duration: 387.54444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:39:23.071638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:39:22.683601Z","time spent":"387.94158ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:12.394771Z","caller":"traceutil/trace.go:171","msg":"trace[1472461502] linearizableReadLoop","detail":"{readStateIndex:23696; appliedIndex:23695; }","duration":"155.440011ms","start":"2026-04-28T06:43:12.23928Z","end":"2026-04-28T06:43:12.39472Z","steps":["trace[1472461502] 'read index received' (duration: 154.578661ms)","trace[1472461502] 'applied index is now lower than readState.Index' (duration: 859.88µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:12.394959Z","caller":"traceutil/trace.go:171","msg":"trace[684809597] transaction","detail":"{read_only:false; response_revision:22920; number_of_response:1; }","duration":"182.364027ms","start":"2026-04-28T06:43:12.21255Z","end":"2026-04-28T06:43:12.394914Z","steps":["trace[684809597] 'process raft request' (duration: 181.389964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:12.395404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.100908ms","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-04-28T06:43:12.395476Z","caller":"traceutil/trace.go:171","msg":"trace[1165719128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22920; }","duration":"156.228241ms","start":"2026-04-28T06:43:12.239232Z","end":"2026-04-28T06:43:12.39546Z","steps":["trace[1165719128] 'agreement among raft nodes before linearized reading' (duration: 155.674367ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:15.079868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20184} {"level":"info","ts":"2026-04-28T06:43:15.184731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20184,"took":"100.799423ms","hash":1739014961,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":39489536,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-04-28T06:43:15.184807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1739014961,"revision":20184,"compact-revision":18099} {"level":"info","ts":"2026-04-28T06:46:30.503669Z","caller":"traceutil/trace.go:171","msg":"trace[731900631] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"120.111676ms","start":"2026-04-28T06:46:30.383526Z","end":"2026-04-28T06:46:30.503638Z","steps":["trace[731900631] 'process raft request' (duration: 119.765398ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:30.672266Z","caller":"traceutil/trace.go:171","msg":"trace[1019622343] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"286.701364ms","start":"2026-04-28T06:46:30.38553Z","end":"2026-04-28T06:46:30.672231Z","steps":["trace[1019622343] 'process raft request' (duration: 286.362796ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:30.67268Z","caller":"traceutil/trace.go:171","msg":"trace[867520075] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"254.809871ms","start":"2026-04-28T06:46:30.417855Z","end":"2026-04-28T06:46:30.672665Z","steps":["trace[867520075] 'process raft request' (duration: 254.284059ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:31.168955Z","caller":"traceutil/trace.go:171","msg":"trace[1028182107] linearizableReadLoop","detail":"{readStateIndex:25807; appliedIndex:25806; }","duration":"119.346747ms","start":"2026-04-28T06:46:31.049581Z","end":"2026-04-28T06:46:31.168928Z","steps":["trace[1028182107] 'read index received' (duration: 119.006849ms)","trace[1028182107] 'applied index is now lower than readState.Index' (duration: 338.948µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:31.169226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.626763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:31.169262Z","caller":"traceutil/trace.go:171","msg":"trace[67808157] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:24980; }","duration":"119.717146ms","start":"2026-04-28T06:46:31.049534Z","end":"2026-04-28T06:46:31.169251Z","steps":["trace[67808157] 'agreement among raft nodes before linearized reading' (duration: 119.604143ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:31.169536Z","caller":"traceutil/trace.go:171","msg":"trace[438664070] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"121.704524ms","start":"2026-04-28T06:46:31.047823Z","end":"2026-04-28T06:46:31.169527Z","steps":["trace[438664070] 'process raft request' (duration: 120.852973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:31.449525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.943379ms","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-04-28T06:46:31.449603Z","caller":"traceutil/trace.go:171","msg":"trace[1519208570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24980; }","duration":"221.066963ms","start":"2026-04-28T06:46:31.22852Z","end":"2026-04-28T06:46:31.449587Z","steps":["trace[1519208570] 'range keys from in-memory index tree' (duration: 220.688613ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:31.767641Z","caller":"traceutil/trace.go:171","msg":"trace[507141674] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"312.283567ms","start":"2026-04-28T06:46:31.455323Z","end":"2026-04-28T06:46:31.767607Z","steps":["trace[507141674] 'process raft request' (duration: 215.209642ms)","trace[507141674] 'compare' (duration: 96.514412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:31.767888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:31.455301Z","time spent":"312.46016ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:31.767984Z","caller":"traceutil/trace.go:171","msg":"trace[154559435] linearizableReadLoop","detail":"{readStateIndex:25811; appliedIndex:25807; }","duration":"270.65366ms","start":"2026-04-28T06:46:31.497265Z","end":"2026-04-28T06:46:31.767919Z","steps":["trace[154559435] 'read index received' (duration: 7.12697ms)","trace[154559435] 'applied index is now lower than readState.Index' (duration: 263.52525ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:31.768041Z","caller":"traceutil/trace.go:171","msg":"trace[226696573] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"312.147563ms","start":"2026-04-28T06:46:31.455857Z","end":"2026-04-28T06:46:31.768004Z","steps":["trace[226696573] 'process raft request' (duration: 311.949918ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:31.768042Z","caller":"traceutil/trace.go:171","msg":"trace[2085202063] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"312.41952ms","start":"2026-04-28T06:46:31.455573Z","end":"2026-04-28T06:46:31.767993Z","steps":["trace[2085202063] 'process raft request' (duration: 311.957369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:31.768247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:31.455839Z","time spent":"312.310758ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:31.768239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.941037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T06:46:31.768285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:31.455564Z","time spent":"312.614834ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:31.768346Z","caller":"traceutil/trace.go:171","msg":"trace[137886558] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:24984; }","duration":"271.06939ms","start":"2026-04-28T06:46:31.497258Z","end":"2026-04-28T06:46:31.768328Z","steps":["trace[137886558] 'agreement among raft nodes before linearized reading' (duration: 270.903096ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:31.769682Z","caller":"traceutil/trace.go:171","msg":"trace[1095995318] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"125.2694ms","start":"2026-04-28T06:46:31.644387Z","end":"2026-04-28T06:46:31.769656Z","steps":["trace[1095995318] 'process raft request' (duration: 125.212449ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:31.769924Z","caller":"traceutil/trace.go:171","msg":"trace[87102117] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"226.850422ms","start":"2026-04-28T06:46:31.543055Z","end":"2026-04-28T06:46:31.769905Z","steps":["trace[87102117] 'process raft request' (duration: 226.151515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:31.770345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.732178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-04-28T06:46:31.770431Z","caller":"traceutil/trace.go:171","msg":"trace[433850423] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24988; }","duration":"218.85481ms","start":"2026-04-28T06:46:31.551557Z","end":"2026-04-28T06:46:31.770412Z","steps":["trace[433850423] 'agreement among raft nodes before linearized reading' (duration: 218.526562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:32.058324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.549095ms","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-04-28T06:46:32.058414Z","caller":"traceutil/trace.go:171","msg":"trace[148969636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24989; }","duration":"117.707008ms","start":"2026-04-28T06:46:31.940686Z","end":"2026-04-28T06:46:32.058393Z","steps":["trace[148969636] 'range keys from in-memory index tree' (duration: 117.284208ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:32.219659Z","caller":"traceutil/trace.go:171","msg":"trace[367174220] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"174.856616ms","start":"2026-04-28T06:46:32.044776Z","end":"2026-04-28T06:46:32.219633Z","steps":["trace[367174220] 'process raft request' (duration: 174.679762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:32.468403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.883403ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:32.468532Z","caller":"traceutil/trace.go:171","msg":"trace[527902419] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24990; }","duration":"194.030226ms","start":"2026-04-28T06:46:32.274483Z","end":"2026-04-28T06:46:32.468513Z","steps":["trace[527902419] 'range keys from in-memory index tree' (duration: 193.861161ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:32.468864Z","caller":"traceutil/trace.go:171","msg":"trace[1754917590] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"406.381601ms","start":"2026-04-28T06:46:32.062452Z","end":"2026-04-28T06:46:32.468834Z","steps":["trace[1754917590] 'process raft request' (duration: 332.557213ms)","trace[1754917590] 'compare' (duration: 73.50401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:32.468984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:32.06243Z","time spent":"406.491413ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:32.471808Z","caller":"traceutil/trace.go:171","msg":"trace[597242486] linearizableReadLoop","detail":"{readStateIndex:25819; appliedIndex:25817; }","duration":"237.67473ms","start":"2026-04-28T06:46:32.234114Z","end":"2026-04-28T06:46:32.471788Z","steps":["trace[597242486] 'read index received' (duration: 160.847281ms)","trace[597242486] 'applied index is now lower than readState.Index' (duration: 76.826649ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:32.472146Z","caller":"traceutil/trace.go:171","msg":"trace[1764857725] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"334.399278ms","start":"2026-04-28T06:46:32.137719Z","end":"2026-04-28T06:46:32.472119Z","steps":["trace[1764857725] 'process raft request' (duration: 330.949325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:32.472267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:32.137693Z","time spent":"334.50977ms","remote":"127.0.0.1:46224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:32.472849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.701205ms","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-04-28T06:46:32.472901Z","caller":"traceutil/trace.go:171","msg":"trace[451245218] 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:24992; }","duration":"238.808328ms","start":"2026-04-28T06:46:32.234078Z","end":"2026-04-28T06:46:32.472886Z","steps":["trace[451245218] 'agreement among raft nodes before linearized reading' (duration: 237.972947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:32.75407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.553466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:46:32.754266Z","caller":"traceutil/trace.go:171","msg":"trace[1567836349] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"245.537848ms","start":"2026-04-28T06:46:32.508692Z","end":"2026-04-28T06:46:32.75423Z","steps":["trace[1567836349] 'process raft request' (duration: 101.766286ms)","trace[1567836349] 'compare' (duration: 143.29613ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:33.006378Z","caller":"traceutil/trace.go:171","msg":"trace[928820305] linearizableReadLoop","detail":"{readStateIndex:25822; appliedIndex:25821; }","duration":"254.894523ms","start":"2026-04-28T06:46:32.751457Z","end":"2026-04-28T06:46:33.006352Z","steps":["trace[928820305] 'read index received' (duration: 168.877954ms)","trace[928820305] 'applied index is now lower than readState.Index' (duration: 86.013059ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:33.006685Z","caller":"traceutil/trace.go:171","msg":"trace[1554397444] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"328.248309ms","start":"2026-04-28T06:46:32.67842Z","end":"2026-04-28T06:46:33.006668Z","steps":["trace[1554397444] 'process raft request' (duration: 241.934123ms)","trace[1554397444] 'compare' (duration: 85.64305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:33.006808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:32.678394Z","time spent":"328.334471ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:33.006975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.93621ms","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:8"} {"level":"warn","ts":"2026-04-28T06:46:33.006992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.514578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18745"} {"level":"info","ts":"2026-04-28T06:46:33.0071Z","caller":"traceutil/trace.go:171","msg":"trace[1994672365] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24995; }","duration":"254.070843ms","start":"2026-04-28T06:46:32.752977Z","end":"2026-04-28T06:46:33.007048Z","steps":["trace[1994672365] 'agreement among raft nodes before linearized reading' (duration: 253.873038ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:33.007128Z","caller":"traceutil/trace.go:171","msg":"trace[690199319] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24995; }","duration":"255.629821ms","start":"2026-04-28T06:46:32.751422Z","end":"2026-04-28T06:46:33.007051Z","steps":["trace[690199319] 'agreement among raft nodes before linearized reading' (duration: 255.406485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:33.006991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.436595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:33.00729Z","caller":"traceutil/trace.go:171","msg":"trace[259522391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24995; }","duration":"128.819715ms","start":"2026-04-28T06:46:32.878439Z","end":"2026-04-28T06:46:33.007259Z","steps":["trace[259522391] 'agreement among raft nodes before linearized reading' (duration: 128.423904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:33.006977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.498581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:33.007397Z","caller":"traceutil/trace.go:171","msg":"trace[2127133833] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24995; }","duration":"147.984094ms","start":"2026-04-28T06:46:32.8594Z","end":"2026-04-28T06:46:33.007384Z","steps":["trace[2127133833] 'agreement among raft nodes before linearized reading' (duration: 147.474181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:33.547771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.536427ms","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-04-28T06:46:33.547841Z","caller":"traceutil/trace.go:171","msg":"trace[809749341] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24996; }","duration":"176.657921ms","start":"2026-04-28T06:46:33.371166Z","end":"2026-04-28T06:46:33.547824Z","steps":["trace[809749341] 'range keys from in-memory index tree' (duration: 176.358003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:33.804423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.101811ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-28T06:46:33.804522Z","caller":"traceutil/trace.go:171","msg":"trace[1367917201] linearizableReadLoop","detail":"{readStateIndex:25824; appliedIndex:25823; }","duration":"293.57558ms","start":"2026-04-28T06:46:33.510929Z","end":"2026-04-28T06:46:33.804504Z","steps":["trace[1367917201] 'read index received' (duration: 50.605242ms)","trace[1367917201] 'applied index is now lower than readState.Index' (duration: 242.968908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:33.804859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.929978ms","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-04-28T06:46:33.804905Z","caller":"traceutil/trace.go:171","msg":"trace[1128896825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24996; }","duration":"294.02ms","start":"2026-04-28T06:46:33.510871Z","end":"2026-04-28T06:46:33.804891Z","steps":["trace[1128896825] 'agreement among raft nodes before linearized reading' (duration: 293.868156ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:33.815227Z","caller":"traceutil/trace.go:171","msg":"trace[1428939130] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"191.865365ms","start":"2026-04-28T06:46:33.623352Z","end":"2026-04-28T06:46:33.815217Z","steps":["trace[1428939130] 'process raft request' (duration: 191.781773ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:34.072648Z","caller":"traceutil/trace.go:171","msg":"trace[1220581177] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25824; }","duration":"268.080329ms","start":"2026-04-28T06:46:33.804547Z","end":"2026-04-28T06:46:34.072627Z","steps":["trace[1220581177] 'read index received' (duration: 10.628545ms)","trace[1220581177] 'applied index is now lower than readState.Index' (duration: 257.450764ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:34.072886Z","caller":"traceutil/trace.go:171","msg":"trace[1186753665] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"296.790076ms","start":"2026-04-28T06:46:33.776083Z","end":"2026-04-28T06:46:34.072873Z","steps":["trace[1186753665] 'process raft request' (duration: 269.135474ms)","trace[1186753665] 'compare' (duration: 26.875343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:34.073047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.490514ms","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-04-28T06:46:34.073102Z","caller":"traceutil/trace.go:171","msg":"trace[1501008373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25002; }","duration":"300.595497ms","start":"2026-04-28T06:46:33.772493Z","end":"2026-04-28T06:46:34.073089Z","steps":["trace[1501008373] 'agreement among raft nodes before linearized reading' (duration: 300.420553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.073123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.74253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-28T06:46:34.073137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:33.772463Z","time spent":"300.665649ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:46:34.07318Z","caller":"traceutil/trace.go:171","msg":"trace[1094596826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25002; }","duration":"300.801932ms","start":"2026-04-28T06:46:33.772345Z","end":"2026-04-28T06:46:34.073147Z","steps":["trace[1094596826] 'agreement among raft nodes before linearized reading' (duration: 300.70621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.073214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:33.77233Z","time spent":"300.876954ms","remote":"127.0.0.1:41596","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":"info","ts":"2026-04-28T06:46:34.073338Z","caller":"traceutil/trace.go:171","msg":"trace[1199649313] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"297.143905ms","start":"2026-04-28T06:46:33.776182Z","end":"2026-04-28T06:46:34.073326Z","steps":["trace[1199649313] 'process raft request' (duration: 296.281304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.073359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.421241ms","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-04-28T06:46:34.073383Z","caller":"traceutil/trace.go:171","msg":"trace[1554614589] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25002; }","duration":"510.477853ms","start":"2026-04-28T06:46:33.562898Z","end":"2026-04-28T06:46:34.073376Z","steps":["trace[1554614589] 'agreement among raft nodes before linearized reading' (duration: 510.38064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.073407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:33.562879Z","time spent":"510.523103ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-28T06:46:34.073474Z","caller":"traceutil/trace.go:171","msg":"trace[1342015312] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"296.702044ms","start":"2026-04-28T06:46:33.776763Z","end":"2026-04-28T06:46:34.073465Z","steps":["trace[1342015312] 'process raft request' (duration: 295.746221ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:34.073596Z","caller":"traceutil/trace.go:171","msg":"trace[774644718] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"295.464154ms","start":"2026-04-28T06:46:33.778124Z","end":"2026-04-28T06:46:34.073588Z","steps":["trace[774644718] 'process raft request' (duration: 294.417199ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:34.073761Z","caller":"traceutil/trace.go:171","msg":"trace[935944214] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"265.030006ms","start":"2026-04-28T06:46:33.808722Z","end":"2026-04-28T06:46:34.073752Z","steps":["trace[935944214] 'process raft request' (duration: 263.853388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.07394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.634268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T06:46:34.073981Z","caller":"traceutil/trace.go:171","msg":"trace[1846830183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25002; }","duration":"300.70537ms","start":"2026-04-28T06:46:33.773262Z","end":"2026-04-28T06:46:34.073967Z","steps":["trace[1846830183] 'agreement among raft nodes before linearized reading' (duration: 300.611647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.074008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:33.773242Z","time spent":"300.759451ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:46:34.350313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.946897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:46:34.350427Z","caller":"traceutil/trace.go:171","msg":"trace[199739558] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"270.820385ms","start":"2026-04-28T06:46:34.079586Z","end":"2026-04-28T06:46:34.350406Z","steps":["trace[199739558] 'process raft request' (duration: 131.681653ms)","trace[199739558] 'compare' (duration: 138.786723ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:34.391236Z","caller":"traceutil/trace.go:171","msg":"trace[1158977155] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25830; }","duration":"245.14038ms","start":"2026-04-28T06:46:34.146073Z","end":"2026-04-28T06:46:34.391213Z","steps":["trace[1158977155] 'read index received' (duration: 65.207941ms)","trace[1158977155] 'applied index is now lower than readState.Index' (duration: 179.931419ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:34.391271Z","caller":"traceutil/trace.go:171","msg":"trace[993394410] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"312.105023ms","start":"2026-04-28T06:46:34.079138Z","end":"2026-04-28T06:46:34.391243Z","steps":["trace[993394410] 'process raft request' (duration: 311.907438ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:34.391303Z","caller":"traceutil/trace.go:171","msg":"trace[516800694] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"308.264761ms","start":"2026-04-28T06:46:34.083005Z","end":"2026-04-28T06:46:34.391269Z","steps":["trace[516800694] 'process raft request' (duration: 308.168408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.391364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.260953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T06:46:34.391393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:34.079119Z","time spent":"312.208056ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:34.391403Z","caller":"traceutil/trace.go:171","msg":"trace[654226701] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25005; }","duration":"245.357326ms","start":"2026-04-28T06:46:34.146035Z","end":"2026-04-28T06:46:34.391392Z","steps":["trace[654226701] 'agreement among raft nodes before linearized reading' (duration: 245.270023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.391592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:34.082986Z","time spent":"308.465076ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:34.64504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.91628ms","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-04-28T06:46:34.645137Z","caller":"traceutil/trace.go:171","msg":"trace[1363855818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25005; }","duration":"173.049314ms","start":"2026-04-28T06:46:34.472064Z","end":"2026-04-28T06:46:34.645114Z","steps":["trace[1363855818] 'range keys from in-memory index tree' (duration: 172.744417ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:34.783337Z","caller":"traceutil/trace.go:171","msg":"trace[1776272550] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"134.128202ms","start":"2026-04-28T06:46:34.649189Z","end":"2026-04-28T06:46:34.783318Z","steps":["trace[1776272550] 'process raft request' (duration: 134.007678ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:35.112342Z","caller":"traceutil/trace.go:171","msg":"trace[711395692] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"100.137578ms","start":"2026-04-28T06:46:35.012148Z","end":"2026-04-28T06:46:35.112285Z","steps":["trace[711395692] 'process raft request' (duration: 99.962304ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:35.529004Z","caller":"traceutil/trace.go:171","msg":"trace[1825765132] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"108.17975ms","start":"2026-04-28T06:46:35.420788Z","end":"2026-04-28T06:46:35.528967Z","steps":["trace[1825765132] 'process raft request' (duration: 108.034976ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:41.040351Z","caller":"traceutil/trace.go:171","msg":"trace[1376777388] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"230.936799ms","start":"2026-04-28T06:46:40.809381Z","end":"2026-04-28T06:46:41.040317Z","steps":["trace[1376777388] 'process raft request' (duration: 230.754344ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:41.07728Z","caller":"traceutil/trace.go:171","msg":"trace[69986854] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"234.128065ms","start":"2026-04-28T06:46:40.843123Z","end":"2026-04-28T06:46:41.077251Z","steps":["trace[69986854] 'process raft request' (duration: 233.607152ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:41.077523Z","caller":"traceutil/trace.go:171","msg":"trace[975430684] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"230.864637ms","start":"2026-04-28T06:46:40.846618Z","end":"2026-04-28T06:46:41.077482Z","steps":["trace[975430684] 'process raft request' (duration: 230.584001ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:49.120215Z","caller":"traceutil/trace.go:171","msg":"trace[867895466] linearizableReadLoop","detail":"{readStateIndex:25939; appliedIndex:25938; }","duration":"242.560367ms","start":"2026-04-28T06:46:48.877633Z","end":"2026-04-28T06:46:49.120193Z","steps":["trace[867895466] 'read index received' (duration: 242.535677ms)","trace[867895466] 'applied index is now lower than readState.Index' (duration: 24.06µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:49.120346Z","caller":"traceutil/trace.go:171","msg":"trace[404397264] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"286.726335ms","start":"2026-04-28T06:46:48.83353Z","end":"2026-04-28T06:46:49.120256Z","steps":["trace[404397264] 'process raft request' (duration: 286.52995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:49.120411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.756682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:49.120503Z","caller":"traceutil/trace.go:171","msg":"trace[208440512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25108; }","duration":"242.910795ms","start":"2026-04-28T06:46:48.877573Z","end":"2026-04-28T06:46:49.120484Z","steps":["trace[208440512] 'agreement among raft nodes before linearized reading' (duration: 242.728021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:49.133876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.729861ms","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":"warn","ts":"2026-04-28T06:46:49.133921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.739383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:49.133963Z","caller":"traceutil/trace.go:171","msg":"trace[389224610] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25110; }","duration":"138.840663ms","start":"2026-04-28T06:46:48.995102Z","end":"2026-04-28T06:46:49.133942Z","steps":["trace[389224610] 'agreement among raft nodes before linearized reading' (duration: 138.69139ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:49.134028Z","caller":"traceutil/trace.go:171","msg":"trace[1282114055] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25110; }","duration":"103.852526ms","start":"2026-04-28T06:46:49.030114Z","end":"2026-04-28T06:46:49.133966Z","steps":["trace[1282114055] 'agreement among raft nodes before linearized reading' (duration: 103.752304ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:49.425193Z","caller":"traceutil/trace.go:171","msg":"trace[93562550] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"286.849877ms","start":"2026-04-28T06:46:49.138318Z","end":"2026-04-28T06:46:49.425168Z","steps":["trace[93562550] 'process raft request' (duration: 266.04506ms)","trace[93562550] 'compare' (duration: 20.294415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:49.974328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.040271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:46:49.974503Z","caller":"traceutil/trace.go:171","msg":"trace[303688152] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25941; }","duration":"718.38573ms","start":"2026-04-28T06:46:49.256099Z","end":"2026-04-28T06:46:49.974485Z","steps":["trace[303688152] 'read index received' (duration: 148.349342ms)","trace[303688152] 'applied index is now lower than readState.Index' (duration: 570.033078ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:49.974633Z","caller":"traceutil/trace.go:171","msg":"trace[475971177] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"809.463541ms","start":"2026-04-28T06:46:49.165155Z","end":"2026-04-28T06:46:49.974618Z","steps":["trace[475971177] 'process raft request' (duration: 583.057491ms)","trace[475971177] 'compare' (duration: 225.537039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:49.974696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:49.165131Z","time spent":"809.530503ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:49.974819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.685107ms","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-04-28T06:46:49.974883Z","caller":"traceutil/trace.go:171","msg":"trace[698683104] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25113; }","duration":"718.776959ms","start":"2026-04-28T06:46:49.256089Z","end":"2026-04-28T06:46:49.974866Z","steps":["trace[698683104] 'agreement among raft nodes before linearized reading' (duration: 718.593835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:49.974883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.243835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-28T06:46:49.974913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:49.256013Z","time spent":"718.894612ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":32,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:46:49.97492Z","caller":"traceutil/trace.go:171","msg":"trace[1094444521] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25113; }","duration":"375.279436ms","start":"2026-04-28T06:46:49.59963Z","end":"2026-04-28T06:46:49.974909Z","steps":["trace[1094444521] 'agreement among raft nodes before linearized reading' (duration: 375.141383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:49.974956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:49.599606Z","time spent":"375.344007ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-28T06:46:49.974976Z","caller":"traceutil/trace.go:171","msg":"trace[2004108981] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"705.695516ms","start":"2026-04-28T06:46:49.269257Z","end":"2026-04-28T06:46:49.974953Z","steps":["trace[2004108981] 'process raft request' (duration: 705.160983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:49.975023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.486038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T06:46:49.975065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:49.269234Z","time spent":"705.787228ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:49.975091Z","caller":"traceutil/trace.go:171","msg":"trace[1678345491] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25113; }","duration":"116.594162ms","start":"2026-04-28T06:46:49.858482Z","end":"2026-04-28T06:46:49.975077Z","steps":["trace[1678345491] 'agreement among raft nodes before linearized reading' (duration: 116.482848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:50.513129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.176634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:46:50.513868Z","caller":"traceutil/trace.go:171","msg":"trace[380099248] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"384.958608ms","start":"2026-04-28T06:46:50.12889Z","end":"2026-04-28T06:46:50.513849Z","steps":["trace[380099248] 'process raft request' (duration: 384.339923ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:50.513871Z","caller":"traceutil/trace.go:171","msg":"trace[1304860917] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"386.170547ms","start":"2026-04-28T06:46:50.127644Z","end":"2026-04-28T06:46:50.513815Z","steps":["trace[1304860917] 'process raft request' (duration: 152.200054ms)","trace[1304860917] 'compare' (duration: 232.806945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:50.513954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.128873Z","time spent":"385.04061ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:50.514143Z","caller":"traceutil/trace.go:171","msg":"trace[1672608477] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"383.250307ms","start":"2026-04-28T06:46:50.130861Z","end":"2026-04-28T06:46:50.514111Z","steps":["trace[1672608477] 'process raft request' (duration: 382.94078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:50.51419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.12761Z","time spent":"386.358951ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:50.514253Z","caller":"traceutil/trace.go:171","msg":"trace[744921058] linearizableReadLoop","detail":"{readStateIndex:25948; appliedIndex:25944; }","duration":"376.523084ms","start":"2026-04-28T06:46:50.137693Z","end":"2026-04-28T06:46:50.514216Z","steps":["trace[744921058] 'read index received' (duration: 142.170123ms)","trace[744921058] 'applied index is now lower than readState.Index' (duration: 234.351621ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:50.514404Z","caller":"traceutil/trace.go:171","msg":"trace[560726071] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"379.555147ms","start":"2026-04-28T06:46:50.134779Z","end":"2026-04-28T06:46:50.514334Z","steps":["trace[560726071] 'process raft request' (duration: 379.288461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:50.514501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.376514ms","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":"warn","ts":"2026-04-28T06:46:50.514501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.784921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-28T06:46:50.514531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.134765Z","time spent":"379.69263ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:50.514566Z","caller":"traceutil/trace.go:171","msg":"trace[629659336] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25117; }","duration":"146.494627ms","start":"2026-04-28T06:46:50.368048Z","end":"2026-04-28T06:46:50.514542Z","steps":["trace[629659336] 'agreement among raft nodes before linearized reading' (duration: 146.371204ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:50.514585Z","caller":"traceutil/trace.go:171","msg":"trace[1919091303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25117; }","duration":"376.897044ms","start":"2026-04-28T06:46:50.137668Z","end":"2026-04-28T06:46:50.514565Z","steps":["trace[1919091303] 'agreement among raft nodes before linearized reading' (duration: 376.677269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:50.51462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.13766Z","time spent":"376.954585ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:46:50.514871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.130847Z","time spent":"383.39829ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:51.101905Z","caller":"traceutil/trace.go:171","msg":"trace[378337765] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"582.057386ms","start":"2026-04-28T06:46:50.519816Z","end":"2026-04-28T06:46:51.101873Z","steps":["trace[378337765] 'process raft request' (duration: 485.656557ms)","trace[378337765] 'compare' (duration: 95.796525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:51.102094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.519794Z","time spent":"582.20205ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:51.102236Z","caller":"traceutil/trace.go:171","msg":"trace[294194487] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"582.089727ms","start":"2026-04-28T06:46:50.520109Z","end":"2026-04-28T06:46:51.102199Z","steps":["trace[294194487] 'process raft request' (duration: 581.696378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:51.102448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.520079Z","time spent":"582.252981ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:46:51.103031Z","caller":"traceutil/trace.go:171","msg":"trace[639150679] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"582.847165ms","start":"2026-04-28T06:46:50.52015Z","end":"2026-04-28T06:46:51.102997Z","steps":["trace[639150679] 'process raft request' (duration: 581.928413ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:51.103074Z","caller":"traceutil/trace.go:171","msg":"trace[1439101008] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"560.595602ms","start":"2026-04-28T06:46:50.542457Z","end":"2026-04-28T06:46:51.103053Z","steps":["trace[1439101008] 'process raft request' (duration: 560.440879ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:51.103065Z","caller":"traceutil/trace.go:171","msg":"trace[1863227055] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25949; }","duration":"224.644409ms","start":"2026-04-28T06:46:50.878396Z","end":"2026-04-28T06:46:51.10304Z","steps":["trace[1863227055] 'read index received' (duration: 126.997241ms)","trace[1863227055] 'applied index is now lower than readState.Index' (duration: 97.645908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:51.103181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.542437Z","time spent":"560.693965ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:51.103205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:50.520124Z","time spent":"582.987799ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:51.103248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.841683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:51.103552Z","caller":"traceutil/trace.go:171","msg":"trace[1594875499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25122; }","duration":"225.184342ms","start":"2026-04-28T06:46:50.878352Z","end":"2026-04-28T06:46:51.103536Z","steps":["trace[1594875499] 'agreement among raft nodes before linearized reading' (duration: 224.814323ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:51.600878Z","caller":"traceutil/trace.go:171","msg":"trace[1867791246] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"461.754666ms","start":"2026-04-28T06:46:51.139094Z","end":"2026-04-28T06:46:51.600849Z","steps":["trace[1867791246] 'process raft request' (duration: 388.486162ms)","trace[1867791246] 'compare' (duration: 73.103161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:51.601016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:51.139066Z","time spent":"461.87409ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:52.154534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.929421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:46:52.15462Z","caller":"traceutil/trace.go:171","msg":"trace[1655629826] linearizableReadLoop","detail":"{readStateIndex:25955; appliedIndex:25953; }","duration":"725.874392ms","start":"2026-04-28T06:46:51.428733Z","end":"2026-04-28T06:46:52.154608Z","steps":["trace[1655629826] 'read index received' (duration: 98.861678ms)","trace[1655629826] 'applied index is now lower than readState.Index' (duration: 627.011944ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:52.154772Z","caller":"traceutil/trace.go:171","msg":"trace[1082520413] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"1.015573167s","start":"2026-04-28T06:46:51.13919Z","end":"2026-04-28T06:46:52.154763Z","steps":["trace[1082520413] 'process raft request' (duration: 786.352959ms)","trace[1082520413] 'compare' (duration: 228.839398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:52.154815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:51.139132Z","time spent":"1.015660169s","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:46:52.155007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.281171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-28T06:46:52.155026Z","caller":"traceutil/trace.go:171","msg":"trace[1312091867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25124; }","duration":"726.336562ms","start":"2026-04-28T06:46:51.428683Z","end":"2026-04-28T06:46:52.155019Z","steps":["trace[1312091867] 'agreement among raft nodes before linearized reading' (duration: 726.24551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:52.155044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:51.42866Z","time spent":"726.379713ms","remote":"127.0.0.1:41596","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":"info","ts":"2026-04-28T06:46:52.171832Z","caller":"traceutil/trace.go:171","msg":"trace[664808745] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"191.057645ms","start":"2026-04-28T06:46:51.98075Z","end":"2026-04-28T06:46:52.171808Z","steps":["trace[664808745] 'process raft request' (duration: 190.924332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:52.27985Z","caller":"traceutil/trace.go:171","msg":"trace[318834621] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"298.874755ms","start":"2026-04-28T06:46:51.980954Z","end":"2026-04-28T06:46:52.279828Z","steps":["trace[318834621] 'process raft request' (duration: 296.748114ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:52.280042Z","caller":"traceutil/trace.go:171","msg":"trace[787373047] linearizableReadLoop","detail":"{readStateIndex:25957; appliedIndex:25955; }","duration":"125.366621ms","start":"2026-04-28T06:46:52.154638Z","end":"2026-04-28T06:46:52.280004Z","steps":["trace[787373047] 'read index received' (duration: 17.073349ms)","trace[787373047] 'applied index is now lower than readState.Index' (duration: 108.290862ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:46:52.280219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.566075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:46:52.280283Z","caller":"traceutil/trace.go:171","msg":"trace[1181121210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25127; }","duration":"401.639076ms","start":"2026-04-28T06:46:51.878625Z","end":"2026-04-28T06:46:52.280264Z","steps":["trace[1181121210] 'agreement among raft nodes before linearized reading' (duration: 401.528234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:52.28026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.562355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"warn","ts":"2026-04-28T06:46:52.280329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:51.878565Z","time spent":"401.751348ms","remote":"127.0.0.1:41352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:46:52.280343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.835099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"} {"level":"info","ts":"2026-04-28T06:46:52.280317Z","caller":"traceutil/trace.go:171","msg":"trace[1709325345] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"121.384586ms","start":"2026-04-28T06:46:52.15882Z","end":"2026-04-28T06:46:52.280205Z","steps":["trace[1709325345] 'process raft request' (duration: 120.970686ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:52.280405Z","caller":"traceutil/trace.go:171","msg":"trace[1359886762] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25127; }","duration":"436.952532ms","start":"2026-04-28T06:46:51.843435Z","end":"2026-04-28T06:46:52.280388Z","steps":["trace[1359886762] 'agreement among raft nodes before linearized reading' (duration: 436.713957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:52.280455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:46:51.843408Z","time spent":"437.034324ms","remote":"127.0.0.1:56200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7773,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-04-28T06:46:52.280347Z","caller":"traceutil/trace.go:171","msg":"trace[777889201] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25127; }","duration":"249.691568ms","start":"2026-04-28T06:46:52.030633Z","end":"2026-04-28T06:46:52.280324Z","steps":["trace[777889201] 'agreement among raft nodes before linearized reading' (duration: 249.396351ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:07.394757Z","caller":"traceutil/trace.go:171","msg":"trace[1840177157] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"204.551457ms","start":"2026-04-28T06:47:07.190178Z","end":"2026-04-28T06:47:07.394729Z","steps":["trace[1840177157] 'process raft request' (duration: 204.322872ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:11.642887Z","caller":"traceutil/trace.go:171","msg":"trace[1029548083] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"367.52119ms","start":"2026-04-28T06:47:11.275341Z","end":"2026-04-28T06:47:11.642862Z","steps":["trace[1029548083] 'process raft request' (duration: 367.351886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:11.643041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:11.275328Z","time spent":"367.633442ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:11.643507Z","caller":"traceutil/trace.go:171","msg":"trace[1803270298] linearizableReadLoop","detail":"{readStateIndex:26105; appliedIndex:26103; }","duration":"233.258745ms","start":"2026-04-28T06:47:11.410231Z","end":"2026-04-28T06:47:11.643489Z","steps":["trace[1803270298] 'read index received' (duration: 232.458286ms)","trace[1803270298] 'applied index is now lower than readState.Index' (duration: 799.939µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:11.643654Z","caller":"traceutil/trace.go:171","msg":"trace[1275572102] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"361.713701ms","start":"2026-04-28T06:47:11.281874Z","end":"2026-04-28T06:47:11.643587Z","steps":["trace[1275572102] 'process raft request' (duration: 361.514756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:11.643746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:11.281861Z","time spent":"361.821343ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:11.643908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.656615ms","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-04-28T06:47:11.644016Z","caller":"traceutil/trace.go:171","msg":"trace[815029546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25270; }","duration":"233.819009ms","start":"2026-04-28T06:47:11.410178Z","end":"2026-04-28T06:47:11.643997Z","steps":["trace[815029546] 'agreement among raft nodes before linearized reading' (duration: 233.554032ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:17.136697Z","caller":"traceutil/trace.go:171","msg":"trace[622794386] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"277.637568ms","start":"2026-04-28T06:47:16.859035Z","end":"2026-04-28T06:47:17.136672Z","steps":["trace[622794386] 'process raft request' (duration: 277.429203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:17.946622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.971251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:47:17.947248Z","caller":"traceutil/trace.go:171","msg":"trace[1983251070] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"222.97222ms","start":"2026-04-28T06:47:17.72425Z","end":"2026-04-28T06:47:17.947223Z","steps":["trace[1983251070] 'process raft request' (duration: 222.506898ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:17.947404Z","caller":"traceutil/trace.go:171","msg":"trace[1692175576] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"224.031925ms","start":"2026-04-28T06:47:17.723318Z","end":"2026-04-28T06:47:17.94735Z","steps":["trace[1692175576] 'process raft request' (duration: 93.245233ms)","trace[1692175576] 'compare' (duration: 129.767587ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:17.947482Z","caller":"traceutil/trace.go:171","msg":"trace[1358302621] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"220.190863ms","start":"2026-04-28T06:47:17.727275Z","end":"2026-04-28T06:47:17.947466Z","steps":["trace[1358302621] 'process raft request' (duration: 219.596529ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.546901Z","caller":"traceutil/trace.go:171","msg":"trace[545682714] linearizableReadLoop","detail":"{readStateIndex:26153; appliedIndex:26152; }","duration":"290.517836ms","start":"2026-04-28T06:47:18.256358Z","end":"2026-04-28T06:47:18.546876Z","steps":["trace[545682714] 'read index received' (duration: 290.345832ms)","trace[545682714] 'applied index is now lower than readState.Index' (duration: 170.954µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:18.54701Z","caller":"traceutil/trace.go:171","msg":"trace[858055626] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"295.503076ms","start":"2026-04-28T06:47:18.251497Z","end":"2026-04-28T06:47:18.547Z","steps":["trace[858055626] 'process raft request' (duration: 295.214159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:18.547236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.869404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-04-28T06:47:18.547272Z","caller":"traceutil/trace.go:171","msg":"trace[1488429139] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:25317; }","duration":"290.955066ms","start":"2026-04-28T06:47:18.256308Z","end":"2026-04-28T06:47:18.547263Z","steps":["trace[1488429139] 'agreement among raft nodes before linearized reading' (duration: 290.826483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:18.81576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.754366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:47:18.816107Z","caller":"traceutil/trace.go:171","msg":"trace[550255304] linearizableReadLoop","detail":"{readStateIndex:26156; appliedIndex:26153; }","duration":"269.119083ms","start":"2026-04-28T06:47:18.546968Z","end":"2026-04-28T06:47:18.816087Z","steps":["trace[550255304] 'read index received' (duration: 128.872125ms)","trace[550255304] 'applied index is now lower than readState.Index' (duration: 140.245898ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:18.816086Z","caller":"traceutil/trace.go:171","msg":"trace[1749482999] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"512.000889ms","start":"2026-04-28T06:47:18.304038Z","end":"2026-04-28T06:47:18.816039Z","steps":["trace[1749482999] 'process raft request' (duration: 371.870144ms)","trace[1749482999] 'compare' (duration: 139.599983ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:18.816212Z","caller":"traceutil/trace.go:171","msg":"trace[1153756129] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"379.262462ms","start":"2026-04-28T06:47:18.436936Z","end":"2026-04-28T06:47:18.816198Z","steps":["trace[1153756129] 'process raft request' (duration: 379.086398ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.81627Z","caller":"traceutil/trace.go:171","msg":"trace[1229997387] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"500.288649ms","start":"2026-04-28T06:47:18.315949Z","end":"2026-04-28T06:47:18.816238Z","steps":["trace[1229997387] 'process raft request' (duration: 499.93116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:18.816537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.222288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-04-28T06:47:18.816557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:18.436906Z","time spent":"379.582189ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:18.816604Z","caller":"traceutil/trace.go:171","msg":"trace[391299511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25320; }","duration":"323.326891ms","start":"2026-04-28T06:47:18.493264Z","end":"2026-04-28T06:47:18.816591Z","steps":["trace[391299511] 'agreement among raft nodes before linearized reading' (duration: 323.135207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:18.81659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:18.304008Z","time spent":"512.171593ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:18.816657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:18.493243Z","time spent":"323.406183ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:47:18.816724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:18.315903Z","time spent":"500.709639ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:18.87985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:18.548533Z","time spent":"331.311914ms","remote":"127.0.0.1:41386","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T06:47:18.880024Z","caller":"traceutil/trace.go:171","msg":"trace[515735546] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"225.710875ms","start":"2026-04-28T06:47:18.654288Z","end":"2026-04-28T06:47:18.879999Z","steps":["trace[515735546] 'process raft request' (duration: 225.591442ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.880091Z","caller":"traceutil/trace.go:171","msg":"trace[22341078] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"231.37254ms","start":"2026-04-28T06:47:18.648694Z","end":"2026-04-28T06:47:18.880067Z","steps":["trace[22341078] 'process raft request' (duration: 231.038592ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.880255Z","caller":"traceutil/trace.go:171","msg":"trace[1090314922] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"221.647477ms","start":"2026-04-28T06:47:18.658593Z","end":"2026-04-28T06:47:18.880241Z","steps":["trace[1090314922] 'process raft request' (duration: 221.32106ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.880292Z","caller":"traceutil/trace.go:171","msg":"trace[1863268595] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"221.37116ms","start":"2026-04-28T06:47:18.658908Z","end":"2026-04-28T06:47:18.880279Z","steps":["trace[1863268595] 'process raft request' (duration: 221.249978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:18.880323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.05085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:18.880372Z","caller":"traceutil/trace.go:171","msg":"trace[848607383] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25324; }","duration":"129.157462ms","start":"2026-04-28T06:47:18.7512Z","end":"2026-04-28T06:47:18.880358Z","steps":["trace[848607383] 'agreement among raft nodes before linearized reading' (duration: 129.07166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:18.880506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.455357ms","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-04-28T06:47:18.880554Z","caller":"traceutil/trace.go:171","msg":"trace[1270040631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25324; }","duration":"174.544979ms","start":"2026-04-28T06:47:18.705998Z","end":"2026-04-28T06:47:18.880543Z","steps":["trace[1270040631] 'agreement among raft nodes before linearized reading' (duration: 174.391215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:18.974786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.220575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T06:47:18.974875Z","caller":"traceutil/trace.go:171","msg":"trace[1564846066] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25325; }","duration":"152.344947ms","start":"2026-04-28T06:47:18.822512Z","end":"2026-04-28T06:47:18.974857Z","steps":["trace[1564846066] 'agreement among raft nodes before linearized reading' (duration: 152.148102ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.974793Z","caller":"traceutil/trace.go:171","msg":"trace[235297232] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"151.567478ms","start":"2026-04-28T06:47:18.823185Z","end":"2026-04-28T06:47:18.974753Z","steps":["trace[235297232] 'process raft request' (duration: 144.09372ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.997972Z","caller":"traceutil/trace.go:171","msg":"trace[522366858] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"115.933197ms","start":"2026-04-28T06:47:18.88202Z","end":"2026-04-28T06:47:18.997954Z","steps":["trace[522366858] 'process raft request' (duration: 115.693891ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:18.997955Z","caller":"traceutil/trace.go:171","msg":"trace[1124229958] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"113.473087ms","start":"2026-04-28T06:47:18.884457Z","end":"2026-04-28T06:47:18.99793Z","steps":["trace[1124229958] 'process raft request' (duration: 113.410876ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:19.275528Z","caller":"traceutil/trace.go:171","msg":"trace[1339109189] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"114.972183ms","start":"2026-04-28T06:47:19.160531Z","end":"2026-04-28T06:47:19.275503Z","steps":["trace[1339109189] 'process raft request' (duration: 114.796499ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:21.861366Z","caller":"traceutil/trace.go:171","msg":"trace[1988709394] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"126.627942ms","start":"2026-04-28T06:47:21.734713Z","end":"2026-04-28T06:47:21.861341Z","steps":["trace[1988709394] 'process raft request' (duration: 126.455598ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:22.128607Z","caller":"traceutil/trace.go:171","msg":"trace[2073942319] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"158.030073ms","start":"2026-04-28T06:47:21.970547Z","end":"2026-04-28T06:47:22.128577Z","steps":["trace[2073942319] 'process raft request' (duration: 154.092829ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:22.268364Z","caller":"traceutil/trace.go:171","msg":"trace[888311365] linearizableReadLoop","detail":"{readStateIndex:26183; appliedIndex:26181; }","duration":"182.749786ms","start":"2026-04-28T06:47:22.085589Z","end":"2026-04-28T06:47:22.268339Z","steps":["trace[888311365] 'read index received' (duration: 39.063685ms)","trace[888311365] 'applied index is now lower than readState.Index' (duration: 143.684791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:22.268522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.915069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:22.268567Z","caller":"traceutil/trace.go:171","msg":"trace[691269632] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25346; }","duration":"183.002422ms","start":"2026-04-28T06:47:22.085548Z","end":"2026-04-28T06:47:22.268551Z","steps":["trace[691269632] 'agreement among raft nodes before linearized reading' (duration: 182.913549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:22.268676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.762629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:22.268737Z","caller":"traceutil/trace.go:171","msg":"trace[1340932592] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25346; }","duration":"109.867681ms","start":"2026-04-28T06:47:22.158855Z","end":"2026-04-28T06:47:22.268723Z","steps":["trace[1340932592] 'agreement among raft nodes before linearized reading' (duration: 109.755708ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:22.268462Z","caller":"traceutil/trace.go:171","msg":"trace[1832363045] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"295.361352ms","start":"2026-04-28T06:47:21.973077Z","end":"2026-04-28T06:47:22.268438Z","steps":["trace[1832363045] 'process raft request' (duration: 292.695278ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:23.045336Z","caller":"traceutil/trace.go:171","msg":"trace[997528470] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"155.716728ms","start":"2026-04-28T06:47:22.889489Z","end":"2026-04-28T06:47:23.045206Z","steps":["trace[997528470] 'process raft request' (duration: 145.219097ms)","trace[997528470] 'compare' (duration: 10.143633ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:23.047896Z","caller":"traceutil/trace.go:171","msg":"trace[1895944948] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"155.508083ms","start":"2026-04-28T06:47:22.892367Z","end":"2026-04-28T06:47:23.047875Z","steps":["trace[1895944948] 'process raft request' (duration: 155.429301ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:23.048049Z","caller":"traceutil/trace.go:171","msg":"trace[48584288] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"156.405604ms","start":"2026-04-28T06:47:22.891636Z","end":"2026-04-28T06:47:23.048042Z","steps":["trace[48584288] 'process raft request' (duration: 156.134388ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:23.048041Z","caller":"traceutil/trace.go:171","msg":"trace[2058810657] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"156.388514ms","start":"2026-04-28T06:47:22.891636Z","end":"2026-04-28T06:47:23.048025Z","steps":["trace[2058810657] 'process raft request' (duration: 156.033345ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:23.238424Z","caller":"traceutil/trace.go:171","msg":"trace[657202273] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"186.914345ms","start":"2026-04-28T06:47:23.051486Z","end":"2026-04-28T06:47:23.238401Z","steps":["trace[657202273] 'process raft request' (duration: 153.879054ms)","trace[657202273] 'compare' (duration: 32.538969ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:23.983213Z","caller":"traceutil/trace.go:171","msg":"trace[2043049282] linearizableReadLoop","detail":"{readStateIndex:26195; appliedIndex:26194; }","duration":"106.89679ms","start":"2026-04-28T06:47:23.876299Z","end":"2026-04-28T06:47:23.983196Z","steps":["trace[2043049282] 'read index received' (duration: 106.843909ms)","trace[2043049282] 'applied index is now lower than readState.Index' (duration: 52.291µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:23.983318Z","caller":"traceutil/trace.go:171","msg":"trace[1654346670] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"114.898221ms","start":"2026-04-28T06:47:23.868393Z","end":"2026-04-28T06:47:23.983292Z","steps":["trace[1654346670] 'process raft request' (duration: 114.664616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:23.983379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.054483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:23.983414Z","caller":"traceutil/trace.go:171","msg":"trace[588470985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25357; }","duration":"107.156296ms","start":"2026-04-28T06:47:23.87625Z","end":"2026-04-28T06:47:23.983407Z","steps":["trace[588470985] 'agreement among raft nodes before linearized reading' (duration: 107.018903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:28.9863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.508588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:28.986419Z","caller":"traceutil/trace.go:171","msg":"trace[74274569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25449; }","duration":"108.661532ms","start":"2026-04-28T06:47:28.87772Z","end":"2026-04-28T06:47:28.986382Z","steps":["trace[74274569] 'range keys from in-memory index tree' (duration: 108.420105ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:29.09141Z","caller":"traceutil/trace.go:171","msg":"trace[1163536257] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"199.497396ms","start":"2026-04-28T06:47:28.891886Z","end":"2026-04-28T06:47:29.091383Z","steps":["trace[1163536257] 'process raft request' (duration: 199.310452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.498794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.557608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:47:29.49893Z","caller":"traceutil/trace.go:171","msg":"trace[1636723717] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"604.530824ms","start":"2026-04-28T06:47:28.894384Z","end":"2026-04-28T06:47:29.498915Z","steps":["trace[1636723717] 'process raft request' (duration: 399.787632ms)","trace[1636723717] 'compare' (duration: 203.872022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:29.498974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:28.894351Z","time spent":"604.602306ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:29.499164Z","caller":"traceutil/trace.go:171","msg":"trace[820241888] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"436.005339ms","start":"2026-04-28T06:47:29.063096Z","end":"2026-04-28T06:47:29.499102Z","steps":["trace[820241888] 'process raft request' (duration: 435.778223ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:29.49923Z","caller":"traceutil/trace.go:171","msg":"trace[1028782192] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"424.184196ms","start":"2026-04-28T06:47:29.075021Z","end":"2026-04-28T06:47:29.499205Z","steps":["trace[1028782192] 'process raft request' (duration: 424.034503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.499347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.063067Z","time spent":"436.188793ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:29.499372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.075008Z","time spent":"424.291648ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:29.499377Z","caller":"traceutil/trace.go:171","msg":"trace[1001226508] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"423.528331ms","start":"2026-04-28T06:47:29.075829Z","end":"2026-04-28T06:47:29.499358Z","steps":["trace[1001226508] 'process raft request' (duration: 423.332846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.499482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.07579Z","time spent":"423.636923ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:29.499713Z","caller":"traceutil/trace.go:171","msg":"trace[1840317163] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"423.854799ms","start":"2026-04-28T06:47:29.075844Z","end":"2026-04-28T06:47:29.499699Z","steps":["trace[1840317163] 'process raft request' (duration: 423.447988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.49984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.07582Z","time spent":"423.976532ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:29.500031Z","caller":"traceutil/trace.go:171","msg":"trace[783018344] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"417.65216ms","start":"2026-04-28T06:47:29.082359Z","end":"2026-04-28T06:47:29.500011Z","steps":["trace[783018344] 'process raft request' (duration: 417.25189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.500141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.082346Z","time spent":"417.750642ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:29.500137Z","caller":"traceutil/trace.go:171","msg":"trace[1902858804] linearizableReadLoop","detail":"{readStateIndex:26291; appliedIndex:26289; }","duration":"575.585872ms","start":"2026-04-28T06:47:28.924525Z","end":"2026-04-28T06:47:29.500111Z","steps":["trace[1902858804] 'read index received' (duration: 166.716562ms)","trace[1902858804] 'applied index is now lower than readState.Index' (duration: 408.866949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:29.500259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.691995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:29.500307Z","caller":"traceutil/trace.go:171","msg":"trace[307606002] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25456; }","duration":"575.814447ms","start":"2026-04-28T06:47:28.924481Z","end":"2026-04-28T06:47:29.500296Z","steps":["trace[307606002] 'agreement among raft nodes before linearized reading' (duration: 575.692614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.500343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:28.924468Z","time spent":"575.866219ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:47:29.500645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.187377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:29.500703Z","caller":"traceutil/trace.go:171","msg":"trace[79982530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25457; }","duration":"513.287279ms","start":"2026-04-28T06:47:28.987401Z","end":"2026-04-28T06:47:29.500688Z","steps":["trace[79982530] 'agreement among raft nodes before linearized reading' (duration: 513.184507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.500721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.645275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T06:47:29.500734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:28.987382Z","time spent":"513.345561ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:47:29.500764Z","caller":"traceutil/trace.go:171","msg":"trace[1520575104] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25457; }","duration":"195.728897ms","start":"2026-04-28T06:47:29.305021Z","end":"2026-04-28T06:47:29.50075Z","steps":["trace[1520575104] 'agreement among raft nodes before linearized reading' (duration: 195.662715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.500914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.930753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:29.500946Z","caller":"traceutil/trace.go:171","msg":"trace[2099838832] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25457; }","duration":"232.000745ms","start":"2026-04-28T06:47:29.268934Z","end":"2026-04-28T06:47:29.500935Z","steps":["trace[2099838832] 'agreement among raft nodes before linearized reading' (duration: 231.953314ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:29.500997Z","caller":"traceutil/trace.go:171","msg":"trace[967014119] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"200.803428ms","start":"2026-04-28T06:47:29.300182Z","end":"2026-04-28T06:47:29.500985Z","steps":["trace[967014119] 'process raft request' (duration: 199.919897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.501165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.158037ms","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-04-28T06:47:29.501192Z","caller":"traceutil/trace.go:171","msg":"trace[354200729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25457; }","duration":"242.215269ms","start":"2026-04-28T06:47:29.258969Z","end":"2026-04-28T06:47:29.501184Z","steps":["trace[354200729] 'agreement among raft nodes before linearized reading' (duration: 242.108626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.501402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.635577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-28T06:47:29.501447Z","caller":"traceutil/trace.go:171","msg":"trace[804945257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25457; }","duration":"348.7393ms","start":"2026-04-28T06:47:29.152697Z","end":"2026-04-28T06:47:29.501436Z","steps":["trace[804945257] 'agreement among raft nodes before linearized reading' (duration: 348.618177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:29.501482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.152678Z","time spent":"348.792301ms","remote":"127.0.0.1:41596","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-04-28T06:47:30.107244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.793452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:47:30.107531Z","caller":"traceutil/trace.go:171","msg":"trace[1920915678] linearizableReadLoop","detail":"{readStateIndex:26299; appliedIndex:26297; }","duration":"582.043995ms","start":"2026-04-28T06:47:29.525462Z","end":"2026-04-28T06:47:30.107506Z","steps":["trace[1920915678] 'read index received' (duration: 115.904535ms)","trace[1920915678] 'applied index is now lower than readState.Index' (duration: 466.13802ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:30.107656Z","caller":"traceutil/trace.go:171","msg":"trace[1891747491] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"601.954933ms","start":"2026-04-28T06:47:29.505683Z","end":"2026-04-28T06:47:30.107638Z","steps":["trace[1891747491] 'process raft request' (duration: 601.705196ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:30.107692Z","caller":"traceutil/trace.go:171","msg":"trace[195387011] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"602.181039ms","start":"2026-04-28T06:47:29.505486Z","end":"2026-04-28T06:47:30.107667Z","steps":["trace[195387011] 'process raft request' (duration: 135.847023ms)","trace[195387011] 'compare' (duration: 465.616678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:30.10776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.505666Z","time spent":"602.037835ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:30.10781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.505464Z","time spent":"602.286071ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:30.107887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.415425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-28T06:47:30.107981Z","caller":"traceutil/trace.go:171","msg":"trace[766373778] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25459; }","duration":"582.544378ms","start":"2026-04-28T06:47:29.52542Z","end":"2026-04-28T06:47:30.107965Z","steps":["trace[766373778] 'agreement among raft nodes before linearized reading' (duration: 582.235701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:30.108032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.525404Z","time spent":"582.62088ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":586,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-28T06:47:30.119771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.337763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-28T06:47:30.119795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.747789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T06:47:30.119836Z","caller":"traceutil/trace.go:171","msg":"trace[1656037214] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25460; }","duration":"373.828922ms","start":"2026-04-28T06:47:29.745996Z","end":"2026-04-28T06:47:30.119825Z","steps":["trace[1656037214] 'agreement among raft nodes before linearized reading' (duration: 373.74917ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:30.119834Z","caller":"traceutil/trace.go:171","msg":"trace[481012508] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25460; }","duration":"110.458295ms","start":"2026-04-28T06:47:30.009361Z","end":"2026-04-28T06:47:30.11982Z","steps":["trace[481012508] 'agreement among raft nodes before linearized reading' (duration: 110.322632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:30.119855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.74597Z","time spent":"373.879802ms","remote":"127.0.0.1:56244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":32,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:47:30.119866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.745974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-04-28T06:47:30.119949Z","caller":"traceutil/trace.go:171","msg":"trace[1460396306] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25460; }","duration":"562.843607ms","start":"2026-04-28T06:47:29.557067Z","end":"2026-04-28T06:47:30.11991Z","steps":["trace[1460396306] 'agreement among raft nodes before linearized reading' (duration: 562.603511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:30.119992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.557048Z","time spent":"562.934478ms","remote":"127.0.0.1:53520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3333,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-28T06:47:30.120092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.842325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-04-28T06:47:30.120118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.742095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-04-28T06:47:30.120161Z","caller":"traceutil/trace.go:171","msg":"trace[657391223] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67; range_end:; response_count:1; response_revision:25460; }","duration":"549.975048ms","start":"2026-04-28T06:47:29.570169Z","end":"2026-04-28T06:47:30.120144Z","steps":["trace[657391223] 'agreement among raft nodes before linearized reading' (duration: 549.779183ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:30.12021Z","caller":"traceutil/trace.go:171","msg":"trace[1246043824] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67; range_end:; response_count:1; response_revision:25460; }","duration":"443.875987ms","start":"2026-04-28T06:47:29.676315Z","end":"2026-04-28T06:47:30.120191Z","steps":["trace[1246043824] 'agreement among raft nodes before linearized reading' (duration: 443.598611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:30.120219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.570158Z","time spent":"550.05065ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" "} {"level":"warn","ts":"2026-04-28T06:47:30.120262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.676297Z","time spent":"443.95657ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" "} {"level":"warn","ts":"2026-04-28T06:47:30.12107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.910816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:30.12114Z","caller":"traceutil/trace.go:171","msg":"trace[386717350] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25460; }","duration":"309.055819ms","start":"2026-04-28T06:47:29.812066Z","end":"2026-04-28T06:47:30.121122Z","steps":["trace[386717350] 'agreement among raft nodes before linearized reading' (duration: 308.898745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:30.121172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:29.812039Z","time spent":"309.124321ms","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:47:30.509625Z","caller":"traceutil/trace.go:171","msg":"trace[1095017414] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"395.08315ms","start":"2026-04-28T06:47:30.114497Z","end":"2026-04-28T06:47:30.50958Z","steps":["trace[1095017414] 'process raft request' (duration: 303.040056ms)","trace[1095017414] 'compare' (duration: 91.814808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:30.509842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.114473Z","time spent":"395.256664ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:31.105754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.912663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:47:31.105928Z","caller":"traceutil/trace.go:171","msg":"trace[118933452] linearizableReadLoop","detail":"{readStateIndex:26302; appliedIndex:26300; }","duration":"980.65054ms","start":"2026-04-28T06:47:30.125266Z","end":"2026-04-28T06:47:31.105916Z","steps":["trace[118933452] 'read index received' (duration: 292.3663ms)","trace[118933452] 'applied index is now lower than readState.Index' (duration: 688.28304ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:31.105964Z","caller":"traceutil/trace.go:171","msg":"trace[1713115219] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"978.271443ms","start":"2026-04-28T06:47:30.127655Z","end":"2026-04-28T06:47:31.105926Z","steps":["trace[1713115219] 'process raft request' (duration: 978.186281ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:31.106014Z","caller":"traceutil/trace.go:171","msg":"trace[1610002414] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"435.830026ms","start":"2026-04-28T06:47:30.670175Z","end":"2026-04-28T06:47:31.106005Z","steps":["trace[1610002414] 'process raft request' (duration: 435.716573ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:31.10601Z","caller":"traceutil/trace.go:171","msg":"trace[855745948] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"981.441549ms","start":"2026-04-28T06:47:30.124521Z","end":"2026-04-28T06:47:31.105962Z","steps":["trace[855745948] 'process raft request' (duration: 716.26102ms)","trace[855745948] 'compare' (duration: 264.629706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:31.106124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.127635Z","time spent":"978.397996ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:31.106193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.124502Z","time spent":"981.610153ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:31.106236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.960067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-04-28T06:47:31.106287Z","caller":"traceutil/trace.go:171","msg":"trace[1126408337] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25464; }","duration":"981.013058ms","start":"2026-04-28T06:47:30.125261Z","end":"2026-04-28T06:47:31.106274Z","steps":["trace[1126408337] 'agreement among raft nodes before linearized reading' (duration: 980.720241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.106345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.125235Z","time spent":"981.097871ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1336,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-04-28T06:47:31.10606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.670147Z","time spent":"435.887897ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:31.30091Z","caller":"traceutil/trace.go:171","msg":"trace[500374021] linearizableReadLoop","detail":"{readStateIndex:26305; appliedIndex:26304; }","duration":"194.924127ms","start":"2026-04-28T06:47:31.105963Z","end":"2026-04-28T06:47:31.300887Z","steps":["trace[500374021] 'read index received' (duration: 104.138054ms)","trace[500374021] 'applied index is now lower than readState.Index' (duration: 90.785013ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:31.301231Z","caller":"traceutil/trace.go:171","msg":"trace[598676143] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"204.778574ms","start":"2026-04-28T06:47:31.096246Z","end":"2026-04-28T06:47:31.301025Z","steps":["trace[598676143] 'process raft request' (duration: 113.923858ms)","trace[598676143] 'compare' (duration: 90.485106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:31.301322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.473921ms","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-04-28T06:47:31.301345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.18313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-04-28T06:47:31.301387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.017263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-28T06:47:31.301439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.497067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:31.301437Z","caller":"traceutil/trace.go:171","msg":"trace[134445081] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25465; }","duration":"189.294763ms","start":"2026-04-28T06:47:31.112125Z","end":"2026-04-28T06:47:31.30142Z","steps":["trace[134445081] 'agreement among raft nodes before linearized reading' (duration: 189.078738ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:31.301448Z","caller":"traceutil/trace.go:171","msg":"trace[1566546341] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25465; }","duration":"429.131426ms","start":"2026-04-28T06:47:30.872303Z","end":"2026-04-28T06:47:31.301434Z","steps":["trace[1566546341] 'agreement among raft nodes before linearized reading' (duration: 428.912901ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:31.301466Z","caller":"traceutil/trace.go:171","msg":"trace[1443356088] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25465; }","duration":"564.548438ms","start":"2026-04-28T06:47:30.736909Z","end":"2026-04-28T06:47:31.301457Z","steps":["trace[1443356088] 'agreement among raft nodes before linearized reading' (duration: 564.506247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.301486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.872279Z","time spent":"429.200928ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-28T06:47:31.301495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.736891Z","time spent":"564.597439ms","remote":"127.0.0.1:46248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:47:31.301657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.214484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:31.301684Z","caller":"traceutil/trace.go:171","msg":"trace[754422660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25465; }","duration":"423.291276ms","start":"2026-04-28T06:47:30.878386Z","end":"2026-04-28T06:47:31.301677Z","steps":["trace[754422660] 'agreement among raft nodes before linearized reading' (duration: 423.246064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.301701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.878354Z","time spent":"423.342626ms","remote":"127.0.0.1:41352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:47:31.301694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110224514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:47:31.301725Z","caller":"traceutil/trace.go:171","msg":"trace[462376039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25465; }","duration":"1.110318065s","start":"2026-04-28T06:47:30.191398Z","end":"2026-04-28T06:47:31.301716Z","steps":["trace[462376039] 'agreement among raft nodes before linearized reading' (duration: 1.110227693s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.301757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.191384Z","time spent":"1.110363636s","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T06:47:31.301392Z","caller":"traceutil/trace.go:171","msg":"trace[1113967023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25465; }","duration":"971.607564ms","start":"2026-04-28T06:47:30.329769Z","end":"2026-04-28T06:47:31.301376Z","steps":["trace[1113967023] 'agreement among raft nodes before linearized reading' (duration: 971.40849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.301858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:30.32974Z","time spent":"972.109746ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:47:31.301957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.241982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-28T06:47:31.302005Z","caller":"traceutil/trace.go:171","msg":"trace[164669515] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67; range_end:; response_count:1; response_revision:25465; }","duration":"184.330914ms","start":"2026-04-28T06:47:31.117665Z","end":"2026-04-28T06:47:31.301996Z","steps":["trace[164669515] 'agreement among raft nodes before linearized reading' (duration: 184.056308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.301995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.509841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-28T06:47:31.3021Z","caller":"traceutil/trace.go:171","msg":"trace[252031975] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67; range_end:; response_count:1; response_revision:25465; }","duration":"177.644674ms","start":"2026-04-28T06:47:31.124429Z","end":"2026-04-28T06:47:31.302073Z","steps":["trace[252031975] 'agreement among raft nodes before linearized reading' (duration: 177.334277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.675495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.175244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:47:31.67571Z","caller":"traceutil/trace.go:171","msg":"trace[2119473788] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"367.720185ms","start":"2026-04-28T06:47:31.307957Z","end":"2026-04-28T06:47:31.675677Z","steps":["trace[2119473788] 'process raft request' (duration: 172.246714ms)","trace[2119473788] 'compare' (duration: 194.756054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:31.676164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.307912Z","time spent":"368.186136ms","remote":"127.0.0.1:41428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:31.682862Z","caller":"traceutil/trace.go:171","msg":"trace[1664129911] linearizableReadLoop","detail":"{readStateIndex:26308; appliedIndex:26305; }","duration":"370.863231ms","start":"2026-04-28T06:47:31.311982Z","end":"2026-04-28T06:47:31.682846Z","steps":["trace[1664129911] 'read index received' (duration: 168.230709ms)","trace[1664129911] 'applied index is now lower than readState.Index' (duration: 202.631812ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:31.68293Z","caller":"traceutil/trace.go:171","msg":"trace[1005731266] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"374.834245ms","start":"2026-04-28T06:47:31.30806Z","end":"2026-04-28T06:47:31.682894Z","steps":["trace[1005731266] 'process raft request' (duration: 374.676141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.682998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.007714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-04-28T06:47:31.68298Z","caller":"traceutil/trace.go:171","msg":"trace[1056617722] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"374.890667ms","start":"2026-04-28T06:47:31.308049Z","end":"2026-04-28T06:47:31.68294Z","steps":["trace[1056617722] 'process raft request' (duration: 374.763574ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:31.683031Z","caller":"traceutil/trace.go:171","msg":"trace[1411167339] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25468; }","duration":"371.041575ms","start":"2026-04-28T06:47:31.311978Z","end":"2026-04-28T06:47:31.68302Z","steps":["trace[1411167339] 'agreement among raft nodes before linearized reading' (duration: 370.934283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:31.683066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.311971Z","time spent":"371.087946ms","remote":"127.0.0.1:34744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9628,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-04-28T06:47:31.683078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.308037Z","time spent":"374.965709ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:31.683153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.308032Z","time spent":"375.03331ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:32.017381Z","caller":"traceutil/trace.go:171","msg":"trace[281077549] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"512.645084ms","start":"2026-04-28T06:47:31.504701Z","end":"2026-04-28T06:47:32.017346Z","steps":["trace[281077549] 'process raft request' (duration: 504.923529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.017553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.504672Z","time spent":"512.786128ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:32.017605Z","caller":"traceutil/trace.go:171","msg":"trace[474801861] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"511.746082ms","start":"2026-04-28T06:47:31.5058Z","end":"2026-04-28T06:47:32.017547Z","steps":["trace[474801861] 'process raft request' (duration: 511.449485ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:32.017606Z","caller":"traceutil/trace.go:171","msg":"trace[1225014060] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"511.66859ms","start":"2026-04-28T06:47:31.505886Z","end":"2026-04-28T06:47:32.017555Z","steps":["trace[1225014060] 'process raft request' (duration: 511.557778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.017805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.50578Z","time spent":"511.923696ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:32.017842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.505861Z","time spent":"511.859054ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:32.017851Z","caller":"traceutil/trace.go:171","msg":"trace[2026037076] linearizableReadLoop","detail":"{readStateIndex:26313; appliedIndex:26308; }","duration":"334.932879ms","start":"2026-04-28T06:47:31.682895Z","end":"2026-04-28T06:47:32.017828Z","steps":["trace[2026037076] 'read index received' (duration: 326.590869ms)","trace[2026037076] 'applied index is now lower than readState.Index' (duration: 8.34133ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:32.017868Z","caller":"traceutil/trace.go:171","msg":"trace[703644846] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"511.816534ms","start":"2026-04-28T06:47:31.506021Z","end":"2026-04-28T06:47:32.017837Z","steps":["trace[703644846] 'process raft request' (duration: 511.478046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.017999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.999821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:32.017998Z","caller":"traceutil/trace.go:171","msg":"trace[1983054954] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"510.40614ms","start":"2026-04-28T06:47:31.507568Z","end":"2026-04-28T06:47:32.017974Z","steps":["trace[1983054954] 'process raft request' (duration: 510.163555ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:32.01803Z","caller":"traceutil/trace.go:171","msg":"trace[171695994] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25473; }","duration":"642.108544ms","start":"2026-04-28T06:47:31.37591Z","end":"2026-04-28T06:47:32.018019Z","steps":["trace[171695994] 'agreement among raft nodes before linearized reading' (duration: 642.044203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.018025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.506015Z","time spent":"511.921726ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:32.018056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.375885Z","time spent":"642.165355ms","remote":"127.0.0.1:34738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-28T06:47:32.018065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.449843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18aa72747cb35be4\" ","response":"range_response_count:1 size:736"} {"level":"warn","ts":"2026-04-28T06:47:32.018129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.80565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418379"} {"level":"warn","ts":"2026-04-28T06:47:32.018134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.507538Z","time spent":"510.507293ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:32.018142Z","caller":"traceutil/trace.go:171","msg":"trace[218459695] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18aa72747cb35be4; range_end:; response_count:1; response_revision:25473; }","duration":"338.573626ms","start":"2026-04-28T06:47:31.679547Z","end":"2026-04-28T06:47:32.018121Z","steps":["trace[218459695] 'agreement among raft nodes before linearized reading' (duration: 338.31063ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:32.018176Z","caller":"traceutil/trace.go:171","msg":"trace[1716682773] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25473; }","duration":"472.880913ms","start":"2026-04-28T06:47:31.545281Z","end":"2026-04-28T06:47:32.018161Z","steps":["trace[1716682773] 'agreement among raft nodes before linearized reading' (duration: 472.580255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.018186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.67953Z","time spent":"338.646468ms","remote":"127.0.0.1:41428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":760,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18aa72747cb35be4\" "} {"level":"warn","ts":"2026-04-28T06:47:32.018214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.545261Z","time spent":"472.945964ms","remote":"127.0.0.1:46224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418403,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-04-28T06:47:32.018212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.372851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-04-28T06:47:32.018278Z","caller":"traceutil/trace.go:171","msg":"trace[296930239] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25473; }","duration":"330.457352ms","start":"2026-04-28T06:47:31.6878Z","end":"2026-04-28T06:47:32.018258Z","steps":["trace[296930239] 'agreement among raft nodes before linearized reading' (duration: 330.253796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.018328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:31.687784Z","time spent":"330.527114ms","remote":"127.0.0.1:41496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1470,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-04-28T06:47:32.624055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.506545ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:47:32.624233Z","caller":"traceutil/trace.go:171","msg":"trace[1974617474] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25474; }","duration":"348.695939ms","start":"2026-04-28T06:47:32.275509Z","end":"2026-04-28T06:47:32.624205Z","steps":["trace[1974617474] 'range keys from in-memory index tree' (duration: 348.488454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.625111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.918356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:47:32.625309Z","caller":"traceutil/trace.go:171","msg":"trace[1167580094] transaction","detail":"{read_only:false; response_revision:25476; number_of_response:1; }","duration":"497.103852ms","start":"2026-04-28T06:47:32.128186Z","end":"2026-04-28T06:47:32.62529Z","steps":["trace[1167580094] 'process raft request' (duration: 497.03072ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:32.625275Z","caller":"traceutil/trace.go:171","msg":"trace[1267864377] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"532.883418ms","start":"2026-04-28T06:47:32.09234Z","end":"2026-04-28T06:47:32.625223Z","steps":["trace[1267864377] 'process raft request' (duration: 81.766297ms)","trace[1267864377] 'compare' (duration: 450.764483ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:32.625319Z","caller":"traceutil/trace.go:171","msg":"trace[1478385300] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26314; }","duration":"528.954755ms","start":"2026-04-28T06:47:32.096324Z","end":"2026-04-28T06:47:32.625279Z","steps":["trace[1478385300] 'read index received' (duration: 77.810534ms)","trace[1478385300] 'applied index is now lower than readState.Index' (duration: 451.140071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:32.625387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.128165Z","time spent":"497.180444ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:32.625687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.092321Z","time spent":"533.055213ms","remote":"127.0.0.1:41428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":729,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:32.625882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.517009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18745"} {"level":"info","ts":"2026-04-28T06:47:32.626026Z","caller":"traceutil/trace.go:171","msg":"trace[76734643] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25476; }","duration":"529.724304ms","start":"2026-04-28T06:47:32.09628Z","end":"2026-04-28T06:47:32.626005Z","steps":["trace[76734643] 'agreement among raft nodes before linearized reading' (duration: 529.346335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.626129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.096259Z","time spent":"529.854877ms","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18769,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T06:47:32.972685Z","caller":"traceutil/trace.go:171","msg":"trace[1748774103] linearizableReadLoop","detail":"{readStateIndex:26317; appliedIndex:26316; }","duration":"347.253755ms","start":"2026-04-28T06:47:32.625401Z","end":"2026-04-28T06:47:32.972655Z","steps":["trace[1748774103] 'read index received' (duration: 346.072297ms)","trace[1748774103] 'applied index is now lower than readState.Index' (duration: 1.180038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:32.972862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.972259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-04-28T06:47:32.972923Z","caller":"traceutil/trace.go:171","msg":"trace[86375017] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25477; }","duration":"624.071402ms","start":"2026-04-28T06:47:32.348835Z","end":"2026-04-28T06:47:32.972906Z","steps":["trace[86375017] 'agreement among raft nodes before linearized reading' (duration: 623.846476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.972917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.095281ms","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-04-28T06:47:32.972969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.338887ms","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-04-28T06:47:32.972988Z","caller":"traceutil/trace.go:171","msg":"trace[2088447993] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"496.984249ms","start":"2026-04-28T06:47:32.475996Z","end":"2026-04-28T06:47:32.97298Z","steps":["trace[2088447993] 'process raft request' (duration: 495.71384ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:32.972996Z","caller":"traceutil/trace.go:171","msg":"trace[48259886] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25477; }","duration":"829.225194ms","start":"2026-04-28T06:47:32.143753Z","end":"2026-04-28T06:47:32.972978Z","steps":["trace[48259886] 'agreement among raft nodes before linearized reading' (duration: 828.973318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.97308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.475969Z","time spent":"497.03859ms","remote":"127.0.0.1:46224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418437,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:32.973087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.508408ms","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:6"} {"level":"info","ts":"2026-04-28T06:47:32.974135Z","caller":"traceutil/trace.go:171","msg":"trace[1635736613] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25477; }","duration":"474.560023ms","start":"2026-04-28T06:47:32.499517Z","end":"2026-04-28T06:47:32.974077Z","steps":["trace[1635736613] 'agreement among raft nodes before linearized reading' (duration: 473.505327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.974209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.499492Z","time spent":"474.696726ms","remote":"127.0.0.1:53532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:47:32.97314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.594882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-04-28T06:47:32.974291Z","caller":"traceutil/trace.go:171","msg":"trace[229968910] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25477; }","duration":"333.773351ms","start":"2026-04-28T06:47:32.640497Z","end":"2026-04-28T06:47:32.97427Z","steps":["trace[229968910] 'agreement among raft nodes before linearized reading' (duration: 332.566681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.974356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.64048Z","time spent":"333.857954ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5676,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-28T06:47:32.973217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.868575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-28T06:47:32.974666Z","caller":"traceutil/trace.go:171","msg":"trace[770125652] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67; range_end:; response_count:1; response_revision:25477; }","duration":"665.599617ms","start":"2026-04-28T06:47:32.309049Z","end":"2026-04-28T06:47:32.974649Z","steps":["trace[770125652] 'agreement among raft nodes before linearized reading' (duration: 663.713241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.974743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.309027Z","time spent":"665.70155ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf79835-2b23-460f-860a-74b52df4ea67\" "} {"level":"warn","ts":"2026-04-28T06:47:32.973314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.810951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T06:47:32.97336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.06584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-28T06:47:32.973366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.825799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T06:47:32.972955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.348825Z","time spent":"624.123033ms","remote":"127.0.0.1:53520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3333,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-04-28T06:47:32.973008Z","caller":"traceutil/trace.go:171","msg":"trace[703943286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25477; }","duration":"852.397109ms","start":"2026-04-28T06:47:32.120599Z","end":"2026-04-28T06:47:32.972996Z","steps":["trace[703943286] 'agreement among raft nodes before linearized reading' (duration: 852.216805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.973568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.143735Z","time spent":"829.305326ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":557,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-28T06:47:32.975063Z","caller":"traceutil/trace.go:171","msg":"trace[130565857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25477; }","duration":"858.791841ms","start":"2026-04-28T06:47:32.116258Z","end":"2026-04-28T06:47:32.97505Z","steps":["trace[130565857] 'agreement among raft nodes before linearized reading' (duration: 857.028879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.975104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.116241Z","time spent":"858.852153ms","remote":"127.0.0.1:41596","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":"info","ts":"2026-04-28T06:47:32.975171Z","caller":"traceutil/trace.go:171","msg":"trace[1004535126] 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:25477; }","duration":"319.664414ms","start":"2026-04-28T06:47:32.655492Z","end":"2026-04-28T06:47:32.975156Z","steps":["trace[1004535126] 'agreement among raft nodes before linearized reading' (duration: 317.805869ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:32.975194Z","caller":"traceutil/trace.go:171","msg":"trace[970103057] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25477; }","duration":"591.721807ms","start":"2026-04-28T06:47:32.383452Z","end":"2026-04-28T06:47:32.975174Z","steps":["trace[970103057] 'agreement among raft nodes before linearized reading' (duration: 589.824731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:32.975317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.38343Z","time spent":"591.865421ms","remote":"127.0.0.1:38576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:47:32.975369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.655467Z","time spent":"319.765527ms","remote":"127.0.0.1:38202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-04-28T06:47:32.975699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:32.12053Z","time spent":"854.815707ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:47:37.82873Z","caller":"traceutil/trace.go:171","msg":"trace[1743927882] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"114.606775ms","start":"2026-04-28T06:47:37.714103Z","end":"2026-04-28T06:47:37.828709Z","steps":["trace[1743927882] 'process raft request' (duration: 114.484852ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:04.683642Z","caller":"traceutil/trace.go:171","msg":"trace[1844311085] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"139.616683ms","start":"2026-04-28T06:48:04.543997Z","end":"2026-04-28T06:48:04.683613Z","steps":["trace[1844311085] 'process raft request' (duration: 139.45383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:04.958483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.023773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:48:04.958765Z","caller":"traceutil/trace.go:171","msg":"trace[1048370204] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"274.648586ms","start":"2026-04-28T06:48:04.68407Z","end":"2026-04-28T06:48:04.958719Z","steps":["trace[1048370204] 'process raft request' (duration: 139.279285ms)","trace[1048370204] 'compare' (duration: 134.753366ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:04.958836Z","caller":"traceutil/trace.go:171","msg":"trace[612063165] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"203.172124ms","start":"2026-04-28T06:48:04.755629Z","end":"2026-04-28T06:48:04.958802Z","steps":["trace[612063165] 'process raft request' (duration: 202.98682ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.328067Z","caller":"traceutil/trace.go:171","msg":"trace[1286870458] linearizableReadLoop","detail":"{readStateIndex:26615; appliedIndex:26614; }","duration":"158.756241ms","start":"2026-04-28T06:48:05.169288Z","end":"2026-04-28T06:48:05.328045Z","steps":["trace[1286870458] 'read index received' (duration: 158.628548ms)","trace[1286870458] 'applied index is now lower than readState.Index' (duration: 126.753µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:05.328107Z","caller":"traceutil/trace.go:171","msg":"trace[1791775389] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"164.321425ms","start":"2026-04-28T06:48:05.163763Z","end":"2026-04-28T06:48:05.328085Z","steps":["trace[1791775389] 'process raft request' (duration: 164.1084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:05.328244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.935565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-28T06:48:05.328285Z","caller":"traceutil/trace.go:171","msg":"trace[1806804793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25767; }","duration":"159.015667ms","start":"2026-04-28T06:48:05.169258Z","end":"2026-04-28T06:48:05.328274Z","steps":["trace[1806804793] 'agreement among raft nodes before linearized reading' (duration: 158.877124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:05.539459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.338193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T06:48:05.539854Z","caller":"traceutil/trace.go:171","msg":"trace[2032887728] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"207.60062ms","start":"2026-04-28T06:48:05.332224Z","end":"2026-04-28T06:48:05.539825Z","steps":["trace[2032887728] 'process raft request' (duration: 98.792555ms)","trace[2032887728] 'compare' (duration: 108.19794ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:05.539968Z","caller":"traceutil/trace.go:171","msg":"trace[851474625] linearizableReadLoop","detail":"{readStateIndex:26617; appliedIndex:26616; }","duration":"125.233069ms","start":"2026-04-28T06:48:05.414671Z","end":"2026-04-28T06:48:05.539904Z","steps":["trace[851474625] 'read index received' (duration: 16.286071ms)","trace[851474625] 'applied index is now lower than readState.Index' (duration: 108.935398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:05.540236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.543686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T06:48:05.540314Z","caller":"traceutil/trace.go:171","msg":"trace[976594959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25770; }","duration":"125.613768ms","start":"2026-04-28T06:48:05.414657Z","end":"2026-04-28T06:48:05.54027Z","steps":["trace[976594959] 'agreement among raft nodes before linearized reading' (duration: 125.396643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:05.942465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.546558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:48:05.942834Z","caller":"traceutil/trace.go:171","msg":"trace[1500887235] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25771; }","duration":"146.967479ms","start":"2026-04-28T06:48:05.795841Z","end":"2026-04-28T06:48:05.942809Z","steps":["trace[1500887235] 'count revisions from in-memory index tree' (duration: 146.367374ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.196181Z","caller":"traceutil/trace.go:171","msg":"trace[1260387487] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"249.084644ms","start":"2026-04-28T06:48:05.947069Z","end":"2026-04-28T06:48:06.196153Z","steps":["trace[1260387487] 'process raft request' (duration: 248.940081ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.196192Z","caller":"traceutil/trace.go:171","msg":"trace[730537991] linearizableReadLoop","detail":"{readStateIndex:26620; appliedIndex:26619; }","duration":"242.61783ms","start":"2026-04-28T06:48:05.953529Z","end":"2026-04-28T06:48:06.196147Z","steps":["trace[730537991] 'read index received' (duration: 242.594379ms)","trace[730537991] 'applied index is now lower than readState.Index' (duration: 20.601µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:06.196493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.945307ms","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-04-28T06:48:06.196546Z","caller":"traceutil/trace.go:171","msg":"trace[520103827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25772; }","duration":"243.087551ms","start":"2026-04-28T06:48:05.953445Z","end":"2026-04-28T06:48:06.196532Z","steps":["trace[520103827] 'agreement among raft nodes before linearized reading' (duration: 242.840595ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.390452Z","caller":"traceutil/trace.go:171","msg":"trace[552142843] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"237.355842ms","start":"2026-04-28T06:48:06.15307Z","end":"2026-04-28T06:48:06.390426Z","steps":["trace[552142843] 'process raft request' (duration: 237.313701ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.390812Z","caller":"traceutil/trace.go:171","msg":"trace[480828686] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"239.601627ms","start":"2026-04-28T06:48:06.151158Z","end":"2026-04-28T06:48:06.390759Z","steps":["trace[480828686] 'process raft request' (duration: 239.161727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.390914Z","caller":"traceutil/trace.go:171","msg":"trace[1794291006] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"248.359716ms","start":"2026-04-28T06:48:06.142532Z","end":"2026-04-28T06:48:06.390891Z","steps":["trace[1794291006] 'process raft request' (duration: 195.398379ms)","trace[1794291006] 'compare' (duration: 52.208989ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:06.390909Z","caller":"traceutil/trace.go:171","msg":"trace[1933775644] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"237.985578ms","start":"2026-04-28T06:48:06.152861Z","end":"2026-04-28T06:48:06.390847Z","steps":["trace[1933775644] 'process raft request' (duration: 237.490866ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.391122Z","caller":"traceutil/trace.go:171","msg":"trace[1579862816] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"242.266212ms","start":"2026-04-28T06:48:06.148824Z","end":"2026-04-28T06:48:06.39109Z","steps":["trace[1579862816] 'process raft request' (duration: 241.451351ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.48254Z","caller":"traceutil/trace.go:171","msg":"trace[1875258197] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"282.627317ms","start":"2026-04-28T06:48:06.199878Z","end":"2026-04-28T06:48:06.482506Z","steps":["trace[1875258197] 'process raft request' (duration: 282.329ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:06.482892Z","caller":"traceutil/trace.go:171","msg":"trace[1620995539] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"281.95456ms","start":"2026-04-28T06:48:06.200595Z","end":"2026-04-28T06:48:06.48255Z","steps":["trace[1620995539] 'process raft request' (duration: 281.821517ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:10.624886Z","caller":"traceutil/trace.go:171","msg":"trace[1906061195] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"203.246506ms","start":"2026-04-28T06:48:10.4216Z","end":"2026-04-28T06:48:10.624847Z","steps":["trace[1906061195] 'process raft request' (duration: 151.038636ms)","trace[1906061195] 'compare' (duration: 51.739109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:10.625137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.727531ms","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-04-28T06:48:10.625187Z","caller":"traceutil/trace.go:171","msg":"trace[732815128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25807; }","duration":"125.867444ms","start":"2026-04-28T06:48:10.499309Z","end":"2026-04-28T06:48:10.625176Z","steps":["trace[732815128] 'agreement among raft nodes before linearized reading' (duration: 125.650929ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:10.624906Z","caller":"traceutil/trace.go:171","msg":"trace[1162570176] linearizableReadLoop","detail":"{readStateIndex:26656; appliedIndex:26651; }","duration":"125.506835ms","start":"2026-04-28T06:48:10.499375Z","end":"2026-04-28T06:48:10.624881Z","steps":["trace[1162570176] 'read index received' (duration: 73.232163ms)","trace[1162570176] 'applied index is now lower than readState.Index' (duration: 52.273632ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:10.625398Z","caller":"traceutil/trace.go:171","msg":"trace[96008169] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"202.844446ms","start":"2026-04-28T06:48:10.422539Z","end":"2026-04-28T06:48:10.625383Z","steps":["trace[96008169] 'process raft request' (duration: 202.232202ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:10.62544Z","caller":"traceutil/trace.go:171","msg":"trace[226713087] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"199.905846ms","start":"2026-04-28T06:48:10.425518Z","end":"2026-04-28T06:48:10.625424Z","steps":["trace[226713087] 'process raft request' (duration: 199.322132ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:10.625447Z","caller":"traceutil/trace.go:171","msg":"trace[1040402704] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"202.839946ms","start":"2026-04-28T06:48:10.422573Z","end":"2026-04-28T06:48:10.625413Z","steps":["trace[1040402704] 'process raft request' (duration: 202.234261ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:10.625494Z","caller":"traceutil/trace.go:171","msg":"trace[251718848] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"203.00505ms","start":"2026-04-28T06:48:10.422468Z","end":"2026-04-28T06:48:10.625473Z","steps":["trace[251718848] 'process raft request' (duration: 202.191931ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:15.117006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22950} {"level":"info","ts":"2026-04-28T06:48:15.297515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22950,"took":"175.871141ms","hash":3727714989,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":35172352,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-28T06:48:15.297569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3727714989,"revision":22950,"compact-revision":20184} {"level":"info","ts":"2026-04-28T06:49:50.523766Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c05.wal"} {"level":"info","ts":"2026-04-28T06:51:48.415248Z","caller":"traceutil/trace.go:171","msg":"trace[1252951368] transaction","detail":"{read_only:false; response_revision:28049; number_of_response:1; }","duration":"133.622187ms","start":"2026-04-28T06:51:48.281582Z","end":"2026-04-28T06:51:48.415205Z","steps":["trace[1252951368] 'process raft request' (duration: 92.238157ms)","trace[1252951368] 'compare' (duration: 41.240317ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:51:48.562475Z","caller":"traceutil/trace.go:171","msg":"trace[1729688239] linearizableReadLoop","detail":"{readStateIndex:28954; appliedIndex:28953; }","duration":"138.121064ms","start":"2026-04-28T06:51:48.424321Z","end":"2026-04-28T06:51:48.562442Z","steps":["trace[1729688239] 'read index received' (duration: 128.511774ms)","trace[1729688239] 'applied index is now lower than readState.Index' (duration: 9.6081ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:51:48.562539Z","caller":"traceutil/trace.go:171","msg":"trace[422596493] transaction","detail":"{read_only:false; response_revision:28051; number_of_response:1; }","duration":"141.028323ms","start":"2026-04-28T06:51:48.421484Z","end":"2026-04-28T06:51:48.562512Z","steps":["trace[422596493] 'process raft request' (duration: 131.382053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:51:48.562603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.258968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T06:51:48.562694Z","caller":"traceutil/trace.go:171","msg":"trace[547232416] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:28051; }","duration":"138.373471ms","start":"2026-04-28T06:51:48.4243Z","end":"2026-04-28T06:51:48.562673Z","steps":["trace[547232416] 'agreement among raft nodes before linearized reading' (duration: 138.250217ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:15.124593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25841} {"level":"info","ts":"2026-04-28T06:53:15.258534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25841,"took":"130.544493ms","hash":4210876268,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":37330944,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-28T06:53:15.258606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4210876268,"revision":25841,"compact-revision":22950} {"level":"info","ts":"2026-04-28T06:54:03.910254Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T06:54:03.916097Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-28T06:54:03.916217Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-28T06:58:15.130334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28710} {"level":"info","ts":"2026-04-28T06:58:15.289259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28710,"took":"154.87796ms","hash":2049817918,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":30146560,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-28T06:58:15.289331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2049817918,"revision":28710,"compact-revision":25841} {"level":"info","ts":"2026-04-28T07:03:15.137276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30934} {"level":"info","ts":"2026-04-28T07:03:15.256908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30934,"took":"114.969845ms","hash":71100486,"current-db-size-bytes":58503168,"current-db-size":"58 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T07:03:15.256997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":71100486,"revision":30934,"compact-revision":28710} {"level":"info","ts":"2026-04-28T07:08:15.144182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33262} {"level":"info","ts":"2026-04-28T07:08:15.3158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33262,"took":"167.505658ms","hash":887653328,"current-db-size-bytes":59543552,"current-db-size":"60 MB","current-db-size-in-use-bytes":31739904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T07:08:15.315954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":887653328,"revision":33262,"compact-revision":30934} {"level":"info","ts":"2026-04-28T07:13:15.184809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35588} {"level":"info","ts":"2026-04-28T07:13:15.606648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35588,"took":"416.261019ms","hash":1821528586,"current-db-size-bytes":60002304,"current-db-size":"60 MB","current-db-size-in-use-bytes":31444992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T07:13:15.60673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1821528586,"revision":35588,"compact-revision":33262} {"level":"info","ts":"2026-04-28T07:15:18.348369Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T07:15:18.354945Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-28T07:15:18.355053Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-28T07:18:15.191232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37816} {"level":"info","ts":"2026-04-28T07:18:15.306786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37816,"took":"110.574315ms","hash":3689994906,"current-db-size-bytes":60002304,"current-db-size":"60 MB","current-db-size-in-use-bytes":31309824,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T07:18:15.306838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3689994906,"revision":37816,"compact-revision":35588}