{"level":"warn","ts":"2026-04-06T17:05:56.670086Z","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-06T17:05:56.670175Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221: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-06T17:05:56.670237Z","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-06T17:05:56.67025Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-04-06T17:05:56.670269Z","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-06T17:05:56.670887Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-04-06T17:05:56.670962Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221: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-06T17:05:56.67508Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.923263ms"} {"level":"info","ts":"2026-04-06T17:05:56.683024Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-04-06T17:05:56.683119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T17:05:56.683149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-04-06T17:05:56.68317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T17:05:56.683187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-04-06T17:05:56.68323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-04-06T17:05:56.690403Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T17:05:56.692089Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T17:05:56.693316Z","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-06T17:05:56.697064Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T17:05:56.697354Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T17:05:56.697432Z","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-06T17:05:56.697554Z","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-06T17:05:56.69759Z","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-06T17:05:56.700135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-04-06T17:05:56.70043Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-04-06T17:05:56.702521Z","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-06T17:05:56.702673Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-04-06T17:05:56.702739Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-04-06T17:05:56.702874Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T17:05:56.702915Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T17:05:57.184182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T17:05:57.18424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T17:05:57.184267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-04-06T17:05:57.184281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-04-06T17:05:57.184292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-04-06T17:05:57.184303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-04-06T17:05:57.184321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-04-06T17:05:57.185787Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:57.18671Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T17:05:57.186871Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T17:05:57.186971Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T17:05:57.18763Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:57.187699Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:57.18772Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:57.188824Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T17:05:57.188875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T17:05:57.189799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T17:05:57.190567Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"info","ts":"2026-04-06T17:06:01.750763Z","caller":"traceutil/trace.go:171","msg":"trace[232133935] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:229; }","duration":"172.662548ms","start":"2026-04-06T17:06:01.578063Z","end":"2026-04-06T17:06:01.750726Z","steps":["trace[232133935] 'read index received' (duration: 172.529084ms)","trace[232133935] 'applied index is now lower than readState.Index' (duration: 132.034µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:06:01.750865Z","caller":"traceutil/trace.go:171","msg":"trace[740732160] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"195.725104ms","start":"2026-04-06T17:06:01.555118Z","end":"2026-04-06T17:06:01.750843Z","steps":["trace[740732160] 'process raft request' (duration: 195.417417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:01.750972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.883293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:06:01.751111Z","caller":"traceutil/trace.go:171","msg":"trace[1174255039] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:224; }","duration":"173.067147ms","start":"2026-04-06T17:06:01.578021Z","end":"2026-04-06T17:06:01.751089Z","steps":["trace[1174255039] 'agreement among raft nodes before linearized reading' (duration: 172.850882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:08.604697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.54253ms","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-06T17:06:08.604811Z","caller":"traceutil/trace.go:171","msg":"trace[1952674760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:350; }","duration":"100.700584ms","start":"2026-04-06T17:06:08.504093Z","end":"2026-04-06T17:06:08.604793Z","steps":["trace[1952674760] 'range keys from in-memory index tree' (duration: 100.438327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:14.917514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.701542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:06:14.917704Z","caller":"traceutil/trace.go:171","msg":"trace[169764947] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"275.146064ms","start":"2026-04-06T17:06:14.64253Z","end":"2026-04-06T17:06:14.917676Z","steps":["trace[169764947] 'process raft request' (duration: 32.364476ms)","trace[169764947] 'compare' (duration: 241.491127ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:06:14.918125Z","caller":"traceutil/trace.go:171","msg":"trace[890291676] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"231.562762ms","start":"2026-04-06T17:06:14.686547Z","end":"2026-04-06T17:06:14.91811Z","steps":["trace[890291676] 'read index received' (duration: 41.171µs)","trace[890291676] 'applied index is now lower than readState.Index' (duration: 231.520121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:06:14.918219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.671134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-06T17:06:14.918258Z","caller":"traceutil/trace.go:171","msg":"trace[1422510095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"231.711645ms","start":"2026-04-06T17:06:14.686536Z","end":"2026-04-06T17:06:14.918248Z","steps":["trace[1422510095] 'agreement among raft nodes before linearized reading' (duration: 231.617793ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:15.050644Z","caller":"traceutil/trace.go:171","msg":"trace[548676954] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"127.836966ms","start":"2026-04-06T17:06:14.922777Z","end":"2026-04-06T17:06:15.050614Z","steps":["trace[548676954] 'process raft request' (duration: 127.672912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:35.732242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.18545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:06:35.73243Z","caller":"traceutil/trace.go:171","msg":"trace[1780065100] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"447.958347ms","start":"2026-04-06T17:06:35.284455Z","end":"2026-04-06T17:06:35.732413Z","steps":["trace[1780065100] 'read index received' (duration: 228.474259ms)","trace[1780065100] 'applied index is now lower than readState.Index' (duration: 219.483088ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:06:35.732496Z","caller":"traceutil/trace.go:171","msg":"trace[1712637212] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"559.268262ms","start":"2026-04-06T17:06:35.173201Z","end":"2026-04-06T17:06:35.732469Z","steps":["trace[1712637212] 'process raft request' (duration: 559.15721ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:35.732506Z","caller":"traceutil/trace.go:171","msg":"trace[871549565] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"560.917832ms","start":"2026-04-06T17:06:35.171545Z","end":"2026-04-06T17:06:35.732463Z","steps":["trace[871549565] 'process raft request' (duration: 341.396114ms)","trace[871549565] 'compare' (duration: 218.981775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:06:35.732605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.245329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:06:35.732687Z","caller":"traceutil/trace.go:171","msg":"trace[166335842] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:589; }","duration":"164.410493ms","start":"2026-04-06T17:06:35.568261Z","end":"2026-04-06T17:06:35.732672Z","steps":["trace[166335842] 'agreement among raft nodes before linearized reading' (duration: 164.25605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:35.732699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:35.171517Z","time spent":"561.074315ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:06:35.732708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.365219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z64bb\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-06T17:06:35.732693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.375793ms","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-06T17:06:35.73286Z","caller":"traceutil/trace.go:171","msg":"trace[580562125] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:589; }","duration":"164.626379ms","start":"2026-04-06T17:06:35.568208Z","end":"2026-04-06T17:06:35.732834Z","steps":["trace[580562125] 'agreement among raft nodes before linearized reading' (duration: 164.333512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:35.732607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:35.173177Z","time spent":"559.376055ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:06:35.73259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.125171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:06:35.732995Z","caller":"traceutil/trace.go:171","msg":"trace[861106708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"448.595743ms","start":"2026-04-06T17:06:35.284387Z","end":"2026-04-06T17:06:35.732983Z","steps":["trace[861106708] 'agreement among raft nodes before linearized reading' (duration: 448.108551ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:35.732792Z","caller":"traceutil/trace.go:171","msg":"trace[1002919736] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z64bb; range_end:; response_count:1; response_revision:589; }","duration":"356.448491ms","start":"2026-04-06T17:06:35.376332Z","end":"2026-04-06T17:06:35.732781Z","steps":["trace[1002919736] 'agreement among raft nodes before linearized reading' (duration: 356.178404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:35.733057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:35.376273Z","time spent":"356.778779ms","remote":"127.0.0.1:39446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-z64bb\" "} {"level":"warn","ts":"2026-04-06T17:06:35.733029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:35.284358Z","time spent":"448.663863ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-06T17:06:59.073888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.723034ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:06:59.074213Z","caller":"traceutil/trace.go:171","msg":"trace[1199532168] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:765; }","duration":"158.078092ms","start":"2026-04-06T17:06:58.916098Z","end":"2026-04-06T17:06:59.074176Z","steps":["trace[1199532168] 'range keys from in-memory index tree' (duration: 157.689063ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:18.999517Z","caller":"traceutil/trace.go:171","msg":"trace[1499642099] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"135.124323ms","start":"2026-04-06T17:08:18.864364Z","end":"2026-04-06T17:08:18.999488Z","steps":["trace[1499642099] 'read index received' (duration: 134.838696ms)","trace[1499642099] 'applied index is now lower than readState.Index' (duration: 284.286µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:18.999572Z","caller":"traceutil/trace.go:171","msg":"trace[172733271] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"155.928628ms","start":"2026-04-06T17:08:18.843609Z","end":"2026-04-06T17:08:18.999538Z","steps":["trace[172733271] 'process raft request' (duration: 155.705042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:18.999724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.346087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89b2063f-039b-4dab-8cda-3eace1b70b16\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-06T17:08:18.999762Z","caller":"traceutil/trace.go:171","msg":"trace[931083014] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89b2063f-039b-4dab-8cda-3eace1b70b16; range_end:; response_count:1; response_revision:1847; }","duration":"135.44448ms","start":"2026-04-06T17:08:18.864305Z","end":"2026-04-06T17:08:18.99975Z","steps":["trace[931083014] 'agreement among raft nodes before linearized reading' (duration: 135.289446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:19.627214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.722556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-06T17:08:19.627275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.35044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-06T17:08:19.627335Z","caller":"traceutil/trace.go:171","msg":"trace[1284973945] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1850; }","duration":"286.881071ms","start":"2026-04-06T17:08:19.34043Z","end":"2026-04-06T17:08:19.627311Z","steps":["trace[1284973945] 'count revisions from in-memory index tree' (duration: 286.597824ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:19.62736Z","caller":"traceutil/trace.go:171","msg":"trace[178259367] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1850; }","duration":"125.496123ms","start":"2026-04-06T17:08:19.50184Z","end":"2026-04-06T17:08:19.627336Z","steps":["trace[178259367] 'range keys from in-memory index tree' (duration: 125.212017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:19.627441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.673435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89b2063f-039b-4dab-8cda-3eace1b70b16\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-06T17:08:19.627549Z","caller":"traceutil/trace.go:171","msg":"trace[173439171] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89b2063f-039b-4dab-8cda-3eace1b70b16; range_end:; response_count:1; response_revision:1850; }","duration":"148.831919ms","start":"2026-04-06T17:08:19.478691Z","end":"2026-04-06T17:08:19.627523Z","steps":["trace[173439171] 'range keys from in-memory index tree' (duration: 148.373608ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:25.10859Z","caller":"traceutil/trace.go:171","msg":"trace[1867481269] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1981; }","duration":"112.145096ms","start":"2026-04-06T17:08:24.996419Z","end":"2026-04-06T17:08:25.108564Z","steps":["trace[1867481269] 'read index received' (duration: 111.946401ms)","trace[1867481269] 'applied index is now lower than readState.Index' (duration: 189.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:25.108676Z","caller":"traceutil/trace.go:171","msg":"trace[1253208693] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"138.963893ms","start":"2026-04-06T17:08:24.96967Z","end":"2026-04-06T17:08:25.108634Z","steps":["trace[1253208693] 'process raft request' (duration: 138.687097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:25.108842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.386752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:08:25.108903Z","caller":"traceutil/trace.go:171","msg":"trace[1770570091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1903; }","duration":"112.521405ms","start":"2026-04-06T17:08:24.996368Z","end":"2026-04-06T17:08:25.10889Z","steps":["trace[1770570091] 'agreement among raft nodes before linearized reading' (duration: 112.34512ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:31.409201Z","caller":"traceutil/trace.go:171","msg":"trace[305907562] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"115.030935ms","start":"2026-04-06T17:08:31.294144Z","end":"2026-04-06T17:08:31.409175Z","steps":["trace[305907562] 'process raft request' (duration: 114.864261ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:33.910112Z","caller":"traceutil/trace.go:171","msg":"trace[483393541] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"139.286361ms","start":"2026-04-06T17:08:33.770798Z","end":"2026-04-06T17:08:33.910085Z","steps":["trace[483393541] 'process raft request' (duration: 100.11534ms)","trace[483393541] 'compare' (duration: 38.434514ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:35.484917Z","caller":"traceutil/trace.go:171","msg":"trace[1649746601] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"153.721905ms","start":"2026-04-06T17:08:35.331166Z","end":"2026-04-06T17:08:35.484888Z","steps":["trace[1649746601] 'process raft request' (duration: 153.534281ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:35.490081Z","caller":"traceutil/trace.go:171","msg":"trace[671435401] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"149.250468ms","start":"2026-04-06T17:08:35.34081Z","end":"2026-04-06T17:08:35.490061Z","steps":["trace[671435401] 'process raft request' (duration: 149.060014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:36.038607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.121744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-06T17:08:36.03871Z","caller":"traceutil/trace.go:171","msg":"trace[219064732] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1985; }","duration":"125.245517ms","start":"2026-04-06T17:08:35.91344Z","end":"2026-04-06T17:08:36.038685Z","steps":["trace[219064732] 'range keys from in-memory index tree' (duration: 124.983211ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:36.040849Z","caller":"traceutil/trace.go:171","msg":"trace[115413171] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2067; }","duration":"112.259439ms","start":"2026-04-06T17:08:35.928575Z","end":"2026-04-06T17:08:36.040834Z","steps":["trace[115413171] 'read index received' (duration: 112.110106ms)","trace[115413171] 'applied index is now lower than readState.Index' (duration: 148.473µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:08:36.040985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.412173ms","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-06T17:08:36.041016Z","caller":"traceutil/trace.go:171","msg":"trace[1538710700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1986; }","duration":"112.481924ms","start":"2026-04-06T17:08:35.928523Z","end":"2026-04-06T17:08:36.041005Z","steps":["trace[1538710700] 'agreement among raft nodes before linearized reading' (duration: 112.373352ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:36.041011Z","caller":"traceutil/trace.go:171","msg":"trace[64010406] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"125.544843ms","start":"2026-04-06T17:08:35.915419Z","end":"2026-04-06T17:08:36.040964Z","steps":["trace[64010406] 'process raft request' (duration: 125.269487ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:08.25984Z","caller":"traceutil/trace.go:171","msg":"trace[1791492820] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"110.60059ms","start":"2026-04-06T17:09:08.149191Z","end":"2026-04-06T17:09:08.259792Z","steps":["trace[1791492820] 'process raft request' (duration: 110.430046ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:14.083147Z","caller":"traceutil/trace.go:171","msg":"trace[92035588] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"130.573123ms","start":"2026-04-06T17:09:13.95254Z","end":"2026-04-06T17:09:14.083113Z","steps":["trace[92035588] 'process raft request' (duration: 130.286576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:15.160208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.493832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:09:15.160396Z","caller":"traceutil/trace.go:171","msg":"trace[225169264] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"413.491965ms","start":"2026-04-06T17:09:14.746868Z","end":"2026-04-06T17:09:15.16036Z","steps":["trace[225169264] 'process raft request' (duration: 184.740666ms)","trace[225169264] 'compare' (duration: 228.334229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:15.160547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:14.746845Z","time spent":"413.623818ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:10:22.797688Z","caller":"traceutil/trace.go:171","msg":"trace[987871486] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"136.995126ms","start":"2026-04-06T17:10:22.660665Z","end":"2026-04-06T17:10:22.79766Z","steps":["trace[987871486] 'process raft request' (duration: 136.804741ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:22.833206Z","caller":"traceutil/trace.go:171","msg":"trace[651811217] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"167.246877ms","start":"2026-04-06T17:10:22.665937Z","end":"2026-04-06T17:10:22.833184Z","steps":["trace[651811217] 'process raft request' (duration: 162.610247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:23.219077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.333818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-06T17:10:23.219214Z","caller":"traceutil/trace.go:171","msg":"trace[2018272221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2763; }","duration":"143.524291ms","start":"2026-04-06T17:10:23.075658Z","end":"2026-04-06T17:10:23.219182Z","steps":["trace[2018272221] 'range keys from in-memory index tree' (duration: 143.00831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:12.826736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.917187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-04-06T17:14:12.826924Z","caller":"traceutil/trace.go:171","msg":"trace[328241483] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4782; }","duration":"267.157803ms","start":"2026-04-06T17:14:12.559744Z","end":"2026-04-06T17:14:12.826902Z","steps":["trace[328241483] 'range keys from in-memory index tree' (duration: 266.692593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:12.826969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.715754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:14:12.827086Z","caller":"traceutil/trace.go:171","msg":"trace[715175571] linearizableReadLoop","detail":"{readStateIndex:4972; appliedIndex:4971; }","duration":"226.014081ms","start":"2026-04-06T17:14:12.60105Z","end":"2026-04-06T17:14:12.827064Z","steps":["trace[715175571] 'read index received' (duration: 69.239626ms)","trace[715175571] 'applied index is now lower than readState.Index' (duration: 156.772665ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:12.827237Z","caller":"traceutil/trace.go:171","msg":"trace[1019393464] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"260.408494ms","start":"2026-04-06T17:14:12.566705Z","end":"2026-04-06T17:14:12.827113Z","steps":["trace[1019393464] 'process raft request' (duration: 103.454995ms)","trace[1019393464] 'compare' (duration: 156.55641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:12.827633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.567134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:521845"} {"level":"info","ts":"2026-04-06T17:14:12.827705Z","caller":"traceutil/trace.go:171","msg":"trace[512411424] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4783; }","duration":"226.679547ms","start":"2026-04-06T17:14:12.601007Z","end":"2026-04-06T17:14:12.827687Z","steps":["trace[512411424] 'agreement among raft nodes before linearized reading' (duration: 226.147654ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:12.835241Z","caller":"traceutil/trace.go:171","msg":"trace[1109025131] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"217.117361ms","start":"2026-04-06T17:14:12.6181Z","end":"2026-04-06T17:14:12.835218Z","steps":["trace[1109025131] 'process raft request' (duration: 216.987868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:12.835349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.205427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tjwwl\" ","response":"range_response_count:1 size:9716"} {"level":"info","ts":"2026-04-06T17:14:12.835397Z","caller":"traceutil/trace.go:171","msg":"trace[1917900665] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tjwwl; range_end:; response_count:1; response_revision:4784; }","duration":"218.292619ms","start":"2026-04-06T17:14:12.61709Z","end":"2026-04-06T17:14:12.835383Z","steps":["trace[1917900665] 'agreement among raft nodes before linearized reading' (duration: 218.127175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:12.835721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.913462ms","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-06T17:14:12.835803Z","caller":"traceutil/trace.go:171","msg":"trace[731516762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4784; }","duration":"158.033044ms","start":"2026-04-06T17:14:12.677747Z","end":"2026-04-06T17:14:12.83578Z","steps":["trace[731516762] 'agreement among raft nodes before linearized reading' (duration: 157.788428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:17.30431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.701492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-06T17:14:17.304545Z","caller":"traceutil/trace.go:171","msg":"trace[1214600498] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"349.728944ms","start":"2026-04-06T17:14:16.954797Z","end":"2026-04-06T17:14:17.304526Z","steps":["trace[1214600498] 'read index received' (duration: 162.94252ms)","trace[1214600498] 'applied index is now lower than readState.Index' (duration: 186.785384ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:17.304577Z","caller":"traceutil/trace.go:171","msg":"trace[448422440] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4918; }","duration":"367.848013ms","start":"2026-04-06T17:14:16.9367Z","end":"2026-04-06T17:14:17.304548Z","steps":["trace[448422440] 'process raft request' (duration: 180.804822ms)","trace[448422440] 'compare' (duration: 186.638151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:17.304698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:16.936673Z","time spent":"367.970395ms","remote":"127.0.0.1:39358","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:14:17.304695Z","caller":"traceutil/trace.go:171","msg":"trace[1785360323] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"288.6266ms","start":"2026-04-06T17:14:17.016034Z","end":"2026-04-06T17:14:17.30466Z","steps":["trace[1785360323] 'process raft request' (duration: 288.425666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:17.304851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.042351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07032d4a-d152-4472-ac9b-0f4ea150b102\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-06T17:14:17.30489Z","caller":"traceutil/trace.go:171","msg":"trace[1038167691] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07032d4a-d152-4472-ac9b-0f4ea150b102; range_end:; response_count:1; response_revision:4919; }","duration":"350.130654ms","start":"2026-04-06T17:14:16.954748Z","end":"2026-04-06T17:14:17.304879Z","steps":["trace[1038167691] 'agreement among raft nodes before linearized reading' (duration: 349.870567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:17.304928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:16.954729Z","time spent":"350.190895ms","remote":"127.0.0.1:39446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07032d4a-d152-4472-ac9b-0f4ea150b102\" "} {"level":"warn","ts":"2026-04-06T17:14:17.305002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.707288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-06T17:14:17.305096Z","caller":"traceutil/trace.go:171","msg":"trace[1773272124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4919; }","duration":"160.833211ms","start":"2026-04-06T17:14:17.144233Z","end":"2026-04-06T17:14:17.305067Z","steps":["trace[1773272124] 'agreement among raft nodes before linearized reading' (duration: 160.620646ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:17.950285Z","caller":"traceutil/trace.go:171","msg":"trace[200352922] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5124; }","duration":"304.340242ms","start":"2026-04-06T17:14:17.645926Z","end":"2026-04-06T17:14:17.950266Z","steps":["trace[200352922] 'read index received' (duration: 304.127817ms)","trace[200352922] 'applied index is now lower than readState.Index' (duration: 211.765µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:17.95041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.482216ms","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:5"} {"level":"info","ts":"2026-04-06T17:14:17.950397Z","caller":"traceutil/trace.go:171","msg":"trace[704936279] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"363.525531ms","start":"2026-04-06T17:14:17.58685Z","end":"2026-04-06T17:14:17.950376Z","steps":["trace[704936279] 'process raft request' (duration: 363.314696ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:17.950439Z","caller":"traceutil/trace.go:171","msg":"trace[79321163] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4933; }","duration":"304.571858ms","start":"2026-04-06T17:14:17.645858Z","end":"2026-04-06T17:14:17.95043Z","steps":["trace[79321163] 'agreement among raft nodes before linearized reading' (duration: 304.512587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:17.950463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:17.645836Z","time spent":"304.621159ms","remote":"127.0.0.1:57894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:14:17.9505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.785835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kjkd9\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-06T17:14:17.950513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:17.586819Z","time spent":"363.627323ms","remote":"127.0.0.1:39330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58177,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:14:17.950551Z","caller":"traceutil/trace.go:171","msg":"trace[1903583889] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kjkd9; range_end:; response_count:1; response_revision:4933; }","duration":"302.861167ms","start":"2026-04-06T17:14:17.647675Z","end":"2026-04-06T17:14:17.950536Z","steps":["trace[1903583889] 'agreement among raft nodes before linearized reading' (duration: 302.732604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:17.950582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:17.647662Z","time spent":"302.913838ms","remote":"127.0.0.1:39446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-kjkd9\" "} {"level":"warn","ts":"2026-04-06T17:14:17.950645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.346116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07032d4a-d152-4472-ac9b-0f4ea150b102\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-06T17:14:17.950724Z","caller":"traceutil/trace.go:171","msg":"trace[1514937549] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07032d4a-d152-4472-ac9b-0f4ea150b102; range_end:; response_count:1; response_revision:4933; }","duration":"294.457538ms","start":"2026-04-06T17:14:17.656247Z","end":"2026-04-06T17:14:17.950704Z","steps":["trace[1514937549] 'agreement among raft nodes before linearized reading' (duration: 294.192792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:17.950731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.487367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-06T17:14:17.950812Z","caller":"traceutil/trace.go:171","msg":"trace[146950905] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4933; }","duration":"252.60741ms","start":"2026-04-06T17:14:17.698186Z","end":"2026-04-06T17:14:17.950793Z","steps":["trace[146950905] 'agreement among raft nodes before linearized reading' (duration: 252.250501ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:17.950368Z","caller":"traceutil/trace.go:171","msg":"trace[995274025] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"374.709326ms","start":"2026-04-06T17:14:17.575619Z","end":"2026-04-06T17:14:17.950329Z","steps":["trace[995274025] 'process raft request' (duration: 374.414899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:17.951593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:17.575603Z","time spent":"375.75566ms","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:14:18.155487Z","caller":"traceutil/trace.go:171","msg":"trace[1918720804] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"128.005856ms","start":"2026-04-06T17:14:18.027457Z","end":"2026-04-06T17:14:18.155463Z","steps":["trace[1918720804] 'process raft request' (duration: 127.7723ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:18.15581Z","caller":"traceutil/trace.go:171","msg":"trace[224737864] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"127.974994ms","start":"2026-04-06T17:14:18.027803Z","end":"2026-04-06T17:14:18.155778Z","steps":["trace[224737864] 'process raft request' (duration: 127.586345ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:28.185212Z","caller":"traceutil/trace.go:171","msg":"trace[962919762] linearizableReadLoop","detail":"{readStateIndex:5337; appliedIndex:5336; }","duration":"122.816543ms","start":"2026-04-06T17:14:28.06237Z","end":"2026-04-06T17:14:28.185187Z","steps":["trace[962919762] 'read index received' (duration: 122.605608ms)","trace[962919762] 'applied index is now lower than readState.Index' (duration: 209.825µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:28.185255Z","caller":"traceutil/trace.go:171","msg":"trace[1567820216] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"150.154369ms","start":"2026-04-06T17:14:28.035078Z","end":"2026-04-06T17:14:28.185233Z","steps":["trace[1567820216] 'process raft request' (duration: 149.927993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:28.185455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.062008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-06T17:14:28.185478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.435033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kjkd9\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-06T17:14:28.185519Z","caller":"traceutil/trace.go:171","msg":"trace[1903898479] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5138; }","duration":"123.161871ms","start":"2026-04-06T17:14:28.062335Z","end":"2026-04-06T17:14:28.185497Z","steps":["trace[1903898479] 'agreement among raft nodes before linearized reading' (duration: 122.970086ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:28.18553Z","caller":"traceutil/trace.go:171","msg":"trace[887967485] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kjkd9; range_end:; response_count:1; response_revision:5138; }","duration":"119.506494ms","start":"2026-04-06T17:14:28.066006Z","end":"2026-04-06T17:14:28.185512Z","steps":["trace[887967485] 'agreement among raft nodes before linearized reading' (duration: 119.353961ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:28.637056Z","caller":"traceutil/trace.go:171","msg":"trace[740018883] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"106.947937ms","start":"2026-04-06T17:14:28.530075Z","end":"2026-04-06T17:14:28.637023Z","steps":["trace[740018883] 'process raft request' (duration: 106.753813ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:28.659911Z","caller":"traceutil/trace.go:171","msg":"trace[1953608352] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5338; }","duration":"107.501771ms","start":"2026-04-06T17:14:28.552387Z","end":"2026-04-06T17:14:28.659889Z","steps":["trace[1953608352] 'read index received' (duration: 84.418015ms)","trace[1953608352] 'applied index is now lower than readState.Index' (duration: 23.082426ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:28.660019Z","caller":"traceutil/trace.go:171","msg":"trace[1759543411] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"114.538197ms","start":"2026-04-06T17:14:28.545424Z","end":"2026-04-06T17:14:28.659962Z","steps":["trace[1759543411] 'process raft request' (duration: 114.328672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:28.660099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.688835ms","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-06T17:14:28.660133Z","caller":"traceutil/trace.go:171","msg":"trace[123141990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5141; }","duration":"107.765017ms","start":"2026-04-06T17:14:28.552356Z","end":"2026-04-06T17:14:28.660121Z","steps":["trace[123141990] 'agreement among raft nodes before linearized reading' (duration: 107.616674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:30.048192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.048501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-2n2k9\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-04-06T17:14:30.048345Z","caller":"traceutil/trace.go:171","msg":"trace[1861476350] range","detail":"{range_begin:/registry/pods/monitoring/vector-2n2k9; range_end:; response_count:1; response_revision:5159; }","duration":"278.264466ms","start":"2026-04-06T17:14:29.770047Z","end":"2026-04-06T17:14:30.048311Z","steps":["trace[1861476350] 'range keys from in-memory index tree' (duration: 277.729713ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:36.840883Z","caller":"traceutil/trace.go:171","msg":"trace[1890086182] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"172.144428ms","start":"2026-04-06T17:14:36.668711Z","end":"2026-04-06T17:14:36.840855Z","steps":["trace[1890086182] 'process raft request' (duration: 171.945163ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:36.993346Z","caller":"traceutil/trace.go:171","msg":"trace[1287869141] linearizableReadLoop","detail":"{readStateIndex:5521; appliedIndex:5519; }","duration":"256.85439ms","start":"2026-04-06T17:14:36.736466Z","end":"2026-04-06T17:14:36.99332Z","steps":["trace[1287869141] 'read index received' (duration: 104.210853ms)","trace[1287869141] 'applied index is now lower than readState.Index' (duration: 152.642567ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:36.993436Z","caller":"traceutil/trace.go:171","msg":"trace[450916148] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"302.787536ms","start":"2026-04-06T17:14:36.690613Z","end":"2026-04-06T17:14:36.9934Z","steps":["trace[450916148] 'process raft request' (duration: 221.043034ms)","trace[450916148] 'compare' (duration: 81.355093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:36.993528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.265749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:14:36.993563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.088655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-06T17:14:36.99358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:36.690578Z","time spent":"302.923899ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:14:36.993593Z","caller":"traceutil/trace.go:171","msg":"trace[151005766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5317; }","duration":"232.384122ms","start":"2026-04-06T17:14:36.761189Z","end":"2026-04-06T17:14:36.993573Z","steps":["trace[151005766] 'agreement among raft nodes before linearized reading' (duration: 232.277409ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:36.993638Z","caller":"traceutil/trace.go:171","msg":"trace[115986760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5317; }","duration":"257.205478ms","start":"2026-04-06T17:14:36.736414Z","end":"2026-04-06T17:14:36.993619Z","steps":["trace[115986760] 'agreement among raft nodes before linearized reading' (duration: 257.009413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:36.993694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.501472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e02b1b9d-536c-4243-9dfe-c296c87de0ea\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-06T17:14:36.993757Z","caller":"traceutil/trace.go:171","msg":"trace[546383123] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e02b1b9d-536c-4243-9dfe-c296c87de0ea; range_end:; response_count:1; response_revision:5317; }","duration":"146.596305ms","start":"2026-04-06T17:14:36.847142Z","end":"2026-04-06T17:14:36.993739Z","steps":["trace[546383123] 'agreement among raft nodes before linearized reading' (duration: 146.40326ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:37.103878Z","caller":"traceutil/trace.go:171","msg":"trace[501713013] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"102.484932ms","start":"2026-04-06T17:14:37.001362Z","end":"2026-04-06T17:14:37.103847Z","steps":["trace[501713013] 'process raft request' (duration: 102.171165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:39.074561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.128869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-06T17:14:39.074655Z","caller":"traceutil/trace.go:171","msg":"trace[23490410] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5341; }","duration":"120.276092ms","start":"2026-04-06T17:14:38.954358Z","end":"2026-04-06T17:14:39.074634Z","steps":["trace[23490410] 'range keys from in-memory index tree' (duration: 119.842882ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:15:57.238192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2951} {"level":"info","ts":"2026-04-06T17:15:57.307162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2951,"took":"67.479661ms","hash":205727662,"current-db-size-bytes":57417728,"current-db-size":"57 MB","current-db-size-in-use-bytes":33771520,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-06T17:15:57.307214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":205727662,"revision":2951,"compact-revision":-1} {"level":"info","ts":"2026-04-06T17:20:37.472563Z","caller":"traceutil/trace.go:171","msg":"trace[566901750] transaction","detail":"{read_only:false; response_revision:8128; number_of_response:1; }","duration":"163.869153ms","start":"2026-04-06T17:20:37.30866Z","end":"2026-04-06T17:20:37.472529Z","steps":["trace[566901750] 'process raft request' (duration: 163.628898ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:50.37271Z","caller":"traceutil/trace.go:171","msg":"trace[1714149096] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"259.321166ms","start":"2026-04-06T17:20:50.113357Z","end":"2026-04-06T17:20:50.372678Z","steps":["trace[1714149096] 'process raft request' (duration: 259.099801ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:50.416017Z","caller":"traceutil/trace.go:171","msg":"trace[77636856] linearizableReadLoop","detail":"{readStateIndex:8487; appliedIndex:8485; }","duration":"208.728903ms","start":"2026-04-06T17:20:50.207255Z","end":"2026-04-06T17:20:50.415983Z","steps":["trace[77636856] 'read index received' (duration: 165.290357ms)","trace[77636856] 'applied index is now lower than readState.Index' (duration: 43.437156ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:50.416047Z","caller":"traceutil/trace.go:171","msg":"trace[96103542] transaction","detail":"{read_only:false; response_revision:8195; number_of_response:1; }","duration":"245.430842ms","start":"2026-04-06T17:20:50.170586Z","end":"2026-04-06T17:20:50.416017Z","steps":["trace[96103542] 'process raft request' (duration: 245.230357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:50.416235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.82463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:20:50.416279Z","caller":"traceutil/trace.go:171","msg":"trace[1718399384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8195; }","duration":"130.938894ms","start":"2026-04-06T17:20:50.285329Z","end":"2026-04-06T17:20:50.416268Z","steps":["trace[1718399384] 'agreement among raft nodes before linearized reading' (duration: 130.844101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:50.416335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.04994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-06T17:20:50.416386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.552089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-06T17:20:50.41644Z","caller":"traceutil/trace.go:171","msg":"trace[1970888659] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8195; }","duration":"111.697453ms","start":"2026-04-06T17:20:50.304729Z","end":"2026-04-06T17:20:50.416427Z","steps":["trace[1970888659] 'agreement among raft nodes before linearized reading' (duration: 111.505759ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:50.416448Z","caller":"traceutil/trace.go:171","msg":"trace[1873802991] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:8195; }","duration":"209.247455ms","start":"2026-04-06T17:20:50.207177Z","end":"2026-04-06T17:20:50.416424Z","steps":["trace[1873802991] 'agreement among raft nodes before linearized reading' (duration: 208.982939ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:50.550561Z","caller":"traceutil/trace.go:171","msg":"trace[570582334] linearizableReadLoop","detail":"{readStateIndex:8488; appliedIndex:8487; }","duration":"129.568591ms","start":"2026-04-06T17:20:50.420955Z","end":"2026-04-06T17:20:50.550524Z","steps":["trace[570582334] 'read index received' (duration: 85.854428ms)","trace[570582334] 'applied index is now lower than readState.Index' (duration: 43.712373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:20:50.551081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.105983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223863"} {"level":"info","ts":"2026-04-06T17:20:50.551127Z","caller":"traceutil/trace.go:171","msg":"trace[1140127521] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8195; }","duration":"130.231097ms","start":"2026-04-06T17:20:50.420885Z","end":"2026-04-06T17:20:50.551116Z","steps":["trace[1140127521] 'agreement among raft nodes before linearized reading' (duration: 129.780516ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:50.591318Z","caller":"traceutil/trace.go:171","msg":"trace[1134313424] transaction","detail":"{read_only:false; response_revision:8196; number_of_response:1; }","duration":"169.828102ms","start":"2026-04-06T17:20:50.42145Z","end":"2026-04-06T17:20:50.591278Z","steps":["trace[1134313424] 'process raft request' (duration: 169.527575ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:50.737596Z","caller":"traceutil/trace.go:171","msg":"trace[555485277] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"212.537681ms","start":"2026-04-06T17:20:50.52504Z","end":"2026-04-06T17:20:50.737578Z","steps":["trace[555485277] 'process raft request' (duration: 196.139808ms)","trace[555485277] 'compare' (duration: 16.24393ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:50.737794Z","caller":"traceutil/trace.go:171","msg":"trace[493901601] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"186.052742ms","start":"2026-04-06T17:20:50.551731Z","end":"2026-04-06T17:20:50.737783Z","steps":["trace[493901601] 'process raft request' (duration: 185.796766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:51.157252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.406115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:20:51.157746Z","caller":"traceutil/trace.go:171","msg":"trace[657431502] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8201; }","duration":"108.786485ms","start":"2026-04-06T17:20:51.048648Z","end":"2026-04-06T17:20:51.157435Z","steps":["trace[657431502] 'count revisions from in-memory index tree' (duration: 108.259992ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:51.933836Z","caller":"traceutil/trace.go:171","msg":"trace[720893996] linearizableReadLoop","detail":"{readStateIndex:8495; appliedIndex:8494; }","duration":"319.780251ms","start":"2026-04-06T17:20:51.614029Z","end":"2026-04-06T17:20:51.93381Z","steps":["trace[720893996] 'read index received' (duration: 319.593726ms)","trace[720893996] 'applied index is now lower than readState.Index' (duration: 185.445µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:51.93388Z","caller":"traceutil/trace.go:171","msg":"trace[1518318924] transaction","detail":"{read_only:false; response_revision:8202; number_of_response:1; }","duration":"379.134568ms","start":"2026-04-06T17:20:51.554707Z","end":"2026-04-06T17:20:51.933841Z","steps":["trace[1518318924] 'process raft request' (duration: 378.933034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:51.934044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.554674Z","time spent":"379.282582ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:20:51.934367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.307623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-06T17:20:51.934465Z","caller":"traceutil/trace.go:171","msg":"trace[2101553082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8202; }","duration":"320.488597ms","start":"2026-04-06T17:20:51.613954Z","end":"2026-04-06T17:20:51.934443Z","steps":["trace[2101553082] 'agreement among raft nodes before linearized reading' (duration: 319.954135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:51.934516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.613923Z","time spent":"320.583659ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-06T17:20:52.300168Z","caller":"traceutil/trace.go:171","msg":"trace[1395666534] transaction","detail":"{read_only:false; response_revision:8203; number_of_response:1; }","duration":"361.559068ms","start":"2026-04-06T17:20:51.938583Z","end":"2026-04-06T17:20:52.300142Z","steps":["trace[1395666534] 'process raft request' (duration: 361.353063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:52.300342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.93855Z","time spent":"361.701011ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:20:52.74575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.593472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-06T17:20:52.745782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.698107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kjkd9\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-06T17:20:52.745858Z","caller":"traceutil/trace.go:171","msg":"trace[2141057751] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8205; }","duration":"150.751766ms","start":"2026-04-06T17:20:52.595082Z","end":"2026-04-06T17:20:52.745834Z","steps":["trace[2141057751] 'range keys from in-memory index tree' (duration: 150.408648ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:52.74587Z","caller":"traceutil/trace.go:171","msg":"trace[880737547] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kjkd9; range_end:; response_count:1; response_revision:8205; }","duration":"186.85846ms","start":"2026-04-06T17:20:52.558991Z","end":"2026-04-06T17:20:52.745849Z","steps":["trace[880737547] 'range keys from in-memory index tree' (duration: 186.445411ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:57.250871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6066} {"level":"info","ts":"2026-04-06T17:20:57.353605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6066,"took":"100.730566ms","hash":2759109569,"current-db-size-bytes":57417728,"current-db-size":"57 MB","current-db-size-in-use-bytes":23048192,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-06T17:20:57.3537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2759109569,"revision":6066,"compact-revision":2951} {"level":"info","ts":"2026-04-06T17:21:22.275731Z","caller":"traceutil/trace.go:171","msg":"trace[1530520230] linearizableReadLoop","detail":"{readStateIndex:8722; appliedIndex:8721; }","duration":"103.530621ms","start":"2026-04-06T17:21:22.172173Z","end":"2026-04-06T17:21:22.275704Z","steps":["trace[1530520230] 'read index received' (duration: 103.122472ms)","trace[1530520230] 'applied index is now lower than readState.Index' (duration: 407.229µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:21:22.275787Z","caller":"traceutil/trace.go:171","msg":"trace[1492802097] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"158.411635ms","start":"2026-04-06T17:21:22.117362Z","end":"2026-04-06T17:21:22.275773Z","steps":["trace[1492802097] 'process raft request' (duration: 158.030156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:22.27591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.718137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-06T17:21:22.275946Z","caller":"traceutil/trace.go:171","msg":"trace[1828025490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8420; }","duration":"103.812509ms","start":"2026-04-06T17:21:22.172121Z","end":"2026-04-06T17:21:22.275933Z","steps":["trace[1828025490] 'agreement among raft nodes before linearized reading' (duration: 103.691916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:23.601436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.454256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:21:23.601561Z","caller":"traceutil/trace.go:171","msg":"trace[1718555670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8429; }","duration":"317.632141ms","start":"2026-04-06T17:21:23.283904Z","end":"2026-04-06T17:21:23.601536Z","steps":["trace[1718555670] 'range keys from in-memory index tree' (duration: 317.385064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:23.60154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.955637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-04-06T17:21:23.601641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:21:23.283892Z","time spent":"317.738573ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-06T17:21:23.601989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.868528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:21:23.602034Z","caller":"traceutil/trace.go:171","msg":"trace[1214531464] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8429; }","duration":"235.97249ms","start":"2026-04-06T17:21:23.366053Z","end":"2026-04-06T17:21:23.602026Z","steps":["trace[1214531464] 'count revisions from in-memory index tree' (duration: 235.776476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:23.602154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.431459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:21:23.602217Z","caller":"traceutil/trace.go:171","msg":"trace[518296126] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8429; }","duration":"324.500781ms","start":"2026-04-06T17:21:23.277698Z","end":"2026-04-06T17:21:23.602199Z","steps":["trace[518296126] 'count revisions from in-memory index tree' (duration: 324.324847ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:23.601642Z","caller":"traceutil/trace.go:171","msg":"trace[342786708] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8429; }","duration":"185.171421ms","start":"2026-04-06T17:21:23.416448Z","end":"2026-04-06T17:21:23.601619Z","steps":["trace[342786708] 'range keys from in-memory index tree' (duration: 184.727241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:23.602262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:21:23.2776Z","time spent":"324.650614ms","remote":"127.0.0.1:39272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":92,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-06T17:21:33.224476Z","caller":"traceutil/trace.go:171","msg":"trace[1146953489] linearizableReadLoop","detail":"{readStateIndex:8830; appliedIndex:8829; }","duration":"202.2256ms","start":"2026-04-06T17:21:33.022219Z","end":"2026-04-06T17:21:33.224444Z","steps":["trace[1146953489] 'read index received' (duration: 202.19412ms)","trace[1146953489] 'applied index is now lower than readState.Index' (duration: 30.02µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:21:33.224653Z","caller":"traceutil/trace.go:171","msg":"trace[1007174267] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"289.166384ms","start":"2026-04-06T17:21:32.935474Z","end":"2026-04-06T17:21:33.22464Z","steps":["trace[1007174267] 'process raft request' (duration: 288.803035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:33.224971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.722062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kjkd9\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-06T17:21:33.225075Z","caller":"traceutil/trace.go:171","msg":"trace[1117789233] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kjkd9; range_end:; response_count:1; response_revision:8523; }","duration":"202.898617ms","start":"2026-04-06T17:21:33.022153Z","end":"2026-04-06T17:21:33.225052Z","steps":["trace[1117789233] 'agreement among raft nodes before linearized reading' (duration: 202.584289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:33.237707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.35261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-06T17:21:33.23776Z","caller":"traceutil/trace.go:171","msg":"trace[87424509] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8524; }","duration":"142.451713ms","start":"2026-04-06T17:21:33.095296Z","end":"2026-04-06T17:21:33.237748Z","steps":["trace[87424509] 'agreement among raft nodes before linearized reading' (duration: 141.421099ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:33.478486Z","caller":"traceutil/trace.go:171","msg":"trace[1296392037] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"238.832926ms","start":"2026-04-06T17:21:33.239602Z","end":"2026-04-06T17:21:33.478435Z","steps":["trace[1296392037] 'process raft request' (duration: 142.893932ms)","trace[1296392037] 'compare' (duration: 95.463603ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:21:33.486676Z","caller":"traceutil/trace.go:171","msg":"trace[1596060346] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8831; }","duration":"202.559877ms","start":"2026-04-06T17:21:33.284099Z","end":"2026-04-06T17:21:33.486659Z","steps":["trace[1596060346] 'read index received' (duration: 98.619887ms)","trace[1596060346] 'applied index is now lower than readState.Index' (duration: 103.93902ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:21:33.486921Z","caller":"traceutil/trace.go:171","msg":"trace[2026281327] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"247.252083ms","start":"2026-04-06T17:21:33.239658Z","end":"2026-04-06T17:21:33.48691Z","steps":["trace[2026281327] 'process raft request' (duration: 246.750521ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:33.487064Z","caller":"traceutil/trace.go:171","msg":"trace[104789004] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"246.784271ms","start":"2026-04-06T17:21:33.24027Z","end":"2026-04-06T17:21:33.487054Z","steps":["trace[104789004] 'process raft request' (duration: 246.226818ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:33.487272Z","caller":"traceutil/trace.go:171","msg":"trace[1941087773] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"127.069911ms","start":"2026-04-06T17:21:33.360193Z","end":"2026-04-06T17:21:33.487262Z","steps":["trace[1941087773] 'process raft request' (duration: 126.347794ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:33.487518Z","caller":"traceutil/trace.go:171","msg":"trace[451094089] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"120.078178ms","start":"2026-04-06T17:21:33.367429Z","end":"2026-04-06T17:21:33.487507Z","steps":["trace[451094089] 'process raft request' (duration: 119.188487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:33.487667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.548021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:21:33.487699Z","caller":"traceutil/trace.go:171","msg":"trace[958751288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8529; }","duration":"203.633152ms","start":"2026-04-06T17:21:33.284057Z","end":"2026-04-06T17:21:33.48769Z","steps":["trace[958751288] 'agreement among raft nodes before linearized reading' (duration: 203.566581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:33.487948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.248659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-fhk8z\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-04-06T17:21:33.487993Z","caller":"traceutil/trace.go:171","msg":"trace[2001534116] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-fhk8z; range_end:; response_count:1; response_revision:8529; }","duration":"128.29467ms","start":"2026-04-06T17:21:33.359686Z","end":"2026-04-06T17:21:33.487981Z","steps":["trace[2001534116] 'agreement among raft nodes before linearized reading' (duration: 128.098955ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:22:22.198297Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002437.wal"} {"level":"info","ts":"2026-04-06T17:22:49.158695Z","caller":"traceutil/trace.go:171","msg":"trace[1987656892] linearizableReadLoop","detail":"{readStateIndex:9592; appliedIndex:9591; }","duration":"207.0576ms","start":"2026-04-06T17:22:48.951601Z","end":"2026-04-06T17:22:49.158659Z","steps":["trace[1987656892] 'read index received' (duration: 206.771264ms)","trace[1987656892] 'applied index is now lower than readState.Index' (duration: 285.056µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:22:49.158779Z","caller":"traceutil/trace.go:171","msg":"trace[1788274147] transaction","detail":"{read_only:false; response_revision:9264; number_of_response:1; }","duration":"274.611779ms","start":"2026-04-06T17:22:48.884117Z","end":"2026-04-06T17:22:49.158729Z","steps":["trace[1788274147] 'process raft request' (duration: 274.345973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:22:49.159043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.21107ms","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-06T17:22:49.159094Z","caller":"traceutil/trace.go:171","msg":"trace[1501072907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9264; }","duration":"132.297763ms","start":"2026-04-06T17:22:49.026783Z","end":"2026-04-06T17:22:49.159081Z","steps":["trace[1501072907] 'agreement among raft nodes before linearized reading' (duration: 132.070588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:22:49.159129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.474551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-06T17:22:49.159256Z","caller":"traceutil/trace.go:171","msg":"trace[978250692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9264; }","duration":"207.690516ms","start":"2026-04-06T17:22:48.951535Z","end":"2026-04-06T17:22:49.159225Z","steps":["trace[978250692] 'agreement among raft nodes before linearized reading' (duration: 207.310877ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:22:49.432305Z","caller":"traceutil/trace.go:171","msg":"trace[1232834273] transaction","detail":"{read_only:false; response_revision:9267; number_of_response:1; }","duration":"135.835655ms","start":"2026-04-06T17:22:49.29643Z","end":"2026-04-06T17:22:49.432266Z","steps":["trace[1232834273] 'process raft request' (duration: 135.564559ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:23:43.472368Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c187bdee547e8fd1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-06T17:23:43.47856Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-06T17:23:43.478645Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-06T17:25:02.658525Z","caller":"traceutil/trace.go:171","msg":"trace[175788240] transaction","detail":"{read_only:false; response_revision:10280; number_of_response:1; }","duration":"149.947636ms","start":"2026-04-06T17:25:02.50855Z","end":"2026-04-06T17:25:02.658498Z","steps":["trace[175788240] 'process raft request' (duration: 149.789952ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:25:06.806331Z","caller":"traceutil/trace.go:171","msg":"trace[1268520523] linearizableReadLoop","detail":"{readStateIndex:10675; appliedIndex:10674; }","duration":"135.481328ms","start":"2026-04-06T17:25:06.670813Z","end":"2026-04-06T17:25:06.806295Z","steps":["trace[1268520523] 'read index received' (duration: 127.8963ms)","trace[1268520523] 'applied index is now lower than readState.Index' (duration: 7.582898ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:25:06.806386Z","caller":"traceutil/trace.go:171","msg":"trace[1583129043] transaction","detail":"{read_only:false; response_revision:10312; number_of_response:1; }","duration":"151.549574ms","start":"2026-04-06T17:25:06.654801Z","end":"2026-04-06T17:25:06.806351Z","steps":["trace[1583129043] 'process raft request' (duration: 143.803873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:25:06.806766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.032076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kjkd9\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-06T17:25:06.80683Z","caller":"traceutil/trace.go:171","msg":"trace[218944488] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kjkd9; range_end:; response_count:1; response_revision:10312; }","duration":"111.155599ms","start":"2026-04-06T17:25:06.695658Z","end":"2026-04-06T17:25:06.806814Z","steps":["trace[218944488] 'agreement among raft nodes before linearized reading' (duration: 110.880263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:25:06.806835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.245147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-06T17:25:06.806952Z","caller":"traceutil/trace.go:171","msg":"trace[1878691699] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10312; }","duration":"104.581195ms","start":"2026-04-06T17:25:06.702338Z","end":"2026-04-06T17:25:06.806919Z","steps":["trace[1878691699] 'agreement among raft nodes before linearized reading' (duration: 104.302509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:25:06.806933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.02155ms","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-06T17:25:06.807183Z","caller":"traceutil/trace.go:171","msg":"trace[963742270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10312; }","duration":"136.344298ms","start":"2026-04-06T17:25:06.670761Z","end":"2026-04-06T17:25:06.807105Z","steps":["trace[963742270] 'agreement among raft nodes before linearized reading' (duration: 135.728313ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:25:57.258544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8241} {"level":"info","ts":"2026-04-06T17:25:57.36827Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8241,"took":"107.313498ms","hash":4107822384,"current-db-size-bytes":57417728,"current-db-size":"57 MB","current-db-size-in-use-bytes":24727552,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-06T17:25:57.368331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4107822384,"revision":8241,"compact-revision":6066} {"level":"info","ts":"2026-04-06T17:30:38.271182Z","caller":"traceutil/trace.go:171","msg":"trace[760711587] transaction","detail":"{read_only:false; response_revision:12073; number_of_response:1; }","duration":"119.610726ms","start":"2026-04-06T17:30:38.151538Z","end":"2026-04-06T17:30:38.271148Z","steps":["trace[760711587] 'process raft request' (duration: 119.473693ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:30:57.264261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10625} {"level":"info","ts":"2026-04-06T17:30:57.344322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10625,"took":"76.99755ms","hash":1676011419,"current-db-size-bytes":57417728,"current-db-size":"57 MB","current-db-size-in-use-bytes":20340736,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-06T17:30:57.34439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1676011419,"revision":10625,"compact-revision":8241}