{"level":"warn","ts":"2026-04-27T20:53:44.448675Z","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-27T20:53:44.448766Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-27T20:53:44.448829Z","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-27T20:53:44.448843Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-27T20:53:44.448873Z","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-27T20:53:44.449469Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-27T20:53:44.449542Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-27T20:53:44.454184Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.432986ms"} {"level":"info","ts":"2026-04-27T20:53:44.462048Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-27T20:53:44.462112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T20:53:44.462136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-27T20:53:44.462149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T20:53:44.462162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-27T20:53:44.462199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-27T20:53:44.468916Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T20:53:44.470244Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T20:53:44.472092Z","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-27T20:53:44.474453Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T20:53:44.476296Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T20:53:44.47918Z","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-27T20:53:44.47927Z","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-27T20:53:44.479643Z","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-27T20:53:44.481251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-27T20:53:44.481371Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-27T20:53:44.481712Z","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-27T20:53:44.481829Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-27T20:53:44.481892Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-27T20:53:44.483515Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T20:53:44.483565Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T20:53:44.963111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T20:53:44.963183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T20:53:44.963237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-27T20:53:44.963263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-27T20:53:44.963279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-27T20:53:44.963301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-27T20:53:44.963322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-27T20:53:44.964171Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:53:44.964311Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T20:53:44.964592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:53:44.964684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:53:44.965185Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:53:44.965361Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:53:44.965458Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:53:44.96823Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-27T20:53:44.968267Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T20:53:44.968325Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T20:53:44.968878Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T20:53:46.663951Z","caller":"traceutil/trace.go:171","msg":"trace[2059721292] linearizableReadLoop","detail":"{readStateIndex:73; appliedIndex:72; }","duration":"141.344901ms","start":"2026-04-27T20:53:46.522578Z","end":"2026-04-27T20:53:46.663923Z","steps":["trace[2059721292] 'read index received' (duration: 140.999187ms)","trace[2059721292] 'applied index is now lower than readState.Index' (duration: 344.564µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:53:46.664242Z","caller":"traceutil/trace.go:171","msg":"trace[1429845404] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"239.863907ms","start":"2026-04-27T20:53:46.424342Z","end":"2026-04-27T20:53:46.664206Z","steps":["trace[1429845404] 'process raft request' (duration: 239.299631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:53:46.664361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.630694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T20:53:46.664424Z","caller":"traceutil/trace.go:171","msg":"trace[598474137] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:69; }","duration":"141.845567ms","start":"2026-04-27T20:53:46.522563Z","end":"2026-04-27T20:53:46.664408Z","steps":["trace[598474137] 'agreement among raft nodes before linearized reading' (duration: 141.573614ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:53:46.805143Z","caller":"traceutil/trace.go:171","msg":"trace[1447708467] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"134.144293ms","start":"2026-04-27T20:53:46.670975Z","end":"2026-04-27T20:53:46.805119Z","steps":["trace[1447708467] 'process raft request' (duration: 128.674236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:53:51.744548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.739418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T20:53:51.74457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.472597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T20:53:51.744621Z","caller":"traceutil/trace.go:171","msg":"trace[1802149971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:275; }","duration":"302.866199ms","start":"2026-04-27T20:53:51.441736Z","end":"2026-04-27T20:53:51.744602Z","steps":["trace[1802149971] 'range keys from in-memory index tree' (duration: 302.642477ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:53:51.744641Z","caller":"traceutil/trace.go:171","msg":"trace[57768471] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:275; }","duration":"431.596388ms","start":"2026-04-27T20:53:51.313024Z","end":"2026-04-27T20:53:51.744621Z","steps":["trace[57768471] 'range keys from in-memory index tree' (duration: 431.374546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:53:51.744683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:53:51.313003Z","time spent":"431.67258ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T20:53:51.744653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:53:51.441715Z","time spent":"302.932061ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T20:53:52.267575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898865311027342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T20:53:52.586303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.26881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:53:52.586647Z","caller":"traceutil/trace.go:171","msg":"trace[990520988] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:288; }","duration":"819.844214ms","start":"2026-04-27T20:53:51.766784Z","end":"2026-04-27T20:53:52.586628Z","steps":["trace[990520988] 'read index received' (duration: 673.591834ms)","trace[990520988] 'applied index is now lower than readState.Index' (duration: 146.2508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:53:52.586786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.972337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:53:52.586884Z","caller":"traceutil/trace.go:171","msg":"trace[443617327] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:277; }","duration":"820.111578ms","start":"2026-04-27T20:53:51.766749Z","end":"2026-04-27T20:53:52.58686Z","steps":["trace[443617327] 'agreement among raft nodes before linearized reading' (duration: 819.950066ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:53:52.586671Z","caller":"traceutil/trace.go:171","msg":"trace[610775919] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"837.165089ms","start":"2026-04-27T20:53:51.74948Z","end":"2026-04-27T20:53:52.586645Z","steps":["trace[610775919] 'process raft request' (duration: 690.98385ms)","trace[610775919] 'compare' (duration: 145.128278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:53:52.586934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:53:51.766731Z","time spent":"820.194309ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-27T20:53:52.587012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:53:51.749462Z","time spent":"837.486924ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:53:52.587065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.525237ms","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-27T20:53:52.587115Z","caller":"traceutil/trace.go:171","msg":"trace[144141695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:277; }","duration":"186.611578ms","start":"2026-04-27T20:53:52.400493Z","end":"2026-04-27T20:53:52.587105Z","steps":["trace[144141695] 'agreement among raft nodes before linearized reading' (duration: 186.533907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:53:52.587235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.55148ms","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-27T20:53:52.587432Z","caller":"traceutil/trace.go:171","msg":"trace[840755163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:277; }","duration":"667.799324ms","start":"2026-04-27T20:53:51.919615Z","end":"2026-04-27T20:53:52.587414Z","steps":["trace[840755163] 'agreement among raft nodes before linearized reading' (duration: 667.400099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:53:52.587581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:53:51.919587Z","time spent":"667.940065ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T20:54:01.195966Z","caller":"traceutil/trace.go:171","msg":"trace[722749392] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"106.653164ms","start":"2026-04-27T20:54:01.089284Z","end":"2026-04-27T20:54:01.195937Z","steps":["trace[722749392] 'process raft request' (duration: 47.4415ms)","trace[722749392] 'compare' (duration: 59.086743ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:54:01.199483Z","caller":"traceutil/trace.go:171","msg":"trace[669183456] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"108.28195ms","start":"2026-04-27T20:54:01.091132Z","end":"2026-04-27T20:54:01.199414Z","steps":["trace[669183456] 'process raft request' (duration: 108.125728ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.656154Z","caller":"traceutil/trace.go:171","msg":"trace[1889962126] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"131.717882ms","start":"2026-04-27T20:54:13.52441Z","end":"2026-04-27T20:54:13.656128Z","steps":["trace[1889962126] 'process raft request' (duration: 131.53451ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.661975Z","caller":"traceutil/trace.go:171","msg":"trace[1550471541] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:480; }","duration":"119.903933ms","start":"2026-04-27T20:54:13.542057Z","end":"2026-04-27T20:54:13.661961Z","steps":["trace[1550471541] 'read index received' (duration: 113.854683ms)","trace[1550471541] 'applied index is now lower than readState.Index' (duration: 6.04873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:54:13.662062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.991104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:54:13.662081Z","caller":"traceutil/trace.go:171","msg":"trace[1337621191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"120.050704ms","start":"2026-04-27T20:54:13.542026Z","end":"2026-04-27T20:54:13.662077Z","steps":["trace[1337621191] 'agreement among raft nodes before linearized reading' (duration: 119.992854ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.662109Z","caller":"traceutil/trace.go:171","msg":"trace[1899009689] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"101.309758ms","start":"2026-04-27T20:54:13.560779Z","end":"2026-04-27T20:54:13.662089Z","steps":["trace[1899009689] 'process raft request' (duration: 101.002895ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.662337Z","caller":"traceutil/trace.go:171","msg":"trace[566371705] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"131.183987ms","start":"2026-04-27T20:54:13.531143Z","end":"2026-04-27T20:54:13.662327Z","steps":["trace[566371705] 'process raft request' (duration: 130.496792ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.662417Z","caller":"traceutil/trace.go:171","msg":"trace[1247650410] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"112.078907ms","start":"2026-04-27T20:54:13.550331Z","end":"2026-04-27T20:54:13.66241Z","steps":["trace[1247650410] 'process raft request' (duration: 111.384102ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.662485Z","caller":"traceutil/trace.go:171","msg":"trace[404742548] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"105.994067ms","start":"2026-04-27T20:54:13.556484Z","end":"2026-04-27T20:54:13.662478Z","steps":["trace[404742548] 'process raft request' (duration: 105.252961ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.662545Z","caller":"traceutil/trace.go:171","msg":"trace[92511319] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"103.179833ms","start":"2026-04-27T20:54:13.559359Z","end":"2026-04-27T20:54:13.662539Z","steps":["trace[92511319] 'process raft request' (duration: 102.398927ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.90462Z","caller":"traceutil/trace.go:171","msg":"trace[1716222852] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"164.506596ms","start":"2026-04-27T20:54:13.740092Z","end":"2026-04-27T20:54:13.904598Z","steps":["trace[1716222852] 'process raft request' (duration: 164.312124ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.911197Z","caller":"traceutil/trace.go:171","msg":"trace[105694224] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"168.795902ms","start":"2026-04-27T20:54:13.742379Z","end":"2026-04-27T20:54:13.911175Z","steps":["trace[105694224] 'process raft request' (duration: 168.445929ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.911823Z","caller":"traceutil/trace.go:171","msg":"trace[1180213292] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"168.619131ms","start":"2026-04-27T20:54:13.743184Z","end":"2026-04-27T20:54:13.911803Z","steps":["trace[1180213292] 'process raft request' (duration: 167.813684ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.91228Z","caller":"traceutil/trace.go:171","msg":"trace[1900047819] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"161.886274ms","start":"2026-04-27T20:54:13.750379Z","end":"2026-04-27T20:54:13.912265Z","steps":["trace[1900047819] 'process raft request' (duration: 161.818374ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:13.912661Z","caller":"traceutil/trace.go:171","msg":"trace[735647746] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"166.543423ms","start":"2026-04-27T20:54:13.746105Z","end":"2026-04-27T20:54:13.912649Z","steps":["trace[735647746] 'process raft request' (duration: 165.610756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:20.283951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898865311027905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T20:54:20.78458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898865311027905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T20:54:20.82822Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126830968s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T20:54:20.82965Z","caller":"traceutil/trace.go:171","msg":"trace[961331153] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.129112606s","start":"2026-04-27T20:54:19.700492Z","end":"2026-04-27T20:54:20.829604Z","steps":["trace[961331153] 'process raft request' (duration: 1.12836996s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:20.829817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:19.700465Z","time spent":"1.129262198s","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:54:20.872034Z","caller":"traceutil/trace.go:171","msg":"trace[733588533] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"500.691444ms","start":"2026-04-27T20:54:20.37132Z","end":"2026-04-27T20:54:20.872011Z","steps":["trace[733588533] 'process raft request' (duration: 500.526223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:20.872175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:20.371286Z","time spent":"500.824915ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:54:20.872264Z","caller":"traceutil/trace.go:171","msg":"trace[780280711] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"1.088905956s","start":"2026-04-27T20:54:19.783327Z","end":"2026-04-27T20:54:20.872233Z","steps":["trace[780280711] 'read index received' (duration: 1.045208775s)","trace[780280711] 'applied index is now lower than readState.Index' (duration: 43.696081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:54:20.872462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089132177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q2lzg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-27T20:54:20.872495Z","caller":"traceutil/trace.go:171","msg":"trace[989681857] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q2lzg; range_end:; response_count:1; response_revision:571; }","duration":"1.089216798s","start":"2026-04-27T20:54:19.78327Z","end":"2026-04-27T20:54:20.872487Z","steps":["trace[989681857] 'agreement among raft nodes before linearized reading' (duration: 1.089152517s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:20.872519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:19.783251Z","time spent":"1.089263869s","remote":"127.0.0.1:49106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-q2lzg\" "} {"level":"warn","ts":"2026-04-27T20:54:20.872569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.480386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:54:20.872621Z","caller":"traceutil/trace.go:171","msg":"trace[88626265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"335.576117ms","start":"2026-04-27T20:54:20.537031Z","end":"2026-04-27T20:54:20.872607Z","steps":["trace[88626265] 'agreement among raft nodes before linearized reading' (duration: 335.486076ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:20.872618Z","caller":"traceutil/trace.go:171","msg":"trace[1847907040] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"315.42831ms","start":"2026-04-27T20:54:20.557175Z","end":"2026-04-27T20:54:20.872604Z","steps":["trace[1847907040] 'process raft request' (duration: 314.785965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:20.872663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:20.537015Z","time spent":"335.640877ms","remote":"127.0.0.1:48902","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-27T20:54:20.872748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:20.557156Z","time spent":"315.51936ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:54:20.87292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.697879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T20:54:20.872945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.66327ms","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-27T20:54:20.872961Z","caller":"traceutil/trace.go:171","msg":"trace[1195825673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"103.786929ms","start":"2026-04-27T20:54:20.769162Z","end":"2026-04-27T20:54:20.872949Z","steps":["trace[1195825673] 'agreement among raft nodes before linearized reading' (duration: 103.685468ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:20.872988Z","caller":"traceutil/trace.go:171","msg":"trace[111028656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"107.75103ms","start":"2026-04-27T20:54:20.765222Z","end":"2026-04-27T20:54:20.872973Z","steps":["trace[111028656] 'agreement among raft nodes before linearized reading' (duration: 107.6698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:21.663727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898865311027910,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T20:54:21.710303Z","caller":"traceutil/trace.go:171","msg":"trace[1590419409] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"822.734994ms","start":"2026-04-27T20:54:20.887541Z","end":"2026-04-27T20:54:21.710276Z","steps":["trace[1590419409] 'process raft request' (duration: 822.671413ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:21.710379Z","caller":"traceutil/trace.go:171","msg":"trace[1715689047] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"824.603558ms","start":"2026-04-27T20:54:20.885754Z","end":"2026-04-27T20:54:21.710357Z","steps":["trace[1715689047] 'process raft request' (duration: 824.396297ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:21.710384Z","caller":"traceutil/trace.go:171","msg":"trace[2128161438] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:599; }","duration":"547.209547ms","start":"2026-04-27T20:54:21.163124Z","end":"2026-04-27T20:54:21.710333Z","steps":["trace[2128161438] 'read index received' (duration: 547.196577ms)","trace[2128161438] 'applied index is now lower than readState.Index' (duration: 9.49µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:54:21.710453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:20.887528Z","time spent":"822.849374ms","remote":"127.0.0.1:49106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:54:21.710478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:20.885743Z","time spent":"824.68714ms","remote":"127.0.0.1:49420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:54:21.710356Z","caller":"traceutil/trace.go:171","msg":"trace[960060106] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"831.968816ms","start":"2026-04-27T20:54:20.878357Z","end":"2026-04-27T20:54:21.710326Z","steps":["trace[960060106] 'process raft request' (duration: 831.746644ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:54:21.710667Z","caller":"traceutil/trace.go:171","msg":"trace[984750377] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"832.338269ms","start":"2026-04-27T20:54:20.878244Z","end":"2026-04-27T20:54:21.710583Z","steps":["trace[984750377] 'process raft request' (duration: 831.715484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:21.710748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.56511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T20:54:21.710869Z","caller":"traceutil/trace.go:171","msg":"trace[1297985063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"547.765621ms","start":"2026-04-27T20:54:21.16308Z","end":"2026-04-27T20:54:21.710846Z","steps":["trace[1297985063] 'agreement among raft nodes before linearized reading' (duration: 547.376958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:21.710922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:21.163059Z","time spent":"547.852902ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T20:54:21.710884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:20.878221Z","time spent":"832.54023ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:54:21.711187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.349706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:54:21.711313Z","caller":"traceutil/trace.go:171","msg":"trace[732033071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"174.472847ms","start":"2026-04-27T20:54:21.536818Z","end":"2026-04-27T20:54:21.711291Z","steps":["trace[732033071] 'agreement among raft nodes before linearized reading' (duration: 174.203005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:54:21.711472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:54:20.87834Z","time spent":"832.320389ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:54:36.729315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.083601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:54:36.72939Z","caller":"traceutil/trace.go:171","msg":"trace[1837110893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"192.200252ms","start":"2026-04-27T20:54:36.537174Z","end":"2026-04-27T20:54:36.729375Z","steps":["trace[1837110893] 'range keys from in-memory index tree' (duration: 191.94232ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:09.379255Z","caller":"traceutil/trace.go:171","msg":"trace[194874547] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"104.777469ms","start":"2026-04-27T20:55:09.274443Z","end":"2026-04-27T20:55:09.379221Z","steps":["trace[194874547] 'process raft request' (duration: 104.654864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:09.922527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898865311029057,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T20:55:10.010583Z","caller":"traceutil/trace.go:171","msg":"trace[1194763336] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1192; }","duration":"588.165369ms","start":"2026-04-27T20:55:09.422392Z","end":"2026-04-27T20:55:10.010558Z","steps":["trace[1194763336] 'read index received' (duration: 576.306143ms)","trace[1194763336] 'applied index is now lower than readState.Index' (duration: 11.857906ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:10.010644Z","caller":"traceutil/trace.go:171","msg":"trace[1786064938] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"656.918465ms","start":"2026-04-27T20:55:09.353697Z","end":"2026-04-27T20:55:10.010615Z","steps":["trace[1786064938] 'process raft request' (duration: 645.046848ms)","trace[1786064938] 'compare' (duration: 11.702001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:10.010794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:09.353635Z","time spent":"657.05683ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:10.010863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.467941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-27T20:55:10.010911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.506671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:10.010929Z","caller":"traceutil/trace.go:171","msg":"trace[1066322558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1155; }","duration":"588.564725ms","start":"2026-04-27T20:55:09.422348Z","end":"2026-04-27T20:55:10.010912Z","steps":["trace[1066322558] 'agreement among raft nodes before linearized reading' (duration: 588.392818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:10.010984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:09.422335Z","time spent":"588.636567ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T20:55:10.010945Z","caller":"traceutil/trace.go:171","msg":"trace[906800171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"473.570203ms","start":"2026-04-27T20:55:09.537364Z","end":"2026-04-27T20:55:10.010934Z","steps":["trace[906800171] 'agreement among raft nodes before linearized reading' (duration: 473.520111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:10.011087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:09.537347Z","time spent":"473.73328ms","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T20:55:14.151932Z","caller":"traceutil/trace.go:171","msg":"trace[1140615569] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"106.151591ms","start":"2026-04-27T20:55:14.045744Z","end":"2026-04-27T20:55:14.151896Z","steps":["trace[1140615569] 'process raft request' (duration: 84.030829ms)","trace[1140615569] 'compare' (duration: 21.890594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:14.152557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.059448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T20:55:14.152578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.703165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T20:55:14.152691Z","caller":"traceutil/trace.go:171","msg":"trace[724663813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1197; }","duration":"115.83955ms","start":"2026-04-27T20:55:14.036832Z","end":"2026-04-27T20:55:14.152672Z","steps":["trace[724663813] 'agreement among raft nodes before linearized reading' (duration: 115.541948ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:14.152633Z","caller":"traceutil/trace.go:171","msg":"trace[200963334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"116.162913ms","start":"2026-04-27T20:55:14.036452Z","end":"2026-04-27T20:55:14.152615Z","steps":["trace[200963334] 'agreement among raft nodes before linearized reading' (duration: 115.945414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:14.153142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.30377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T20:55:14.153183Z","caller":"traceutil/trace.go:171","msg":"trace[406001328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"120.363092ms","start":"2026-04-27T20:55:14.032807Z","end":"2026-04-27T20:55:14.15317Z","steps":["trace[406001328] 'agreement among raft nodes before linearized reading' (duration: 119.534461ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:14.476797Z","caller":"traceutil/trace.go:171","msg":"trace[1726357088] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"112.512654ms","start":"2026-04-27T20:55:14.364255Z","end":"2026-04-27T20:55:14.476767Z","steps":["trace[1726357088] 'read index received' (duration: 90.771226ms)","trace[1726357088] 'applied index is now lower than readState.Index' (duration: 21.728987ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:14.476811Z","caller":"traceutil/trace.go:171","msg":"trace[739986380] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"113.280714ms","start":"2026-04-27T20:55:14.363495Z","end":"2026-04-27T20:55:14.476775Z","steps":["trace[739986380] 'process raft request' (duration: 91.586978ms)","trace[739986380] 'compare' (duration: 21.561171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:14.477029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.754783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/self-signed-ca-lkrvf\" ","response":"range_response_count:1 size:997"} {"level":"info","ts":"2026-04-27T20:55:14.477066Z","caller":"traceutil/trace.go:171","msg":"trace[292182709] range","detail":"{range_begin:/registry/secrets/cert-manager/self-signed-ca-lkrvf; range_end:; response_count:1; response_revision:1219; }","duration":"112.843546ms","start":"2026-04-27T20:55:14.364213Z","end":"2026-04-27T20:55:14.477057Z","steps":["trace[292182709] 'agreement among raft nodes before linearized reading' (duration: 112.66029ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:30.346199Z","caller":"traceutil/trace.go:171","msg":"trace[116871505] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1447; }","duration":"109.794797ms","start":"2026-04-27T20:55:30.236372Z","end":"2026-04-27T20:55:30.346167Z","steps":["trace[116871505] 'read index received' (duration: 109.552079ms)","trace[116871505] 'applied index is now lower than readState.Index' (duration: 241.268µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:30.346551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.340156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T20:55:30.346535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.126757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T20:55:30.346567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.583453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T20:55:30.346597Z","caller":"traceutil/trace.go:171","msg":"trace[143880100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1404; }","duration":"100.435048ms","start":"2026-04-27T20:55:30.246148Z","end":"2026-04-27T20:55:30.346583Z","steps":["trace[143880100] 'agreement among raft nodes before linearized reading' (duration: 100.219491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:30.346567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.909672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T20:55:30.346634Z","caller":"traceutil/trace.go:171","msg":"trace[1957550300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1404; }","duration":"100.676446ms","start":"2026-04-27T20:55:30.245943Z","end":"2026-04-27T20:55:30.34662Z","steps":["trace[1957550300] 'agreement among raft nodes before linearized reading' (duration: 100.444139ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:30.346628Z","caller":"traceutil/trace.go:171","msg":"trace[1566952601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1404; }","duration":"110.262272ms","start":"2026-04-27T20:55:30.236341Z","end":"2026-04-27T20:55:30.346603Z","steps":["trace[1566952601] 'agreement among raft nodes before linearized reading' (duration: 109.981353ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:30.346306Z","caller":"traceutil/trace.go:171","msg":"trace[883382905] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"119.535066ms","start":"2026-04-27T20:55:30.226733Z","end":"2026-04-27T20:55:30.346268Z","steps":["trace[883382905] 'process raft request' (duration: 119.231877ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:30.346647Z","caller":"traceutil/trace.go:171","msg":"trace[314056277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1404; }","duration":"106.031057ms","start":"2026-04-27T20:55:30.240605Z","end":"2026-04-27T20:55:30.346636Z","steps":["trace[314056277] 'agreement among raft nodes before linearized reading' (duration: 105.763348ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:30.475525Z","caller":"traceutil/trace.go:171","msg":"trace[1652529731] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1448; }","duration":"121.171338ms","start":"2026-04-27T20:55:30.354333Z","end":"2026-04-27T20:55:30.475505Z","steps":["trace[1652529731] 'read index received' (duration: 9.403899ms)","trace[1652529731] 'applied index is now lower than readState.Index' (duration: 111.766329ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:30.475623Z","caller":"traceutil/trace.go:171","msg":"trace[1681820507] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"124.022079ms","start":"2026-04-27T20:55:30.351587Z","end":"2026-04-27T20:55:30.475609Z","steps":["trace[1681820507] 'process raft request' (duration: 123.862944ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:30.475615Z","caller":"traceutil/trace.go:171","msg":"trace[2036917563] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"124.061339ms","start":"2026-04-27T20:55:30.35153Z","end":"2026-04-27T20:55:30.475591Z","steps":["trace[2036917563] 'process raft request' (duration: 123.820552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:30.475672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.311842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T20:55:30.475698Z","caller":"traceutil/trace.go:171","msg":"trace[88835523] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1408; }","duration":"121.382994ms","start":"2026-04-27T20:55:30.354306Z","end":"2026-04-27T20:55:30.475689Z","steps":["trace[88835523] 'agreement among raft nodes before linearized reading' (duration: 121.281341ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:30.475731Z","caller":"traceutil/trace.go:171","msg":"trace[1794562743] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"124.105111ms","start":"2026-04-27T20:55:30.35162Z","end":"2026-04-27T20:55:30.475725Z","steps":["trace[1794562743] 'process raft request' (duration: 123.856903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.161308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.393674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:55.162141Z","caller":"traceutil/trace.go:171","msg":"trace[1159125451] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"720.785714ms","start":"2026-04-27T20:55:54.441324Z","end":"2026-04-27T20:55:55.162109Z","steps":["trace[1159125451] 'process raft request' (duration: 350.488038ms)","trace[1159125451] 'compare' (duration: 368.910422ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:55.162131Z","caller":"traceutil/trace.go:171","msg":"trace[572625986] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"676.922428ms","start":"2026-04-27T20:55:54.485152Z","end":"2026-04-27T20:55:55.162074Z","steps":["trace[572625986] 'read index received' (duration: 306.672744ms)","trace[572625986] 'applied index is now lower than readState.Index' (duration: 370.247224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:55.162278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.441306Z","time spent":"720.897907ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:55.16245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.266117ms","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-27T20:55:55.162743Z","caller":"traceutil/trace.go:171","msg":"trace[204705226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1814; }","duration":"677.620446ms","start":"2026-04-27T20:55:54.485101Z","end":"2026-04-27T20:55:55.162722Z","steps":["trace[204705226] 'agreement among raft nodes before linearized reading' (duration: 677.062023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.16289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.485049Z","time spent":"677.826551ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T20:55:55.171292Z","caller":"traceutil/trace.go:171","msg":"trace[232782730] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"502.429206ms","start":"2026-04-27T20:55:54.668852Z","end":"2026-04-27T20:55:55.171281Z","steps":["trace[232782730] 'process raft request' (duration: 502.329694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.171372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.668826Z","time spent":"502.501727ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:55.198695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.857857ms","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-27T20:55:55.198755Z","caller":"traceutil/trace.go:171","msg":"trace[1265891168] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1817; }","duration":"289.959269ms","start":"2026-04-27T20:55:54.908779Z","end":"2026-04-27T20:55:55.198738Z","steps":["trace[1265891168] 'agreement among raft nodes before linearized reading' (duration: 289.744904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.198693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.952261ms","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-27T20:55:55.198905Z","caller":"traceutil/trace.go:171","msg":"trace[2127613963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1817; }","duration":"613.192248ms","start":"2026-04-27T20:55:54.585694Z","end":"2026-04-27T20:55:55.198886Z","steps":["trace[2127613963] 'agreement among raft nodes before linearized reading' (duration: 612.854349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.199024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.585683Z","time spent":"613.326921ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T20:55:55.199092Z","caller":"traceutil/trace.go:171","msg":"trace[461115411] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"244.098884ms","start":"2026-04-27T20:55:54.954975Z","end":"2026-04-27T20:55:55.199074Z","steps":["trace[461115411] 'process raft request' (duration: 243.361466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.199301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.131854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60bcafea-4699-40b7-b719-d5c58e76d176\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-27T20:55:55.199367Z","caller":"traceutil/trace.go:171","msg":"trace[1584336177] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60bcafea-4699-40b7-b719-d5c58e76d176; range_end:; response_count:1; response_revision:1817; }","duration":"657.234807ms","start":"2026-04-27T20:55:54.542116Z","end":"2026-04-27T20:55:55.199351Z","steps":["trace[1584336177] 'agreement among raft nodes before linearized reading' (duration: 656.391616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.199502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.542098Z","time spent":"657.353311ms","remote":"127.0.0.1:49106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60bcafea-4699-40b7-b719-d5c58e76d176\" "} {"level":"warn","ts":"2026-04-27T20:55:55.199502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.017822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T20:55:55.199574Z","caller":"traceutil/trace.go:171","msg":"trace[170505555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1817; }","duration":"615.147696ms","start":"2026-04-27T20:55:54.584408Z","end":"2026-04-27T20:55:55.199556Z","steps":["trace[170505555] 'agreement among raft nodes before linearized reading' (duration: 614.949421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.199641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.584384Z","time spent":"615.243028ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T20:55:55.199666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.267735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-27T20:55:55.199819Z","caller":"traceutil/trace.go:171","msg":"trace[940316943] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1817; }","duration":"472.551753ms","start":"2026-04-27T20:55:54.727227Z","end":"2026-04-27T20:55:55.199779Z","steps":["trace[940316943] 'agreement among raft nodes before linearized reading' (duration: 472.057799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.199646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.430596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T20:55:55.199906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.467519ms","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-27T20:55:55.199937Z","caller":"traceutil/trace.go:171","msg":"trace[59663132] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1817; }","duration":"119.850658ms","start":"2026-04-27T20:55:55.080067Z","end":"2026-04-27T20:55:55.199917Z","steps":["trace[59663132] 'agreement among raft nodes before linearized reading' (duration: 119.477608ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.199966Z","caller":"traceutil/trace.go:171","msg":"trace[506641182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1817; }","duration":"618.547052ms","start":"2026-04-27T20:55:54.581405Z","end":"2026-04-27T20:55:55.199952Z","steps":["trace[506641182] 'agreement among raft nodes before linearized reading' (duration: 618.405788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.200018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.581394Z","time spent":"618.610403ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T20:55:55.201465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.72721Z","time spent":"472.691597ms","remote":"127.0.0.1:40264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-27T20:55:55.396971Z","caller":"traceutil/trace.go:171","msg":"trace[1455736163] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"192.613929ms","start":"2026-04-27T20:55:55.204331Z","end":"2026-04-27T20:55:55.396945Z","steps":["trace[1455736163] 'process raft request' (duration: 192.390154ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.397418Z","caller":"traceutil/trace.go:171","msg":"trace[437857905] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"191.87521ms","start":"2026-04-27T20:55:55.205517Z","end":"2026-04-27T20:55:55.397392Z","steps":["trace[437857905] 'process raft request' (duration: 191.381028ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.397557Z","caller":"traceutil/trace.go:171","msg":"trace[1081400216] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1892; }","duration":"141.098423ms","start":"2026-04-27T20:55:55.256448Z","end":"2026-04-27T20:55:55.397546Z","steps":["trace[1081400216] 'read index received' (duration: 141.093073ms)","trace[1081400216] 'applied index is now lower than readState.Index' (duration: 4.35µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:55.397425Z","caller":"traceutil/trace.go:171","msg":"trace[1253950372] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"191.685395ms","start":"2026-04-27T20:55:55.205713Z","end":"2026-04-27T20:55:55.397398Z","steps":["trace[1253950372] 'process raft request' (duration: 191.573392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.398101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.640746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60bcafea-4699-40b7-b719-d5c58e76d176\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-27T20:55:55.398147Z","caller":"traceutil/trace.go:171","msg":"trace[900197974] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60bcafea-4699-40b7-b719-d5c58e76d176; range_end:; response_count:1; response_revision:1821; }","duration":"141.698707ms","start":"2026-04-27T20:55:55.256436Z","end":"2026-04-27T20:55:55.398135Z","steps":["trace[900197974] 'agreement among raft nodes before linearized reading' (duration: 141.195315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:03.397898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.344035ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-27T20:56:03.398287Z","caller":"traceutil/trace.go:171","msg":"trace[1695279551] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1960; }","duration":"165.856348ms","start":"2026-04-27T20:56:03.232408Z","end":"2026-04-27T20:56:03.398265Z","steps":["trace[1695279551] 'read index received' (duration: 157.901024ms)","trace[1695279551] 'applied index is now lower than readState.Index' (duration: 7.954014ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:03.398387Z","caller":"traceutil/trace.go:171","msg":"trace[1733749051] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"210.284331ms","start":"2026-04-27T20:56:03.188045Z","end":"2026-04-27T20:56:03.398329Z","steps":["trace[1733749051] 'process raft request' (duration: 209.946453ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:03.398435Z","caller":"traceutil/trace.go:171","msg":"trace[713433505] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"171.733913ms","start":"2026-04-27T20:56:03.226666Z","end":"2026-04-27T20:56:03.3984Z","steps":["trace[713433505] 'process raft request' (duration: 171.541608ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:03.39848Z","caller":"traceutil/trace.go:171","msg":"trace[1476117699] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"201.402967ms","start":"2026-04-27T20:56:03.196963Z","end":"2026-04-27T20:56:03.398366Z","steps":["trace[1476117699] 'process raft request' (duration: 201.171592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:03.398589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.164644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T20:56:03.398636Z","caller":"traceutil/trace.go:171","msg":"trace[921265753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1891; }","duration":"166.246877ms","start":"2026-04-27T20:56:03.232376Z","end":"2026-04-27T20:56:03.398623Z","steps":["trace[921265753] 'agreement among raft nodes before linearized reading' (duration: 166.090913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:03.398589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.494534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T20:56:03.398724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.123559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-27T20:56:03.398753Z","caller":"traceutil/trace.go:171","msg":"trace[1848100303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1891; }","duration":"163.691218ms","start":"2026-04-27T20:56:03.235048Z","end":"2026-04-27T20:56:03.398739Z","steps":["trace[1848100303] 'agreement among raft nodes before linearized reading' (duration: 163.405302ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:03.398763Z","caller":"traceutil/trace.go:171","msg":"trace[1446369501] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1891; }","duration":"141.193621ms","start":"2026-04-27T20:56:03.257557Z","end":"2026-04-27T20:56:03.39875Z","steps":["trace[1446369501] 'agreement among raft nodes before linearized reading' (duration: 141.064508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:29.783003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.227335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:56:29.783967Z","caller":"traceutil/trace.go:171","msg":"trace[1324959867] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"202.513351ms","start":"2026-04-27T20:56:29.581376Z","end":"2026-04-27T20:56:29.783889Z","steps":["trace[1324959867] 'process raft request' (duration: 202.362279ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:29.784061Z","caller":"traceutil/trace.go:171","msg":"trace[1352857523] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2132; }","duration":"207.396153ms","start":"2026-04-27T20:56:29.576631Z","end":"2026-04-27T20:56:29.784027Z","steps":["trace[1352857523] 'read index received' (duration: 10.019998ms)","trace[1352857523] 'applied index is now lower than readState.Index' (duration: 197.373285ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:29.784121Z","caller":"traceutil/trace.go:171","msg":"trace[1402052915] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"237.927803ms","start":"2026-04-27T20:56:29.546137Z","end":"2026-04-27T20:56:29.784065Z","steps":["trace[1402052915] 'process raft request' (duration: 40.504027ms)","trace[1402052915] 'compare' (duration: 196.04359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:29.784349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.318468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T20:56:29.78435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.463343ms","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-27T20:56:29.784412Z","caller":"traceutil/trace.go:171","msg":"trace[236410702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2056; }","duration":"239.40105ms","start":"2026-04-27T20:56:29.544991Z","end":"2026-04-27T20:56:29.784392Z","steps":["trace[236410702] 'agreement among raft nodes before linearized reading' (duration: 239.252327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:29.784412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.695729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-27T20:56:29.78443Z","caller":"traceutil/trace.go:171","msg":"trace[575155793] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2056; }","duration":"238.586645ms","start":"2026-04-27T20:56:29.545827Z","end":"2026-04-27T20:56:29.784414Z","steps":["trace[575155793] 'agreement among raft nodes before linearized reading' (duration: 238.33306ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:29.78446Z","caller":"traceutil/trace.go:171","msg":"trace[1594660704] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2056; }","duration":"199.78297ms","start":"2026-04-27T20:56:29.584666Z","end":"2026-04-27T20:56:29.784449Z","steps":["trace[1594660704] 'agreement among raft nodes before linearized reading' (duration: 199.627327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:30.333045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.410701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T20:56:30.333152Z","caller":"traceutil/trace.go:171","msg":"trace[520869964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2059; }","duration":"271.564684ms","start":"2026-04-27T20:56:30.061566Z","end":"2026-04-27T20:56:30.333131Z","steps":["trace[520869964] 'range keys from in-memory index tree' (duration: 270.840651ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:30.576204Z","caller":"traceutil/trace.go:171","msg":"trace[834383845] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"238.44736ms","start":"2026-04-27T20:56:30.337718Z","end":"2026-04-27T20:56:30.576165Z","steps":["trace[834383845] 'process raft request' (duration: 238.291707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:30.884099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.381272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:56:30.884199Z","caller":"traceutil/trace.go:171","msg":"trace[613573269] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2137; }","duration":"347.059164ms","start":"2026-04-27T20:56:30.537123Z","end":"2026-04-27T20:56:30.884182Z","steps":["trace[613573269] 'read index received' (duration: 38.893341ms)","trace[613573269] 'applied index is now lower than readState.Index' (duration: 308.164453ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:30.884289Z","caller":"traceutil/trace.go:171","msg":"trace[244261707] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"393.974513ms","start":"2026-04-27T20:56:30.4903Z","end":"2026-04-27T20:56:30.884274Z","steps":["trace[244261707] 'process raft request' (duration: 177.289256ms)","trace[244261707] 'compare' (duration: 216.190228ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:30.884376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:30.490282Z","time spent":"394.062556ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:30.884715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.597533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:56:30.884762Z","caller":"traceutil/trace.go:171","msg":"trace[1921594709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2061; }","duration":"347.677535ms","start":"2026-04-27T20:56:30.537074Z","end":"2026-04-27T20:56:30.884752Z","steps":["trace[1921594709] 'agreement among raft nodes before linearized reading' (duration: 347.598234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:30.884792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:30.537056Z","time spent":"347.729866ms","remote":"127.0.0.1:48892","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-27T20:56:30.885039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.008076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T20:56:30.885137Z","caller":"traceutil/trace.go:171","msg":"trace[798679187] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2061; }","duration":"126.154479ms","start":"2026-04-27T20:56:30.758961Z","end":"2026-04-27T20:56:30.885116Z","steps":["trace[798679187] 'agreement among raft nodes before linearized reading' (duration: 125.755911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:30.885128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.211701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T20:56:30.885215Z","caller":"traceutil/trace.go:171","msg":"trace[1611749245] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2061; }","duration":"289.328123ms","start":"2026-04-27T20:56:30.595867Z","end":"2026-04-27T20:56:30.885195Z","steps":["trace[1611749245] 'agreement among raft nodes before linearized reading' (duration: 288.514788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:31.417638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.66623ms","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-27T20:56:31.417695Z","caller":"traceutil/trace.go:171","msg":"trace[1699717127] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2061; }","duration":"221.777462ms","start":"2026-04-27T20:56:31.195906Z","end":"2026-04-27T20:56:31.417683Z","steps":["trace[1699717127] 'count revisions from in-memory index tree' (duration: 221.544258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:31.41797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.63233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T20:56:31.418511Z","caller":"traceutil/trace.go:171","msg":"trace[1860472174] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2061; }","duration":"190.234ms","start":"2026-04-27T20:56:31.228254Z","end":"2026-04-27T20:56:31.418488Z","steps":["trace[1860472174] 'count revisions from in-memory index tree' (duration: 189.456886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:23.872726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.225818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-9gtfd\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-04-27T20:57:23.873072Z","caller":"traceutil/trace.go:171","msg":"trace[646900210] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-9gtfd; range_end:; response_count:1; response_revision:2389; }","duration":"180.630484ms","start":"2026-04-27T20:57:23.692417Z","end":"2026-04-27T20:57:23.873048Z","steps":["trace[646900210] 'range keys from in-memory index tree' (duration: 180.062836ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:23.895755Z","caller":"traceutil/trace.go:171","msg":"trace[1555340497] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"184.948223ms","start":"2026-04-27T20:57:23.710784Z","end":"2026-04-27T20:57:23.895732Z","steps":["trace[1555340497] 'process raft request' (duration: 184.808071ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:23.904575Z","caller":"traceutil/trace.go:171","msg":"trace[1829347165] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"184.832541ms","start":"2026-04-27T20:57:23.719703Z","end":"2026-04-27T20:57:23.904535Z","steps":["trace[1829347165] 'process raft request' (duration: 184.628988ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:23.904614Z","caller":"traceutil/trace.go:171","msg":"trace[1221179769] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"191.965508ms","start":"2026-04-27T20:57:23.712445Z","end":"2026-04-27T20:57:23.904411Z","steps":["trace[1221179769] 'process raft request' (duration: 191.792536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:32.370274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.250968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:57:32.370424Z","caller":"traceutil/trace.go:171","msg":"trace[458261340] linearizableReadLoop","detail":"{readStateIndex:2585; appliedIndex:2582; }","duration":"419.689621ms","start":"2026-04-27T20:57:31.950719Z","end":"2026-04-27T20:57:32.370409Z","steps":["trace[458261340] 'read index received' (duration: 56.823817ms)","trace[458261340] 'applied index is now lower than readState.Index' (duration: 362.864764ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:57:32.370645Z","caller":"traceutil/trace.go:171","msg":"trace[14485531] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"441.162267ms","start":"2026-04-27T20:57:31.92947Z","end":"2026-04-27T20:57:32.370632Z","steps":["trace[14485531] 'process raft request' (duration: 178.482652ms)","trace[14485531] 'compare' (duration: 262.160687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:57:32.370713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:57:31.929444Z","time spent":"441.235857ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:57:32.371008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.273408ms","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-27T20:57:32.371098Z","caller":"traceutil/trace.go:171","msg":"trace[659307389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2493; }","duration":"420.405449ms","start":"2026-04-27T20:57:31.950671Z","end":"2026-04-27T20:57:32.371077Z","steps":["trace[659307389] 'agreement among raft nodes before linearized reading' (duration: 420.125236ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:32.371139Z","caller":"traceutil/trace.go:171","msg":"trace[470158159] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"437.779001ms","start":"2026-04-27T20:57:31.933346Z","end":"2026-04-27T20:57:32.371125Z","steps":["trace[470158159] 'process raft request' (duration: 437.01536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:32.37115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:57:31.950645Z","time spent":"420.49475ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T20:57:32.37121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:57:31.933332Z","time spent":"437.845283ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:57:32.372342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.662649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T20:57:32.372409Z","caller":"traceutil/trace.go:171","msg":"trace[233596100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2494; }","duration":"251.756899ms","start":"2026-04-27T20:57:32.120635Z","end":"2026-04-27T20:57:32.372392Z","steps":["trace[233596100] 'agreement among raft nodes before linearized reading' (duration: 251.568797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:32.372684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.545415ms","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-27T20:57:32.372865Z","caller":"traceutil/trace.go:171","msg":"trace[1783147046] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2494; }","duration":"122.747878ms","start":"2026-04-27T20:57:32.250071Z","end":"2026-04-27T20:57:32.372819Z","steps":["trace[1783147046] 'agreement among raft nodes before linearized reading' (duration: 122.397713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:32.373261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.873391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T20:57:32.373337Z","caller":"traceutil/trace.go:171","msg":"trace[1611219431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2494; }","duration":"251.955442ms","start":"2026-04-27T20:57:32.121363Z","end":"2026-04-27T20:57:32.373319Z","steps":["trace[1611219431] 'agreement among raft nodes before linearized reading' (duration: 251.780809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:32.37353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.284687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T20:57:32.373565Z","caller":"traceutil/trace.go:171","msg":"trace[2062921315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2494; }","duration":"252.327547ms","start":"2026-04-27T20:57:32.121229Z","end":"2026-04-27T20:57:32.373557Z","steps":["trace[2062921315] 'agreement among raft nodes before linearized reading' (duration: 252.242716ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:32.563595Z","caller":"traceutil/trace.go:171","msg":"trace[848811172] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"186.972529ms","start":"2026-04-27T20:57:32.376566Z","end":"2026-04-27T20:57:32.563539Z","steps":["trace[848811172] 'process raft request' (duration: 186.750926ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:32.564044Z","caller":"traceutil/trace.go:171","msg":"trace[1832750827] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"185.818986ms","start":"2026-04-27T20:57:32.378207Z","end":"2026-04-27T20:57:32.564026Z","steps":["trace[1832750827] 'process raft request' (duration: 185.557452ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:32.56473Z","caller":"traceutil/trace.go:171","msg":"trace[2024308527] linearizableReadLoop","detail":"{readStateIndex:2591; appliedIndex:2586; }","duration":"165.07607ms","start":"2026-04-27T20:57:32.399644Z","end":"2026-04-27T20:57:32.56472Z","steps":["trace[2024308527] 'read index received' (duration: 163.703631ms)","trace[2024308527] 'applied index is now lower than readState.Index' (duration: 1.371659ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:57:32.564793Z","caller":"traceutil/trace.go:171","msg":"trace[951249187] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"185.183886ms","start":"2026-04-27T20:57:32.379602Z","end":"2026-04-27T20:57:32.564786Z","steps":["trace[951249187] 'process raft request' (duration: 185.071895ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:32.56491Z","caller":"traceutil/trace.go:171","msg":"trace[370166106] transaction","detail":"{read_only:false; response_revision:2497; number_of_response:1; }","duration":"186.020108ms","start":"2026-04-27T20:57:32.378318Z","end":"2026-04-27T20:57:32.564339Z","steps":["trace[370166106] 'process raft request' (duration: 185.672363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:32.564999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.980668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-27T20:57:32.565087Z","caller":"traceutil/trace.go:171","msg":"trace[1471776028] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2499; }","duration":"165.11314ms","start":"2026-04-27T20:57:32.399958Z","end":"2026-04-27T20:57:32.565071Z","steps":["trace[1471776028] 'agreement among raft nodes before linearized reading' (duration: 164.917468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:32.565784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.615106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T20:57:32.566525Z","caller":"traceutil/trace.go:171","msg":"trace[2137272303] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2499; }","duration":"166.829462ms","start":"2026-04-27T20:57:32.39963Z","end":"2026-04-27T20:57:32.56646Z","steps":["trace[2137272303] 'agreement among raft nodes before linearized reading' (duration: 165.301672ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:57:32.56593Z","caller":"traceutil/trace.go:171","msg":"trace[1509721908] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"185.752644ms","start":"2026-04-27T20:57:32.378982Z","end":"2026-04-27T20:57:32.564734Z","steps":["trace[1509721908] 'process raft request' (duration: 185.243937ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:05.226327Z","caller":"traceutil/trace.go:171","msg":"trace[273227776] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"169.018833ms","start":"2026-04-27T20:58:05.057283Z","end":"2026-04-27T20:58:05.226302Z","steps":["trace[273227776] 'process raft request' (duration: 168.818321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:05.659144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.270561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:58:05.659544Z","caller":"traceutil/trace.go:171","msg":"trace[247248936] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2678; }","duration":"176.736036ms","start":"2026-04-27T20:58:05.482784Z","end":"2026-04-27T20:58:05.65952Z","steps":["trace[247248936] 'count revisions from in-memory index tree' (duration: 176.124399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:05.659167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.808399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:58:05.659966Z","caller":"traceutil/trace.go:171","msg":"trace[146438406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2678; }","duration":"122.667319ms","start":"2026-04-27T20:58:05.537284Z","end":"2026-04-27T20:58:05.659952Z","steps":["trace[146438406] 'range keys from in-memory index tree' (duration: 121.705328ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:29.164277Z","caller":"traceutil/trace.go:171","msg":"trace[2143453203] transaction","detail":"{read_only:false; response_revision:3522; number_of_response:1; }","duration":"109.83265ms","start":"2026-04-27T21:00:29.054416Z","end":"2026-04-27T21:00:29.164249Z","steps":["trace[2143453203] 'process raft request' (duration: 109.624435ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:29.191662Z","caller":"traceutil/trace.go:171","msg":"trace[988876876] transaction","detail":"{read_only:false; response_revision:3523; number_of_response:1; }","duration":"135.29505ms","start":"2026-04-27T21:00:29.056328Z","end":"2026-04-27T21:00:29.191623Z","steps":["trace[988876876] 'process raft request' (duration: 135.131046ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:38.279472Z","caller":"traceutil/trace.go:171","msg":"trace[1700729569] transaction","detail":"{read_only:false; response_revision:3571; number_of_response:1; }","duration":"116.687503ms","start":"2026-04-27T21:00:38.162753Z","end":"2026-04-27T21:00:38.27944Z","steps":["trace[1700729569] 'process raft request' (duration: 116.519199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:43.032575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.272077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-9pn5v\" ","response":"range_response_count:1 size:4356"} {"level":"info","ts":"2026-04-27T21:01:43.032721Z","caller":"traceutil/trace.go:171","msg":"trace[1055108748] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-9pn5v; range_end:; response_count:1; response_revision:4011; }","duration":"129.48413ms","start":"2026-04-27T21:01:42.903208Z","end":"2026-04-27T21:01:43.032692Z","steps":["trace[1055108748] 'range keys from in-memory index tree' (duration: 129.036562ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:47.878149Z","caller":"traceutil/trace.go:171","msg":"trace[994503860] linearizableReadLoop","detail":"{readStateIndex:4462; appliedIndex:4458; }","duration":"149.421344ms","start":"2026-04-27T21:01:47.728599Z","end":"2026-04-27T21:01:47.87802Z","steps":["trace[994503860] 'read index received' (duration: 7.9954ms)","trace[994503860] 'applied index is now lower than readState.Index' (duration: 141.425064ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:47.878277Z","caller":"traceutil/trace.go:171","msg":"trace[1296542926] transaction","detail":"{read_only:false; response_revision:4295; number_of_response:1; }","duration":"147.463078ms","start":"2026-04-27T21:01:47.730794Z","end":"2026-04-27T21:01:47.878257Z","steps":["trace[1296542926] 'process raft request' (duration: 147.160132ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:47.87841Z","caller":"traceutil/trace.go:171","msg":"trace[162097526] transaction","detail":"{read_only:false; response_revision:4294; number_of_response:1; }","duration":"160.334618ms","start":"2026-04-27T21:01:47.717938Z","end":"2026-04-27T21:01:47.878273Z","steps":["trace[162097526] 'process raft request' (duration: 87.307358ms)","trace[162097526] 'compare' (duration: 72.50053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:01:47.878512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.885993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/grafana-tls\" ","response":"range_response_count:1 size:2995"} {"level":"info","ts":"2026-04-27T21:01:47.87859Z","caller":"traceutil/trace.go:171","msg":"trace[1787961655] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4295; }","duration":"150.016125ms","start":"2026-04-27T21:01:47.728556Z","end":"2026-04-27T21:01:47.878572Z","steps":["trace[1787961655] 'agreement among raft nodes before linearized reading' (duration: 149.775601ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:47.878498Z","caller":"traceutil/trace.go:171","msg":"trace[272489287] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4294; }","duration":"156.580698ms","start":"2026-04-27T21:01:47.721566Z","end":"2026-04-27T21:01:47.878146Z","steps":["trace[272489287] 'process raft request' (duration: 156.346904ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:47.969282Z","caller":"traceutil/trace.go:171","msg":"trace[1924570686] transaction","detail":"{read_only:false; response_revision:4296; number_of_response:1; }","duration":"220.171561ms","start":"2026-04-27T21:01:47.749076Z","end":"2026-04-27T21:01:47.969248Z","steps":["trace[1924570686] 'process raft request' (duration: 219.809984ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:47.969293Z","caller":"traceutil/trace.go:171","msg":"trace[692773479] transaction","detail":"{read_only:false; response_revision:4297; number_of_response:1; }","duration":"220.037879ms","start":"2026-04-27T21:01:47.749213Z","end":"2026-04-27T21:01:47.969251Z","steps":["trace[692773479] 'process raft request' (duration: 219.946187ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.0918Z","caller":"traceutil/trace.go:171","msg":"trace[43320205] transaction","detail":"{read_only:false; response_revision:4299; number_of_response:1; }","duration":"205.051724ms","start":"2026-04-27T21:01:47.886701Z","end":"2026-04-27T21:01:48.091753Z","steps":["trace[43320205] 'process raft request' (duration: 204.440763ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.091858Z","caller":"traceutil/trace.go:171","msg":"trace[507477576] linearizableReadLoop","detail":"{readStateIndex:4466; appliedIndex:4463; }","duration":"213.534893ms","start":"2026-04-27T21:01:47.878306Z","end":"2026-04-27T21:01:48.091841Z","steps":["trace[507477576] 'read index received' (duration: 90.56932ms)","trace[507477576] 'applied index is now lower than readState.Index' (duration: 122.964783ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:48.091899Z","caller":"traceutil/trace.go:171","msg":"trace[174106821] transaction","detail":"{read_only:false; response_revision:4303; number_of_response:1; }","duration":"202.797501ms","start":"2026-04-27T21:01:47.889066Z","end":"2026-04-27T21:01:48.091863Z","steps":["trace[174106821] 'process raft request' (duration: 202.19421ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.091947Z","caller":"traceutil/trace.go:171","msg":"trace[1583893733] transaction","detail":"{read_only:false; response_revision:4305; number_of_response:1; }","duration":"195.902202ms","start":"2026-04-27T21:01:47.896034Z","end":"2026-04-27T21:01:48.091936Z","steps":["trace[1583893733] 'process raft request' (duration: 195.26134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:48.09213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.284316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T21:01:48.092156Z","caller":"traceutil/trace.go:171","msg":"trace[1463609396] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:4305; }","duration":"214.347908ms","start":"2026-04-27T21:01:47.8778Z","end":"2026-04-27T21:01:48.092148Z","steps":["trace[1463609396] 'agreement among raft nodes before linearized reading' (duration: 214.225666ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.092157Z","caller":"traceutil/trace.go:171","msg":"trace[414236325] transaction","detail":"{read_only:false; response_revision:4301; number_of_response:1; }","duration":"204.152487ms","start":"2026-04-27T21:01:47.887998Z","end":"2026-04-27T21:01:48.09215Z","steps":["trace[414236325] 'process raft request' (duration: 203.22471ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.09218Z","caller":"traceutil/trace.go:171","msg":"trace[584662537] transaction","detail":"{read_only:false; response_revision:4298; number_of_response:1; }","duration":"251.387172ms","start":"2026-04-27T21:01:47.840781Z","end":"2026-04-27T21:01:48.092168Z","steps":["trace[584662537] 'process raft request' (duration: 171.461537ms)","trace[584662537] 'compare' (duration: 78.651082ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:48.092205Z","caller":"traceutil/trace.go:171","msg":"trace[2081605517] transaction","detail":"{read_only:false; response_revision:4304; number_of_response:1; }","duration":"202.225581ms","start":"2026-04-27T21:01:47.889966Z","end":"2026-04-27T21:01:48.092191Z","steps":["trace[2081605517] 'process raft request' (duration: 201.311594ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.092129Z","caller":"traceutil/trace.go:171","msg":"trace[2073463103] transaction","detail":"{read_only:false; response_revision:4300; number_of_response:1; }","duration":"204.601405ms","start":"2026-04-27T21:01:47.887516Z","end":"2026-04-27T21:01:48.092117Z","steps":["trace[2073463103] 'process raft request' (duration: 203.674068ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.09237Z","caller":"traceutil/trace.go:171","msg":"trace[820061929] transaction","detail":"{read_only:false; response_revision:4302; number_of_response:1; }","duration":"203.391262ms","start":"2026-04-27T21:01:47.888968Z","end":"2026-04-27T21:01:48.09236Z","steps":["trace[820061929] 'process raft request' (duration: 202.272991ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.125253Z","caller":"traceutil/trace.go:171","msg":"trace[1209576771] transaction","detail":"{read_only:false; response_revision:4307; number_of_response:1; }","duration":"129.533755ms","start":"2026-04-27T21:01:47.995707Z","end":"2026-04-27T21:01:48.125241Z","steps":["trace[1209576771] 'process raft request' (duration: 129.506824ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.125402Z","caller":"traceutil/trace.go:171","msg":"trace[1758964377] transaction","detail":"{read_only:false; response_revision:4306; number_of_response:1; }","duration":"142.730702ms","start":"2026-04-27T21:01:47.982666Z","end":"2026-04-27T21:01:48.125397Z","steps":["trace[1758964377] 'process raft request' (duration: 142.478398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:48.125507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.685365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:01:48.125531Z","caller":"traceutil/trace.go:171","msg":"trace[1860681477] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4307; }","duration":"108.744186ms","start":"2026-04-27T21:01:48.016781Z","end":"2026-04-27T21:01:48.125526Z","steps":["trace[1860681477] 'agreement among raft nodes before linearized reading' (duration: 108.689885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:48.125546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.557219ms","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-27T21:01:48.125562Z","caller":"traceutil/trace.go:171","msg":"trace[670059123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4307; }","duration":"134.60696ms","start":"2026-04-27T21:01:47.990951Z","end":"2026-04-27T21:01:48.125558Z","steps":["trace[670059123] 'agreement among raft nodes before linearized reading' (duration: 134.539089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:48.125654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.152341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:4334"} {"level":"info","ts":"2026-04-27T21:01:48.125669Z","caller":"traceutil/trace.go:171","msg":"trace[1131189927] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4307; }","duration":"231.185312ms","start":"2026-04-27T21:01:47.894478Z","end":"2026-04-27T21:01:48.125664Z","steps":["trace[1131189927] 'agreement among raft nodes before linearized reading' (duration: 231.133481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:48.125692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.627211ms","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-27T21:01:48.125736Z","caller":"traceutil/trace.go:171","msg":"trace[593431187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4307; }","duration":"125.695133ms","start":"2026-04-27T21:01:48.00003Z","end":"2026-04-27T21:01:48.125726Z","steps":["trace[593431187] 'agreement among raft nodes before linearized reading' (duration: 125.561901ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.248228Z","caller":"traceutil/trace.go:171","msg":"trace[2134500421] transaction","detail":"{read_only:false; response_revision:4315; number_of_response:1; }","duration":"123.156135ms","start":"2026-04-27T21:01:48.125046Z","end":"2026-04-27T21:01:48.248203Z","steps":["trace[2134500421] 'process raft request' (duration: 113.488834ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.248719Z","caller":"traceutil/trace.go:171","msg":"trace[710644858] transaction","detail":"{read_only:false; response_revision:4316; number_of_response:1; }","duration":"119.325693ms","start":"2026-04-27T21:01:48.129377Z","end":"2026-04-27T21:01:48.248703Z","steps":["trace[710644858] 'process raft request' (duration: 118.759643ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.248975Z","caller":"traceutil/trace.go:171","msg":"trace[913181628] transaction","detail":"{read_only:false; response_revision:4317; number_of_response:1; }","duration":"115.777337ms","start":"2026-04-27T21:01:48.13319Z","end":"2026-04-27T21:01:48.248968Z","steps":["trace[913181628] 'process raft request' (duration: 115.260827ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.249252Z","caller":"traceutil/trace.go:171","msg":"trace[1255655235] transaction","detail":"{read_only:false; response_revision:4318; number_of_response:1; }","duration":"114.437202ms","start":"2026-04-27T21:01:48.134805Z","end":"2026-04-27T21:01:48.249242Z","steps":["trace[1255655235] 'process raft request' (duration: 113.979023ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.249561Z","caller":"traceutil/trace.go:171","msg":"trace[838926914] transaction","detail":"{read_only:false; response_revision:4319; number_of_response:1; }","duration":"106.475853ms","start":"2026-04-27T21:01:48.143074Z","end":"2026-04-27T21:01:48.249549Z","steps":["trace[838926914] 'process raft request' (duration: 105.76849ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.249789Z","caller":"traceutil/trace.go:171","msg":"trace[718588456] linearizableReadLoop","detail":"{readStateIndex:4484; appliedIndex:4483; }","duration":"124.316807ms","start":"2026-04-27T21:01:48.125461Z","end":"2026-04-27T21:01:48.249778Z","steps":["trace[718588456] 'read index received' (duration: 67.496653ms)","trace[718588456] 'applied index is now lower than readState.Index' (duration: 56.819074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:01:48.24997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.583226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:01:48.250003Z","caller":"traceutil/trace.go:171","msg":"trace[467587199] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4325; }","duration":"152.658768ms","start":"2026-04-27T21:01:48.097335Z","end":"2026-04-27T21:01:48.249993Z","steps":["trace[467587199] 'agreement among raft nodes before linearized reading' (duration: 152.589046ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.584149Z","caller":"traceutil/trace.go:171","msg":"trace[160962177] transaction","detail":"{read_only:false; response_revision:4338; number_of_response:1; }","duration":"180.361406ms","start":"2026-04-27T21:01:48.403755Z","end":"2026-04-27T21:01:48.584117Z","steps":["trace[160962177] 'process raft request' (duration: 175.050737ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.58463Z","caller":"traceutil/trace.go:171","msg":"trace[505321962] transaction","detail":"{read_only:false; response_revision:4339; number_of_response:1; }","duration":"163.468149ms","start":"2026-04-27T21:01:48.421135Z","end":"2026-04-27T21:01:48.584604Z","steps":["trace[505321962] 'process raft request' (duration: 163.020451ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:48.586055Z","caller":"traceutil/trace.go:171","msg":"trace[1986674070] linearizableReadLoop","detail":"{readStateIndex:4509; appliedIndex:4506; }","duration":"102.831856ms","start":"2026-04-27T21:01:48.483207Z","end":"2026-04-27T21:01:48.586039Z","steps":["trace[1986674070] 'read index received' (duration: 95.690892ms)","trace[1986674070] 'applied index is now lower than readState.Index' (duration: 7.140054ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:48.586377Z","caller":"traceutil/trace.go:171","msg":"trace[1089104628] transaction","detail":"{read_only:false; response_revision:4340; number_of_response:1; }","duration":"145.928772ms","start":"2026-04-27T21:01:48.440433Z","end":"2026-04-27T21:01:48.586362Z","steps":["trace[1089104628] 'process raft request' (duration: 145.35208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:48.586479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.272213ms","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-27T21:01:48.586537Z","caller":"traceutil/trace.go:171","msg":"trace[1548410906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4341; }","duration":"103.380325ms","start":"2026-04-27T21:01:48.483126Z","end":"2026-04-27T21:01:48.586507Z","steps":["trace[1548410906] 'agreement among raft nodes before linearized reading' (duration: 103.213692ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:52.402093Z","caller":"traceutil/trace.go:171","msg":"trace[1045929298] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"103.410296ms","start":"2026-04-27T21:01:52.298596Z","end":"2026-04-27T21:01:52.402006Z","steps":["trace[1045929298] 'process raft request' (duration: 40.228755ms)","trace[1045929298] 'compare' (duration: 62.932286ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:54.228807Z","caller":"traceutil/trace.go:171","msg":"trace[954457994] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"119.963172ms","start":"2026-04-27T21:01:54.108814Z","end":"2026-04-27T21:01:54.228777Z","steps":["trace[954457994] 'process raft request' (duration: 119.691047ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:56.704813Z","caller":"traceutil/trace.go:171","msg":"trace[1204046854] transaction","detail":"{read_only:false; response_revision:4521; number_of_response:1; }","duration":"194.713833ms","start":"2026-04-27T21:01:56.510062Z","end":"2026-04-27T21:01:56.704776Z","steps":["trace[1204046854] 'process raft request' (duration: 194.439108ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:56.705402Z","caller":"traceutil/trace.go:171","msg":"trace[1440454078] linearizableReadLoop","detail":"{readStateIndex:4695; appliedIndex:4691; }","duration":"167.628725ms","start":"2026-04-27T21:01:56.537743Z","end":"2026-04-27T21:01:56.705372Z","steps":["trace[1440454078] 'read index received' (duration: 58.149937ms)","trace[1440454078] 'applied index is now lower than readState.Index' (duration: 109.477368ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:56.705431Z","caller":"traceutil/trace.go:171","msg":"trace[796335516] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"194.825505ms","start":"2026-04-27T21:01:56.510573Z","end":"2026-04-27T21:01:56.705399Z","steps":["trace[796335516] 'process raft request' (duration: 194.479569ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:56.705433Z","caller":"traceutil/trace.go:171","msg":"trace[1841200314] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"194.963637ms","start":"2026-04-27T21:01:56.510417Z","end":"2026-04-27T21:01:56.705381Z","steps":["trace[1841200314] 'process raft request' (duration: 194.272655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:56.70556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.796159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:01:56.705705Z","caller":"traceutil/trace.go:171","msg":"trace[1987426312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4523; }","duration":"167.995572ms","start":"2026-04-27T21:01:56.537688Z","end":"2026-04-27T21:01:56.705684Z","steps":["trace[1987426312] 'agreement among raft nodes before linearized reading' (duration: 167.792948ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:58.691455Z","caller":"traceutil/trace.go:171","msg":"trace[1862282892] linearizableReadLoop","detail":"{readStateIndex:4752; appliedIndex:4751; }","duration":"176.704576ms","start":"2026-04-27T21:01:58.514723Z","end":"2026-04-27T21:01:58.691428Z","steps":["trace[1862282892] 'read index received' (duration: 176.433451ms)","trace[1862282892] 'applied index is now lower than readState.Index' (duration: 269.965µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:58.691778Z","caller":"traceutil/trace.go:171","msg":"trace[1437111702] transaction","detail":"{read_only:false; response_revision:4576; number_of_response:1; }","duration":"345.304004ms","start":"2026-04-27T21:01:58.346455Z","end":"2026-04-27T21:01:58.691759Z","steps":["trace[1437111702] 'process raft request' (duration: 344.758354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:58.691919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:58.34644Z","time spent":"345.390935ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:01:58.692109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.358908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T21:01:58.692161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.412159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71308b0a-e1b0-4189-95fd-8b8bfd396d27\" ","response":"range_response_count:1 size:4061"} {"level":"info","ts":"2026-04-27T21:01:58.692249Z","caller":"traceutil/trace.go:171","msg":"trace[1824996833] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-71308b0a-e1b0-4189-95fd-8b8bfd396d27; range_end:; response_count:1; response_revision:4576; }","duration":"177.544071ms","start":"2026-04-27T21:01:58.514685Z","end":"2026-04-27T21:01:58.692229Z","steps":["trace[1824996833] 'agreement among raft nodes before linearized reading' (duration: 177.275467ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:58.692258Z","caller":"traceutil/trace.go:171","msg":"trace[1335988539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4576; }","duration":"154.532481ms","start":"2026-04-27T21:01:58.53767Z","end":"2026-04-27T21:01:58.692202Z","steps":["trace[1335988539] 'agreement among raft nodes before linearized reading' (duration: 154.270186ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:04.128178Z","caller":"traceutil/trace.go:171","msg":"trace[942406453] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"117.7714ms","start":"2026-04-27T21:02:04.01038Z","end":"2026-04-27T21:02:04.128151Z","steps":["trace[942406453] 'process raft request' (duration: 117.568406ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:04.336053Z","caller":"traceutil/trace.go:171","msg":"trace[1692634442] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4870; }","duration":"207.564956ms","start":"2026-04-27T21:02:04.12846Z","end":"2026-04-27T21:02:04.336025Z","steps":["trace[1692634442] 'read index received' (duration: 171.874263ms)","trace[1692634442] 'applied index is now lower than readState.Index' (duration: 35.689693ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:02:04.33614Z","caller":"traceutil/trace.go:171","msg":"trace[156131211] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"271.025308ms","start":"2026-04-27T21:02:04.065066Z","end":"2026-04-27T21:02:04.336091Z","steps":["trace[156131211] 'process raft request' (duration: 235.396796ms)","trace[156131211] 'compare' (duration: 35.428458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:02:04.336309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.94633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-27T21:02:04.336408Z","caller":"traceutil/trace.go:171","msg":"trace[603421294] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4694; }","duration":"214.076132ms","start":"2026-04-27T21:02:04.122309Z","end":"2026-04-27T21:02:04.336385Z","steps":["trace[603421294] 'agreement among raft nodes before linearized reading' (duration: 213.743016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:04.336336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.672799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c479bf7-b1e1-45b7-8d74-c4c6af2d6e3a\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-27T21:02:04.336542Z","caller":"traceutil/trace.go:171","msg":"trace[1232914153] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c479bf7-b1e1-45b7-8d74-c4c6af2d6e3a; range_end:; response_count:1; response_revision:4694; }","duration":"221.893083ms","start":"2026-04-27T21:02:04.114631Z","end":"2026-04-27T21:02:04.336525Z","steps":["trace[1232914153] 'agreement among raft nodes before linearized reading' (duration: 221.564398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:04.336651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.339204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c479bf7-b1e1-45b7-8d74-c4c6af2d6e3a\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-04-27T21:02:04.336723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.795285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:02:04.3368Z","caller":"traceutil/trace.go:171","msg":"trace[851465421] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4694; }","duration":"129.922958ms","start":"2026-04-27T21:02:04.206855Z","end":"2026-04-27T21:02:04.336778Z","steps":["trace[851465421] 'agreement among raft nodes before linearized reading' (duration: 129.392508ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:04.336817Z","caller":"traceutil/trace.go:171","msg":"trace[1925453665] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c479bf7-b1e1-45b7-8d74-c4c6af2d6e3a; range_end:; response_count:1; response_revision:4694; }","duration":"266.564527ms","start":"2026-04-27T21:02:04.07019Z","end":"2026-04-27T21:02:04.336754Z","steps":["trace[1925453665] 'agreement among raft nodes before linearized reading' (duration: 266.11702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:04.749138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.044992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:02:04.749259Z","caller":"traceutil/trace.go:171","msg":"trace[1781875039] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4704; }","duration":"139.208695ms","start":"2026-04-27T21:02:04.610032Z","end":"2026-04-27T21:02:04.74924Z","steps":["trace[1781875039] 'range keys from in-memory index tree' (duration: 138.980271ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:07.565149Z","caller":"traceutil/trace.go:171","msg":"trace[345551271] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"199.004347ms","start":"2026-04-27T21:02:07.366113Z","end":"2026-04-27T21:02:07.565117Z","steps":["trace[345551271] 'process raft request' (duration: 198.788183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:16.419954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.361636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-27T21:02:16.420035Z","caller":"traceutil/trace.go:171","msg":"trace[1383859612] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4862; }","duration":"173.488769ms","start":"2026-04-27T21:02:16.246526Z","end":"2026-04-27T21:02:16.420015Z","steps":["trace[1383859612] 'range keys from in-memory index tree' (duration: 173.200873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:16.420468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.933923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-04-27T21:02:16.420513Z","caller":"traceutil/trace.go:171","msg":"trace[581257538] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4862; }","duration":"180.010444ms","start":"2026-04-27T21:02:16.240492Z","end":"2026-04-27T21:02:16.420502Z","steps":["trace[581257538] 'range keys from in-memory index tree' (duration: 179.820291ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:16.625804Z","caller":"traceutil/trace.go:171","msg":"trace[87207511] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"192.96446ms","start":"2026-04-27T21:02:16.432821Z","end":"2026-04-27T21:02:16.625785Z","steps":["trace[87207511] 'process raft request' (duration: 192.843338ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:16.625859Z","caller":"traceutil/trace.go:171","msg":"trace[1410879023] linearizableReadLoop","detail":"{readStateIndex:5045; appliedIndex:5045; }","duration":"163.534195ms","start":"2026-04-27T21:02:16.462287Z","end":"2026-04-27T21:02:16.625821Z","steps":["trace[1410879023] 'read index received' (duration: 163.521494ms)","trace[1410879023] 'applied index is now lower than readState.Index' (duration: 10.411µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:02:16.626352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.063663ms","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-27T21:02:16.626388Z","caller":"traceutil/trace.go:171","msg":"trace[1186926249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4863; }","duration":"164.149136ms","start":"2026-04-27T21:02:16.462231Z","end":"2026-04-27T21:02:16.62638Z","steps":["trace[1186926249] 'agreement among raft nodes before linearized reading' (duration: 164.043154ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:44.99294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2888} {"level":"info","ts":"2026-04-27T21:03:45.078231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2888,"took":"84.032525ms","hash":625769359,"current-db-size-bytes":52662272,"current-db-size":"53 MB","current-db-size-in-use-bytes":29388800,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-27T21:03:45.078286Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":625769359,"revision":2888,"compact-revision":-1} {"level":"warn","ts":"2026-04-27T21:06:04.040499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.194079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-27T21:06:04.040577Z","caller":"traceutil/trace.go:171","msg":"trace[1468128800] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6563; }","duration":"112.311991ms","start":"2026-04-27T21:06:03.928248Z","end":"2026-04-27T21:06:04.04056Z","steps":["trace[1468128800] 'range keys from in-memory index tree' (duration: 112.061127ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:44.997943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5604} {"level":"info","ts":"2026-04-27T21:08:45.088245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5604,"took":"88.919856ms","hash":506544484,"current-db-size-bytes":52662272,"current-db-size":"53 MB","current-db-size-in-use-bytes":18231296,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-27T21:08:45.088319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":506544484,"revision":5604,"compact-revision":2888}