{"level":"warn","ts":"2026-04-21T01:21:43.719242Z","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-21T01:21:43.719329Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-21T01:21:43.720162Z","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-21T01:21:43.720179Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-04-21T01:21:43.7202Z","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-21T01:21:43.720924Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-04-21T01:21:43.721009Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-21T01:21:43.728444Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.199463ms"} {"level":"info","ts":"2026-04-21T01:21:43.737306Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-04-21T01:21:43.737405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T01:21:43.737436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-04-21T01:21:43.737458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T01:21:43.737474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-04-21T01:21:43.737599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-04-21T01:21:43.745832Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T01:21:43.74694Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T01:21:43.748111Z","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-21T01:21:43.751066Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T01:21:43.754568Z","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-21T01:21:43.754703Z","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-21T01:21:43.754721Z","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-21T01:21:43.755027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-04-21T01:21:43.755149Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-04-21T01:21:43.755363Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T01:21:43.757558Z","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-21T01:21:43.757998Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T01:21:43.757808Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-04-21T01:21:43.759063Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-04-21T01:21:43.758152Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T01:21:44.438084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T01:21:44.438255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T01:21:44.438321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-04-21T01:21:44.438383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-04-21T01:21:44.438426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-04-21T01:21:44.438471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-04-21T01:21:44.438522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-04-21T01:21:44.439856Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T01:21:44.440105Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T01:21:44.44029Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:21:44.440691Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T01:21:44.440895Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T01:21:44.441Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T01:21:44.441942Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:21:44.442141Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:21:44.442203Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:21:44.444239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-04-21T01:21:44.444606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T01:21:50.490032Z","caller":"traceutil/trace.go:171","msg":"trace[1383315574] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"205.859682ms","start":"2026-04-21T01:21:50.284152Z","end":"2026-04-21T01:21:50.490011Z","steps":["trace[1383315574] 'process raft request' (duration: 205.721251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:50.877826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.641442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-21T01:21:50.877952Z","caller":"traceutil/trace.go:171","msg":"trace[709893678] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:314; }","duration":"387.95658ms","start":"2026-04-21T01:21:50.489985Z","end":"2026-04-21T01:21:50.877942Z","steps":["trace[709893678] 'read index received' (duration: 252.900095ms)","trace[709893678] 'applied index is now lower than readState.Index' (duration: 135.055985ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:21:50.878022Z","caller":"traceutil/trace.go:171","msg":"trace[1470214243] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"441.320053ms","start":"2026-04-21T01:21:50.436691Z","end":"2026-04-21T01:21:50.878011Z","steps":["trace[1470214243] 'process raft request' (duration: 306.185678ms)","trace[1470214243] 'compare' (duration: 134.59465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:21:50.878063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.436674Z","time spent":"441.370273ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T01:21:50.878044Z","caller":"traceutil/trace.go:171","msg":"trace[1446460724] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"440.873879ms","start":"2026-04-21T01:21:50.437134Z","end":"2026-04-21T01:21:50.878008Z","steps":["trace[1446460724] 'process raft request' (duration: 440.775428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:50.878152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.829395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T01:21:50.878195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.437122Z","time spent":"441.008761ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T01:21:50.878228Z","caller":"traceutil/trace.go:171","msg":"trace[1896968440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:306; }","duration":"455.946267ms","start":"2026-04-21T01:21:50.422261Z","end":"2026-04-21T01:21:50.878208Z","steps":["trace[1896968440] 'agreement among raft nodes before linearized reading' (duration: 455.785035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:50.878272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.422253Z","time spent":"456.011117ms","remote":"127.0.0.1:42202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:21:50.878334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.052305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-04-21T01:21:50.878355Z","caller":"traceutil/trace.go:171","msg":"trace[1638931129] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:306; }","duration":"461.114506ms","start":"2026-04-21T01:21:50.417233Z","end":"2026-04-21T01:21:50.878348Z","steps":["trace[1638931129] 'agreement among raft nodes before linearized reading' (duration: 461.073345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:50.87837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.417211Z","time spent":"461.155246ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5920,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-04-21T01:21:51.07613Z","caller":"traceutil/trace.go:171","msg":"trace[978450164] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"582.646689ms","start":"2026-04-21T01:21:50.493462Z","end":"2026-04-21T01:21:51.076109Z","steps":["trace[978450164] 'process raft request' (duration: 582.533958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:51.076357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.713828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-21T01:21:51.076171Z","caller":"traceutil/trace.go:171","msg":"trace[1057561844] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:317; }","duration":"198.182065ms","start":"2026-04-21T01:21:50.877972Z","end":"2026-04-21T01:21:51.076154Z","steps":["trace[1057561844] 'read index received' (duration: 198.176615ms)","trace[1057561844] 'applied index is now lower than readState.Index' (duration: 4.62µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:21:51.076503Z","caller":"traceutil/trace.go:171","msg":"trace[1227059087] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:307; }","duration":"583.882959ms","start":"2026-04-21T01:21:50.4926Z","end":"2026-04-21T01:21:51.076483Z","steps":["trace[1227059087] 'agreement among raft nodes before linearized reading' (duration: 583.666646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:51.076573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.492588Z","time spent":"583.963299ms","remote":"127.0.0.1:42410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-04-21T01:21:51.076956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.493453Z","time spent":"582.74218ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:21:51.334456Z","caller":"traceutil/trace.go:171","msg":"trace[41155568] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"257.97333ms","start":"2026-04-21T01:21:51.07646Z","end":"2026-04-21T01:21:51.334433Z","steps":["trace[41155568] 'read index received' (duration: 257.716668ms)","trace[41155568] 'applied index is now lower than readState.Index' (duration: 255.722µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:21:51.334485Z","caller":"traceutil/trace.go:171","msg":"trace[1087151541] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"423.846957ms","start":"2026-04-21T01:21:50.910612Z","end":"2026-04-21T01:21:51.334459Z","steps":["trace[1087151541] 'process raft request' (duration: 423.665305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:51.33462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.910593Z","time spent":"423.956027ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:21:51.334936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.716802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-21T01:21:51.334966Z","caller":"traceutil/trace.go:171","msg":"trace[389598053] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:308; }","duration":"455.775063ms","start":"2026-04-21T01:21:50.879184Z","end":"2026-04-21T01:21:51.334959Z","steps":["trace[389598053] 'agreement among raft nodes before linearized reading' (duration: 455.699913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:51.334986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.879167Z","time spent":"455.811713ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-21T01:21:51.335079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.751418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-21T01:21:51.335094Z","caller":"traceutil/trace.go:171","msg":"trace[1346027965] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:308; }","duration":"253.783738ms","start":"2026-04-21T01:21:51.081306Z","end":"2026-04-21T01:21:51.335089Z","steps":["trace[1346027965] 'agreement among raft nodes before linearized reading' (duration: 253.733638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:51.33552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.524083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-04-21T01:21:51.335578Z","caller":"traceutil/trace.go:171","msg":"trace[497995877] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:308; }","duration":"455.27283ms","start":"2026-04-21T01:21:50.88029Z","end":"2026-04-21T01:21:51.335563Z","steps":["trace[497995877] 'agreement among raft nodes before linearized reading' (duration: 454.270662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:21:51.33573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:21:50.880277Z","time spent":"455.439191ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7326,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-04-21T01:22:14.160855Z","caller":"traceutil/trace.go:171","msg":"trace[2135809080] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"150.736811ms","start":"2026-04-21T01:22:14.010047Z","end":"2026-04-21T01:22:14.160784Z","steps":["trace[2135809080] 'process raft request' (duration: 150.671891ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.160878Z","caller":"traceutil/trace.go:171","msg":"trace[1457020187] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"154.418554ms","start":"2026-04-21T01:22:14.006406Z","end":"2026-04-21T01:22:14.160825Z","steps":["trace[1457020187] 'process raft request' (duration: 153.7563ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.167479Z","caller":"traceutil/trace.go:171","msg":"trace[1874345074] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"127.661561ms","start":"2026-04-21T01:22:14.039778Z","end":"2026-04-21T01:22:14.16744Z","steps":["trace[1874345074] 'process raft request' (duration: 127.59767ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.168104Z","caller":"traceutil/trace.go:171","msg":"trace[1387982446] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"155.43395ms","start":"2026-04-21T01:22:14.012656Z","end":"2026-04-21T01:22:14.168089Z","steps":["trace[1387982446] 'process raft request' (duration: 154.096912ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.168325Z","caller":"traceutil/trace.go:171","msg":"trace[1285966284] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"131.434383ms","start":"2026-04-21T01:22:14.036874Z","end":"2026-04-21T01:22:14.168308Z","steps":["trace[1285966284] 'process raft request' (duration: 130.363567ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.168313Z","caller":"traceutil/trace.go:171","msg":"trace[1457265192] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"134.361861ms","start":"2026-04-21T01:22:14.033919Z","end":"2026-04-21T01:22:14.168281Z","steps":["trace[1457265192] 'process raft request' (duration: 133.258865ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.168685Z","caller":"traceutil/trace.go:171","msg":"trace[45142828] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"145.711311ms","start":"2026-04-21T01:22:14.022959Z","end":"2026-04-21T01:22:14.16867Z","steps":["trace[45142828] 'process raft request' (duration: 143.97292ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.168912Z","caller":"traceutil/trace.go:171","msg":"trace[582729670] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"145.871481ms","start":"2026-04-21T01:22:14.023032Z","end":"2026-04-21T01:22:14.168903Z","steps":["trace[582729670] 'process raft request' (duration: 144.028321ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.169022Z","caller":"traceutil/trace.go:171","msg":"trace[34969671] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"145.921232ms","start":"2026-04-21T01:22:14.023093Z","end":"2026-04-21T01:22:14.169014Z","steps":["trace[34969671] 'process raft request' (duration: 144.035511ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.343051Z","caller":"traceutil/trace.go:171","msg":"trace[461160760] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"157.15615ms","start":"2026-04-21T01:22:14.185864Z","end":"2026-04-21T01:22:14.34302Z","steps":["trace[461160760] 'process raft request' (duration: 90.137831ms)","trace[461160760] 'compare' (duration: 66.833948ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:22:14.343887Z","caller":"traceutil/trace.go:171","msg":"trace[359037720] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"154.405334ms","start":"2026-04-21T01:22:14.189446Z","end":"2026-04-21T01:22:14.343852Z","steps":["trace[359037720] 'process raft request' (duration: 154.065762ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.344097Z","caller":"traceutil/trace.go:171","msg":"trace[1025147629] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"150.149347ms","start":"2026-04-21T01:22:14.193922Z","end":"2026-04-21T01:22:14.344072Z","steps":["trace[1025147629] 'process raft request' (duration: 149.807085ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.344237Z","caller":"traceutil/trace.go:171","msg":"trace[1569975114] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"146.127033ms","start":"2026-04-21T01:22:14.198062Z","end":"2026-04-21T01:22:14.344189Z","steps":["trace[1569975114] 'process raft request' (duration: 145.846491ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:14.344407Z","caller":"traceutil/trace.go:171","msg":"trace[470017890] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:553; }","duration":"112.990281ms","start":"2026-04-21T01:22:14.231372Z","end":"2026-04-21T01:22:14.344363Z","steps":["trace[470017890] 'read index received' (duration: 44.627023ms)","trace[470017890] 'applied index is now lower than readState.Index' (duration: 68.362208ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:22:14.344446Z","caller":"traceutil/trace.go:171","msg":"trace[1245763913] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"138.735118ms","start":"2026-04-21T01:22:14.205695Z","end":"2026-04-21T01:22:14.34443Z","steps":["trace[1245763913] 'process raft request' (duration: 138.359926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:22:14.344623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.241892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-21T01:22:14.344671Z","caller":"traceutil/trace.go:171","msg":"trace[1429799820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"113.345563ms","start":"2026-04-21T01:22:14.231313Z","end":"2026-04-21T01:22:14.344658Z","steps":["trace[1429799820] 'agreement among raft nodes before linearized reading' (duration: 113.210213ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:17.600852Z","caller":"traceutil/trace.go:171","msg":"trace[1273817057] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"147.925955ms","start":"2026-04-21T01:22:17.452859Z","end":"2026-04-21T01:22:17.600785Z","steps":["trace[1273817057] 'process raft request' (duration: 147.472022ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:20.653496Z","caller":"traceutil/trace.go:171","msg":"trace[1029003527] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"100.239477ms","start":"2026-04-21T01:22:20.553225Z","end":"2026-04-21T01:22:20.653465Z","steps":["trace[1029003527] 'process raft request' (duration: 99.540954ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:22:20.827924Z","caller":"traceutil/trace.go:171","msg":"trace[893328763] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"204.885062ms","start":"2026-04-21T01:22:20.623006Z","end":"2026-04-21T01:22:20.827891Z","steps":["trace[893328763] 'process raft request' (duration: 191.458653ms)","trace[893328763] 'compare' (duration: 13.276148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:22:21.475215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.976956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:22:21.475299Z","caller":"traceutil/trace.go:171","msg":"trace[656308721] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"198.936079ms","start":"2026-04-21T01:22:21.276349Z","end":"2026-04-21T01:22:21.475285Z","steps":["trace[656308721] 'read index received' (duration: 87.731901ms)","trace[656308721] 'applied index is now lower than readState.Index' (duration: 111.203348ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:22:21.475377Z","caller":"traceutil/trace.go:171","msg":"trace[751871718] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"760.197353ms","start":"2026-04-21T01:22:20.715136Z","end":"2026-04-21T01:22:21.475333Z","steps":["trace[751871718] 'process raft request' (duration: 649.026035ms)","trace[751871718] 'compare' (duration: 110.673065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:22:21.475473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.12737ms","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-21T01:22:21.475494Z","caller":"traceutil/trace.go:171","msg":"trace[46776491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"199.1994ms","start":"2026-04-21T01:22:21.276288Z","end":"2026-04-21T01:22:21.475487Z","steps":["trace[46776491] 'agreement among raft nodes before linearized reading' (duration: 199.06306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:22:21.47552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:22:20.715106Z","time spent":"760.345093ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T01:22:22.142128Z","caller":"traceutil/trace.go:171","msg":"trace[791878966] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"180.66763ms","start":"2026-04-21T01:22:21.961429Z","end":"2026-04-21T01:22:22.142096Z","steps":["trace[791878966] 'read index received' (duration: 179.694274ms)","trace[791878966] 'applied index is now lower than readState.Index' (duration: 972.346µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:22:22.142278Z","caller":"traceutil/trace.go:171","msg":"trace[930670058] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"215.264681ms","start":"2026-04-21T01:22:21.926911Z","end":"2026-04-21T01:22:22.142175Z","steps":["trace[930670058] 'process raft request' (duration: 214.518486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:22:22.14233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.877451ms","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-21T01:22:22.14237Z","caller":"traceutil/trace.go:171","msg":"trace[1375233788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"180.981472ms","start":"2026-04-21T01:22:21.961377Z","end":"2026-04-21T01:22:22.142359Z","steps":["trace[1375233788] 'agreement among raft nodes before linearized reading' (duration: 180.830501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:32.058575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.316263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:23:32.058667Z","caller":"traceutil/trace.go:171","msg":"trace[1574523577] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1464; }","duration":"118.448586ms","start":"2026-04-21T01:23:31.940198Z","end":"2026-04-21T01:23:32.058646Z","steps":["trace[1574523577] 'count revisions from in-memory index tree' (duration: 118.253252ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:32.14947Z","caller":"traceutil/trace.go:171","msg":"trace[1904281607] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"193.941394ms","start":"2026-04-21T01:23:31.955506Z","end":"2026-04-21T01:23:32.149448Z","steps":["trace[1904281607] 'process raft request' (duration: 193.805271ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:34.092231Z","caller":"traceutil/trace.go:171","msg":"trace[612834090] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"112.595981ms","start":"2026-04-21T01:23:33.979609Z","end":"2026-04-21T01:23:34.092205Z","steps":["trace[612834090] 'process raft request' (duration: 99.992035ms)","trace[612834090] 'compare' (duration: 12.274259ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:23:34.092296Z","caller":"traceutil/trace.go:171","msg":"trace[659604780] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"110.420827ms","start":"2026-04-21T01:23:33.981848Z","end":"2026-04-21T01:23:34.092268Z","steps":["trace[659604780] 'process raft request' (duration: 110.310925ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:55.190237Z","caller":"traceutil/trace.go:171","msg":"trace[1293225095] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"185.733193ms","start":"2026-04-21T01:23:55.004478Z","end":"2026-04-21T01:23:55.190211Z","steps":["trace[1293225095] 'process raft request' (duration: 185.54251ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:55.190714Z","caller":"traceutil/trace.go:171","msg":"trace[540979442] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"184.99304ms","start":"2026-04-21T01:23:55.005704Z","end":"2026-04-21T01:23:55.190697Z","steps":["trace[540979442] 'process raft request' (duration: 184.469672ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:55.190902Z","caller":"traceutil/trace.go:171","msg":"trace[1718160629] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1921; }","duration":"181.558565ms","start":"2026-04-21T01:23:55.00929Z","end":"2026-04-21T01:23:55.190848Z","steps":["trace[1718160629] 'read index received' (duration: 11.669868ms)","trace[1718160629] 'applied index is now lower than readState.Index' (duration: 169.886997ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:23:55.191012Z","caller":"traceutil/trace.go:171","msg":"trace[369922005] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"184.075006ms","start":"2026-04-21T01:23:55.006885Z","end":"2026-04-21T01:23:55.19096Z","steps":["trace[369922005] 'process raft request' (duration: 183.7262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:55.191227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.923721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a135bf4-bb6e-4572-a6d2-ab6ba097b064\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-21T01:23:55.191278Z","caller":"traceutil/trace.go:171","msg":"trace[1596800943] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a135bf4-bb6e-4572-a6d2-ab6ba097b064; range_end:; response_count:1; response_revision:1856; }","duration":"182.029152ms","start":"2026-04-21T01:23:55.009235Z","end":"2026-04-21T01:23:55.191264Z","steps":["trace[1596800943] 'agreement among raft nodes before linearized reading' (duration: 181.862149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:55.191608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.379899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-21T01:23:55.191975Z","caller":"traceutil/trace.go:171","msg":"trace[284535737] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1856; }","duration":"179.698324ms","start":"2026-04-21T01:23:55.012168Z","end":"2026-04-21T01:23:55.191866Z","steps":["trace[284535737] 'agreement among raft nodes before linearized reading' (duration: 179.238127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:55.763233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872211440060349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T01:23:56.08447Z","caller":"traceutil/trace.go:171","msg":"trace[882271463] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"822.001911ms","start":"2026-04-21T01:23:55.262433Z","end":"2026-04-21T01:23:56.084435Z","steps":["trace[882271463] 'read index received' (duration: 821.754358ms)","trace[882271463] 'applied index is now lower than readState.Index' (duration: 246.103µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:23:56.084638Z","caller":"traceutil/trace.go:171","msg":"trace[1610660316] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"887.792768ms","start":"2026-04-21T01:23:55.196832Z","end":"2026-04-21T01:23:56.084625Z","steps":["trace[1610660316] 'process raft request' (duration: 887.401182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:56.0848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:55.196815Z","time spent":"887.862739ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:23:56.0851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.661941ms","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-21T01:23:56.085144Z","caller":"traceutil/trace.go:171","msg":"trace[833841375] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"822.741792ms","start":"2026-04-21T01:23:55.262391Z","end":"2026-04-21T01:23:56.085133Z","steps":["trace[833841375] 'agreement among raft nodes before linearized reading' (duration: 822.58229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:56.085178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:55.262372Z","time spent":"822.798344ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-21T01:23:56.08541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.611049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:23:56.085582Z","caller":"traceutil/trace.go:171","msg":"trace[1182537699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1858; }","duration":"661.860214ms","start":"2026-04-21T01:23:55.423699Z","end":"2026-04-21T01:23:56.085559Z","steps":["trace[1182537699] 'agreement among raft nodes before linearized reading' (duration: 661.385495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:56.08569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.93527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a135bf4-bb6e-4572-a6d2-ab6ba097b064\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-04-21T01:23:56.085708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.895214ms","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-21T01:23:56.085785Z","caller":"traceutil/trace.go:171","msg":"trace[1444592645] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a135bf4-bb6e-4572-a6d2-ab6ba097b064; range_end:; response_count:1; response_revision:1858; }","duration":"203.081743ms","start":"2026-04-21T01:23:55.882686Z","end":"2026-04-21T01:23:56.085768Z","steps":["trace[1444592645] 'agreement among raft nodes before linearized reading' (duration: 202.90685ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:56.085791Z","caller":"traceutil/trace.go:171","msg":"trace[949762009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1858; }","duration":"239.028066ms","start":"2026-04-21T01:23:55.84675Z","end":"2026-04-21T01:23:56.085778Z","steps":["trace[949762009] 'agreement among raft nodes before linearized reading' (duration: 238.717421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:56.085908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:55.42368Z","time spent":"662.056787ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:23:56.085949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.758422ms","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":"warn","ts":"2026-04-21T01:23:56.085617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.418008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:23:56.086122Z","caller":"traceutil/trace.go:171","msg":"trace[1626910439] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1858; }","duration":"383.997997ms","start":"2026-04-21T01:23:55.7021Z","end":"2026-04-21T01:23:56.086098Z","steps":["trace[1626910439] 'agreement among raft nodes before linearized reading' (duration: 383.417458ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:56.086148Z","caller":"traceutil/trace.go:171","msg":"trace[2095262559] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"526.964195ms","start":"2026-04-21T01:23:55.559122Z","end":"2026-04-21T01:23:56.086086Z","steps":["trace[2095262559] 'agreement among raft nodes before linearized reading' (duration: 526.453707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:56.086187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:55.702081Z","time spent":"384.091618ms","remote":"127.0.0.1:42534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:23:56.086229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:55.559101Z","time spent":"527.113658ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-21T01:23:56.404365Z","caller":"traceutil/trace.go:171","msg":"trace[1150308716] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"310.464583ms","start":"2026-04-21T01:23:56.093876Z","end":"2026-04-21T01:23:56.40434Z","steps":["trace[1150308716] 'process raft request' (duration: 310.21475ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:56.404572Z","caller":"traceutil/trace.go:171","msg":"trace[175441330] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1926; }","duration":"289.981235ms","start":"2026-04-21T01:23:56.114565Z","end":"2026-04-21T01:23:56.404547Z","steps":["trace[175441330] 'read index received' (duration: 28.543759ms)","trace[175441330] 'applied index is now lower than readState.Index' (duration: 261.435186ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:23:56.404601Z","caller":"traceutil/trace.go:171","msg":"trace[321843738] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"306.335947ms","start":"2026-04-21T01:23:56.09823Z","end":"2026-04-21T01:23:56.404566Z","steps":["trace[321843738] 'process raft request' (duration: 306.012622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:56.405561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.098218Z","time spent":"307.249873ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10929,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:23:56.404876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.297071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:23:56.405801Z","caller":"traceutil/trace.go:171","msg":"trace[444291487] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1861; }","duration":"291.280926ms","start":"2026-04-21T01:23:56.114504Z","end":"2026-04-21T01:23:56.405785Z","steps":["trace[444291487] 'agreement among raft nodes before linearized reading' (duration: 290.114467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:56.405178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.093849Z","time spent":"311.256067ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:23:56.470768Z","caller":"traceutil/trace.go:171","msg":"trace[750527773] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"176.304666ms","start":"2026-04-21T01:23:56.294435Z","end":"2026-04-21T01:23:56.47074Z","steps":["trace[750527773] 'process raft request' (duration: 143.62904ms)","trace[750527773] 'compare' (duration: 32.550125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:23:57.146045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.490714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:23:57.146599Z","caller":"traceutil/trace.go:171","msg":"trace[1440441038] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"733.559383ms","start":"2026-04-21T01:23:56.413011Z","end":"2026-04-21T01:23:57.146571Z","steps":["trace[1440441038] 'process raft request' (duration: 733.222528ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:57.146606Z","caller":"traceutil/trace.go:171","msg":"trace[1555443486] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"732.874502ms","start":"2026-04-21T01:23:56.413702Z","end":"2026-04-21T01:23:57.146577Z","steps":["trace[1555443486] 'process raft request' (duration: 732.648679ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:57.14671Z","caller":"traceutil/trace.go:171","msg":"trace[106656494] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"730.017096ms","start":"2026-04-21T01:23:56.416672Z","end":"2026-04-21T01:23:57.146689Z","steps":["trace[106656494] 'process raft request' (duration: 729.714141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:57.146756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.412999Z","time spent":"733.671375ms","remote":"127.0.0.1:42484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:23:57.14679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.41369Z","time spent":"732.989984ms","remote":"127.0.0.1:42484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:23:57.146798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.416658Z","time spent":"730.093148ms","remote":"127.0.0.1:42372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:23:57.146606Z","caller":"traceutil/trace.go:171","msg":"trace[1830630062] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"734.187823ms","start":"2026-04-21T01:23:56.412393Z","end":"2026-04-21T01:23:57.14658Z","steps":["trace[1830630062] 'process raft request' (duration: 174.06219ms)","trace[1830630062] 'compare' (duration: 559.23538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:23:57.146893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.412295Z","time spent":"734.57226ms","remote":"127.0.0.1:42372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:23:57.147137Z","caller":"traceutil/trace.go:171","msg":"trace[1573651532] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"122.283943ms","start":"2026-04-21T01:23:57.024834Z","end":"2026-04-21T01:23:57.147118Z","steps":["trace[1573651532] 'process raft request' (duration: 122.215352ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:23:57.14715Z","caller":"traceutil/trace.go:171","msg":"trace[1314798697] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1930; }","duration":"570.049963ms","start":"2026-04-21T01:23:56.577077Z","end":"2026-04-21T01:23:57.147127Z","steps":["trace[1314798697] 'read index received' (duration: 9.34099ms)","trace[1314798697] 'applied index is now lower than readState.Index' (duration: 560.706603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:23:57.147277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.183815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:23:57.147338Z","caller":"traceutil/trace.go:171","msg":"trace[290910943] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1867; }","duration":"570.287026ms","start":"2026-04-21T01:23:56.577032Z","end":"2026-04-21T01:23:57.147319Z","steps":["trace[290910943] 'agreement among raft nodes before linearized reading' (duration: 570.165084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:57.147393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.577015Z","time spent":"570.363688ms","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:23:57.147439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.627505ms","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-21T01:23:57.148186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.25441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:23:57.148244Z","caller":"traceutil/trace.go:171","msg":"trace[1677473409] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1867; }","duration":"311.358762ms","start":"2026-04-21T01:23:56.836867Z","end":"2026-04-21T01:23:57.148225Z","steps":["trace[1677473409] 'agreement among raft nodes before linearized reading' (duration: 311.26642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:57.148284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.836844Z","time spent":"311.429053ms","remote":"127.0.0.1:45688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-04-21T01:23:57.149393Z","caller":"traceutil/trace.go:171","msg":"trace[1053954008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1867; }","duration":"497.607927ms","start":"2026-04-21T01:23:56.651766Z","end":"2026-04-21T01:23:57.149374Z","steps":["trace[1053954008] 'agreement among raft nodes before linearized reading' (duration: 495.573055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:23:57.149498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:23:56.651739Z","time spent":"497.738159ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T01:24:02.531605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.772316ms","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-21T01:24:02.531698Z","caller":"traceutil/trace.go:171","msg":"trace[1857292195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1921; }","duration":"102.92508ms","start":"2026-04-21T01:24:02.428755Z","end":"2026-04-21T01:24:02.53168Z","steps":["trace[1857292195] 'range keys from in-memory index tree' (duration: 102.578463ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:24:10.102784Z","caller":"traceutil/trace.go:171","msg":"trace[1051141550] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"217.714171ms","start":"2026-04-21T01:24:09.885044Z","end":"2026-04-21T01:24:10.102758Z","steps":["trace[1051141550] 'process raft request' (duration: 217.574459ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:24:10.241989Z","caller":"traceutil/trace.go:171","msg":"trace[789822099] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"129.731775ms","start":"2026-04-21T01:24:10.112227Z","end":"2026-04-21T01:24:10.241959Z","steps":["trace[789822099] 'process raft request' (duration: 107.568836ms)","trace[789822099] 'compare' (duration: 22.008857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:24:47.389215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.78886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:24:47.389948Z","caller":"traceutil/trace.go:171","msg":"trace[1300642513] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"630.67029ms","start":"2026-04-21T01:24:46.759253Z","end":"2026-04-21T01:24:47.389923Z","steps":["trace[1300642513] 'read index received' (duration: 145.110622ms)","trace[1300642513] 'applied index is now lower than readState.Index' (duration: 485.556198ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:24:47.390022Z","caller":"traceutil/trace.go:171","msg":"trace[422397142] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"708.717406ms","start":"2026-04-21T01:24:46.681242Z","end":"2026-04-21T01:24:47.389959Z","steps":["trace[422397142] 'process raft request' (duration: 223.082177ms)","trace[422397142] 'compare' (duration: 484.435715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:24:47.390299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:46.681216Z","time spent":"708.939838ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:24:47.390295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.031435ms","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-21T01:24:47.390381Z","caller":"traceutil/trace.go:171","msg":"trace[1083542805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2185; }","duration":"631.166587ms","start":"2026-04-21T01:24:46.759196Z","end":"2026-04-21T01:24:47.390363Z","steps":["trace[1083542805] 'agreement among raft nodes before linearized reading' (duration: 630.853423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:47.390454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:46.759174Z","time spent":"631.266848ms","remote":"127.0.0.1:42476","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-21T01:24:48.066427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.752672ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:24:48.066566Z","caller":"traceutil/trace.go:171","msg":"trace[526812105] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2185; }","duration":"180.895764ms","start":"2026-04-21T01:24:47.885642Z","end":"2026-04-21T01:24:48.066537Z","steps":["trace[526812105] 'range keys from in-memory index tree' (duration: 180.728811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.066708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.534664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:24:48.066856Z","caller":"traceutil/trace.go:171","msg":"trace[756178099] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"730.450434ms","start":"2026-04-21T01:24:47.336392Z","end":"2026-04-21T01:24:48.066843Z","steps":["trace[756178099] 'process raft request' (duration: 208.715348ms)","trace[756178099] 'compare' (duration: 521.025508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:24:48.066925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.336359Z","time spent":"730.533495ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:24:48.0678Z","caller":"traceutil/trace.go:171","msg":"trace[3463631] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"687.935234ms","start":"2026-04-21T01:24:47.379838Z","end":"2026-04-21T01:24:48.067773Z","steps":["trace[3463631] 'process raft request' (duration: 686.966813ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:24:48.067754Z","caller":"traceutil/trace.go:171","msg":"trace[1143337524] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2263; }","duration":"677.737386ms","start":"2026-04-21T01:24:47.390003Z","end":"2026-04-21T01:24:48.06774Z","steps":["trace[1143337524] 'read index received' (duration: 155.190071ms)","trace[1143337524] 'applied index is now lower than readState.Index' (duration: 522.546425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:24:48.067877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.379807Z","time spent":"688.033294ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:24:48.067972Z","caller":"traceutil/trace.go:171","msg":"trace[1110824256] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"671.711556ms","start":"2026-04-21T01:24:47.396201Z","end":"2026-04-21T01:24:48.067912Z","steps":["trace[1110824256] 'process raft request' (duration: 671.484193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.068086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.370035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:24:48.068119Z","caller":"traceutil/trace.go:171","msg":"trace[1294682110] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2188; }","duration":"822.464276ms","start":"2026-04-21T01:24:47.245646Z","end":"2026-04-21T01:24:48.06811Z","steps":["trace[1294682110] 'agreement among raft nodes before linearized reading' (duration: 822.399856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.068159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.245611Z","time spent":"822.539247ms","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:24:48.068178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.428653ms","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":"warn","ts":"2026-04-21T01:24:48.068218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.396176Z","time spent":"671.903789ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:24:48.068346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.443125ms","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-21T01:24:48.068385Z","caller":"traceutil/trace.go:171","msg":"trace[1315029356] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2188; }","duration":"600.499775ms","start":"2026-04-21T01:24:47.467874Z","end":"2026-04-21T01:24:48.068374Z","steps":["trace[1315029356] 'agreement among raft nodes before linearized reading' (duration: 600.386984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.068415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.467864Z","time spent":"600.543255ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-21T01:24:48.068425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.168746ms","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":"warn","ts":"2026-04-21T01:24:48.068454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.051673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-21T01:24:48.068517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.486922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:24:48.068547Z","caller":"traceutil/trace.go:171","msg":"trace[1115362491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"644.579503ms","start":"2026-04-21T01:24:47.42396Z","end":"2026-04-21T01:24:48.06854Z","steps":["trace[1115362491] 'agreement among raft nodes before linearized reading' (duration: 644.537862ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:24:48.06852Z","caller":"traceutil/trace.go:171","msg":"trace[1115419151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2188; }","duration":"622.129824ms","start":"2026-04-21T01:24:47.446372Z","end":"2026-04-21T01:24:48.068501Z","steps":["trace[1115419151] 'agreement among raft nodes before linearized reading' (duration: 622.002043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.068338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.170638ms","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-21T01:24:48.068574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.423924Z","time spent":"644.642783ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:24:48.068588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.446363Z","time spent":"622.211865ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T01:24:48.068607Z","caller":"traceutil/trace.go:171","msg":"trace[596290322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2188; }","duration":"676.479121ms","start":"2026-04-21T01:24:47.392115Z","end":"2026-04-21T01:24:48.068594Z","steps":["trace[596290322] 'agreement among raft nodes before linearized reading' (duration: 676.106487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.068658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.392099Z","time spent":"676.530692ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T01:24:48.068464Z","caller":"traceutil/trace.go:171","msg":"trace[644754541] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"401.232706ms","start":"2026-04-21T01:24:47.667221Z","end":"2026-04-21T01:24:48.068453Z","steps":["trace[644754541] 'agreement among raft nodes before linearized reading' (duration: 401.128835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.068678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.840763ms","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-21T01:24:48.068704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.667207Z","time spent":"401.489799ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-21T01:24:48.068718Z","caller":"traceutil/trace.go:171","msg":"trace[1157365180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2188; }","duration":"623.927216ms","start":"2026-04-21T01:24:47.444779Z","end":"2026-04-21T01:24:48.068706Z","steps":["trace[1157365180] 'agreement among raft nodes before linearized reading' (duration: 623.716023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.068746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.444755Z","time spent":"623.984556ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T01:24:48.068224Z","caller":"traceutil/trace.go:171","msg":"trace[1292286708] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2188; }","duration":"970.520354ms","start":"2026-04-21T01:24:47.097691Z","end":"2026-04-21T01:24:48.068212Z","steps":["trace[1292286708] 'agreement among raft nodes before linearized reading' (duration: 970.442633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.06889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.097666Z","time spent":"971.205541ms","remote":"127.0.0.1:42358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:24:48.069208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.827117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T01:24:48.069521Z","caller":"traceutil/trace.go:171","msg":"trace[136306682] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:2188; }","duration":"535.204192ms","start":"2026-04-21T01:24:47.534297Z","end":"2026-04-21T01:24:48.069501Z","steps":["trace[136306682] 'agreement among raft nodes before linearized reading' (duration: 534.059989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:24:48.069604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:24:47.534266Z","time spent":"535.323253ms","remote":"127.0.0.1:42226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-04-21T01:24:48.287015Z","caller":"traceutil/trace.go:171","msg":"trace[467997029] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"213.330528ms","start":"2026-04-21T01:24:48.073662Z","end":"2026-04-21T01:24:48.286993Z","steps":["trace[467997029] 'process raft request' (duration: 213.174427ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:24:48.28712Z","caller":"traceutil/trace.go:171","msg":"trace[2135097224] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"213.386259ms","start":"2026-04-21T01:24:48.07372Z","end":"2026-04-21T01:24:48.287106Z","steps":["trace[2135097224] 'process raft request' (duration: 213.241127ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:24:48.287684Z","caller":"traceutil/trace.go:171","msg":"trace[1139036913] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"213.309739ms","start":"2026-04-21T01:24:48.074358Z","end":"2026-04-21T01:24:48.287668Z","steps":["trace[1139036913] 'process raft request' (duration: 212.727252ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:24:48.287714Z","caller":"traceutil/trace.go:171","msg":"trace[1683491329] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"213.276179ms","start":"2026-04-21T01:24:48.074426Z","end":"2026-04-21T01:24:48.287702Z","steps":["trace[1683491329] 'process raft request' (duration: 213.075046ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:18.301886Z","caller":"traceutil/trace.go:171","msg":"trace[539149769] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"117.73841ms","start":"2026-04-21T01:25:18.184122Z","end":"2026-04-21T01:25:18.30186Z","steps":["trace[539149769] 'process raft request' (duration: 117.480198ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:18.307547Z","caller":"traceutil/trace.go:171","msg":"trace[2053800003] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"121.130554ms","start":"2026-04-21T01:25:18.186365Z","end":"2026-04-21T01:25:18.307496Z","steps":["trace[2053800003] 'process raft request' (duration: 120.965293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:26.239172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.15521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-21T01:25:26.239255Z","caller":"traceutil/trace.go:171","msg":"trace[1668129762] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2440; }","duration":"203.27475ms","start":"2026-04-21T01:25:26.035961Z","end":"2026-04-21T01:25:26.239236Z","steps":["trace[1668129762] 'range keys from in-memory index tree' (duration: 202.876567ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:30.461831Z","caller":"traceutil/trace.go:171","msg":"trace[1937725266] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"115.26433ms","start":"2026-04-21T01:25:30.34652Z","end":"2026-04-21T01:25:30.461784Z","steps":["trace[1937725266] 'process raft request' (duration: 115.103229ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:30.4726Z","caller":"traceutil/trace.go:171","msg":"trace[4859837] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2565; }","duration":"100.027802ms","start":"2026-04-21T01:25:30.372549Z","end":"2026-04-21T01:25:30.472577Z","steps":["trace[4859837] 'read index received' (duration: 89.076675ms)","trace[4859837] 'applied index is now lower than readState.Index' (duration: 10.947937ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:25:30.472689Z","caller":"traceutil/trace.go:171","msg":"trace[1884933823] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"124.574921ms","start":"2026-04-21T01:25:30.348086Z","end":"2026-04-21T01:25:30.47266Z","steps":["trace[1884933823] 'process raft request' (duration: 124.35931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:30.472826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.254985ms","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-21T01:25:30.47287Z","caller":"traceutil/trace.go:171","msg":"trace[880446360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2477; }","duration":"100.346506ms","start":"2026-04-21T01:25:30.372511Z","end":"2026-04-21T01:25:30.472858Z","steps":["trace[880446360] 'agreement among raft nodes before linearized reading' (duration: 100.187835ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:30.818228Z","caller":"traceutil/trace.go:171","msg":"trace[1757576748] linearizableReadLoop","detail":"{readStateIndex:2569; appliedIndex:2568; }","duration":"301.124528ms","start":"2026-04-21T01:25:30.517055Z","end":"2026-04-21T01:25:30.818179Z","steps":["trace[1757576748] 'read index received' (duration: 241.903371ms)","trace[1757576748] 'applied index is now lower than readState.Index' (duration: 59.219817ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:25:30.818445Z","caller":"traceutil/trace.go:171","msg":"trace[530734059] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"306.829213ms","start":"2026-04-21T01:25:30.511596Z","end":"2026-04-21T01:25:30.818426Z","steps":["trace[530734059] 'process raft request' (duration: 247.453376ms)","trace[530734059] 'compare' (duration: 58.847072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:25:30.819015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.378332ms","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-21T01:25:30.819129Z","caller":"traceutil/trace.go:171","msg":"trace[1082024434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2479; }","duration":"292.551474ms","start":"2026-04-21T01:25:30.526549Z","end":"2026-04-21T01:25:30.8191Z","steps":["trace[1082024434] 'agreement among raft nodes before linearized reading' (duration: 292.282961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:30.81921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.771636ms","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-21T01:25:30.819406Z","caller":"traceutil/trace.go:171","msg":"trace[1841737166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2479; }","duration":"293.14091ms","start":"2026-04-21T01:25:30.526243Z","end":"2026-04-21T01:25:30.819384Z","steps":["trace[1841737166] 'agreement among raft nodes before linearized reading' (duration: 292.695365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:30.819092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:25:30.511556Z","time spent":"307.017244ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8453,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:25:30.819153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.055036ms","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-21T01:25:30.819932Z","caller":"traceutil/trace.go:171","msg":"trace[798674987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2479; }","duration":"302.915334ms","start":"2026-04-21T01:25:30.516995Z","end":"2026-04-21T01:25:30.81991Z","steps":["trace[798674987] 'agreement among raft nodes before linearized reading' (duration: 301.924154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:30.820066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:25:30.516973Z","time spent":"303.072235ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T01:25:30.819442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.372871ms","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-21T01:25:30.820437Z","caller":"traceutil/trace.go:171","msg":"trace[1306811382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2479; }","duration":"295.016658ms","start":"2026-04-21T01:25:30.525397Z","end":"2026-04-21T01:25:30.820413Z","steps":["trace[1306811382] 'agreement among raft nodes before linearized reading' (duration: 293.21862ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:31.03713Z","caller":"traceutil/trace.go:171","msg":"trace[859987732] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"210.457155ms","start":"2026-04-21T01:25:30.826645Z","end":"2026-04-21T01:25:31.037102Z","steps":["trace[859987732] 'process raft request' (duration: 171.75795ms)","trace[859987732] 'compare' (duration: 38.039949ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:25:31.03716Z","caller":"traceutil/trace.go:171","msg":"trace[256360212] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"209.549017ms","start":"2026-04-21T01:25:30.827582Z","end":"2026-04-21T01:25:31.037131Z","steps":["trace[256360212] 'process raft request' (duration: 209.264884ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:31.03718Z","caller":"traceutil/trace.go:171","msg":"trace[1027473306] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"209.427525ms","start":"2026-04-21T01:25:30.827712Z","end":"2026-04-21T01:25:31.037139Z","steps":["trace[1027473306] 'process raft request' (duration: 209.208643ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:31.037701Z","caller":"traceutil/trace.go:171","msg":"trace[2101822318] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"209.116753ms","start":"2026-04-21T01:25:30.828567Z","end":"2026-04-21T01:25:31.037684Z","steps":["trace[2101822318] 'process raft request' (duration: 208.503816ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:25:31.037873Z","caller":"traceutil/trace.go:171","msg":"trace[1876967062] linearizableReadLoop","detail":"{readStateIndex:2575; appliedIndex:2569; }","duration":"181.556734ms","start":"2026-04-21T01:25:30.856268Z","end":"2026-04-21T01:25:31.037825Z","steps":["trace[1876967062] 'read index received' (duration: 38.949369ms)","trace[1876967062] 'applied index is now lower than readState.Index' (duration: 142.606065ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:25:31.037872Z","caller":"traceutil/trace.go:171","msg":"trace[934116253] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"205.682308ms","start":"2026-04-21T01:25:30.83217Z","end":"2026-04-21T01:25:31.037853Z","steps":["trace[934116253] 'process raft request' (duration: 205.124602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:31.038159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.867957ms","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-21T01:25:31.038205Z","caller":"traceutil/trace.go:171","msg":"trace[1657214144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2485; }","duration":"181.930837ms","start":"2026-04-21T01:25:30.856264Z","end":"2026-04-21T01:25:31.038195Z","steps":["trace[1657214144] 'agreement among raft nodes before linearized reading' (duration: 181.779036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.212212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.3347ms","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-21T01:25:35.212298Z","caller":"traceutil/trace.go:171","msg":"trace[1209560749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2545; }","duration":"152.438111ms","start":"2026-04-21T01:25:35.059841Z","end":"2026-04-21T01:25:35.212279Z","steps":["trace[1209560749] 'range keys from in-memory index tree' (duration: 152.175208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.212488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.520813ms","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-21T01:25:35.212558Z","caller":"traceutil/trace.go:171","msg":"trace[104343087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2545; }","duration":"123.616084ms","start":"2026-04-21T01:25:35.088922Z","end":"2026-04-21T01:25:35.212538Z","steps":["trace[104343087] 'range keys from in-memory index tree' (duration: 123.408432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.212693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.615197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-21T01:25:35.212735Z","caller":"traceutil/trace.go:171","msg":"trace[1412819850] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2545; }","duration":"160.691379ms","start":"2026-04-21T01:25:35.05203Z","end":"2026-04-21T01:25:35.212721Z","steps":["trace[1412819850] 'range keys from in-memory index tree' (duration: 160.513837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.212981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.225895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-21T01:25:35.21301Z","caller":"traceutil/trace.go:171","msg":"trace[400037617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2545; }","duration":"160.272765ms","start":"2026-04-21T01:25:35.052729Z","end":"2026-04-21T01:25:35.213001Z","steps":["trace[400037617] 'range keys from in-memory index tree' (duration: 160.066544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.859305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872211440061923,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T01:25:35.928613Z","caller":"traceutil/trace.go:171","msg":"trace[1179604444] linearizableReadLoop","detail":"{readStateIndex:2642; appliedIndex:2640; }","duration":"569.633577ms","start":"2026-04-21T01:25:35.358947Z","end":"2026-04-21T01:25:35.92858Z","steps":["trace[1179604444] 'read index received' (duration: 554.359081ms)","trace[1179604444] 'applied index is now lower than readState.Index' (duration: 15.273486ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:25:35.928622Z","caller":"traceutil/trace.go:171","msg":"trace[1149956223] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"585.203956ms","start":"2026-04-21T01:25:35.343384Z","end":"2026-04-21T01:25:35.928588Z","steps":["trace[1149956223] 'process raft request' (duration: 569.95066ms)","trace[1149956223] 'compare' (duration: 14.439618ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:25:35.928843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:25:35.343343Z","time spent":"585.357307ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:25:35.928923Z","caller":"traceutil/trace.go:171","msg":"trace[763476771] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"316.919926ms","start":"2026-04-21T01:25:35.611986Z","end":"2026-04-21T01:25:35.928906Z","steps":["trace[763476771] 'process raft request' (duration: 316.444122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.929081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:25:35.611969Z","time spent":"316.991647ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:25:35.929174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.839436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:25:35.929205Z","caller":"traceutil/trace.go:171","msg":"trace[1163928155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2551; }","duration":"504.897186ms","start":"2026-04-21T01:25:35.424299Z","end":"2026-04-21T01:25:35.929196Z","steps":["trace[1163928155] 'agreement among raft nodes before linearized reading' (duration: 504.843095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.929227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:25:35.424287Z","time spent":"504.935016ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:25:35.929447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.500765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-21T01:25:35.929437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.037004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:25:35.929466Z","caller":"traceutil/trace.go:171","msg":"trace[1518073752] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2551; }","duration":"570.535615ms","start":"2026-04-21T01:25:35.358924Z","end":"2026-04-21T01:25:35.92946Z","steps":["trace[1518073752] 'agreement among raft nodes before linearized reading' (duration: 570.408974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.930045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:25:35.358914Z","time spent":"571.107281ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-21T01:25:35.92955Z","caller":"traceutil/trace.go:171","msg":"trace[991379149] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2551; }","duration":"395.157616ms","start":"2026-04-21T01:25:35.534356Z","end":"2026-04-21T01:25:35.929513Z","steps":["trace[991379149] 'agreement among raft nodes before linearized reading' (duration: 395.020075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:25:35.932105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:25:35.534343Z","time spent":"397.72799ms","remote":"127.0.0.1:42668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:26:07.989789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.755422ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:26:07.990016Z","caller":"traceutil/trace.go:171","msg":"trace[816527551] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2727; }","duration":"105.006534ms","start":"2026-04-21T01:26:07.884974Z","end":"2026-04-21T01:26:07.989981Z","steps":["trace[816527551] 'range keys from in-memory index tree' (duration: 104.738792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:26:13.60856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.242917ms","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-21T01:26:13.608666Z","caller":"traceutil/trace.go:171","msg":"trace[133894957] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2752; }","duration":"246.395528ms","start":"2026-04-21T01:26:13.362247Z","end":"2026-04-21T01:26:13.608642Z","steps":["trace[133894957] 'range keys from in-memory index tree' (duration: 246.093555ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:26:13.609255Z","caller":"traceutil/trace.go:171","msg":"trace[345941285] linearizableReadLoop","detail":"{readStateIndex:2853; appliedIndex:2852; }","duration":"106.92603ms","start":"2026-04-21T01:26:13.502298Z","end":"2026-04-21T01:26:13.609225Z","steps":["trace[345941285] 'read index received' (duration: 102.075188ms)","trace[345941285] 'applied index is now lower than readState.Index' (duration: 4.849552ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:26:13.60935Z","caller":"traceutil/trace.go:171","msg":"trace[356332704] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"136.858273ms","start":"2026-04-21T01:26:13.472457Z","end":"2026-04-21T01:26:13.609315Z","steps":["trace[356332704] 'process raft request' (duration: 131.966311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:26:13.609501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.184452ms","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-21T01:26:13.60955Z","caller":"traceutil/trace.go:171","msg":"trace[648014489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2753; }","duration":"107.273463ms","start":"2026-04-21T01:26:13.502263Z","end":"2026-04-21T01:26:13.609537Z","steps":["trace[648014489] 'agreement among raft nodes before linearized reading' (duration: 107.101042ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:00.067852Z","caller":"traceutil/trace.go:171","msg":"trace[1766986451] transaction","detail":"{read_only:false; response_revision:3000; number_of_response:1; }","duration":"112.165431ms","start":"2026-04-21T01:26:59.95566Z","end":"2026-04-21T01:27:00.067826Z","steps":["trace[1766986451] 'process raft request' (duration: 86.76408ms)","trace[1766986451] 'compare' (duration: 25.28632ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:27:00.103091Z","caller":"traceutil/trace.go:171","msg":"trace[1042855671] transaction","detail":"{read_only:false; response_revision:3001; number_of_response:1; }","duration":"147.204102ms","start":"2026-04-21T01:26:59.955752Z","end":"2026-04-21T01:27:00.102956Z","steps":["trace[1042855671] 'process raft request' (duration: 146.92637ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:00.103249Z","caller":"traceutil/trace.go:171","msg":"trace[84727314] transaction","detail":"{read_only:false; response_revision:3002; number_of_response:1; }","duration":"146.541886ms","start":"2026-04-21T01:26:59.956684Z","end":"2026-04-21T01:27:00.103226Z","steps":["trace[84727314] 'process raft request' (duration: 146.217914ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:00.48449Z","caller":"traceutil/trace.go:171","msg":"trace[493790145] transaction","detail":"{read_only:false; response_revision:3003; number_of_response:1; }","duration":"254.826966ms","start":"2026-04-21T01:27:00.229622Z","end":"2026-04-21T01:27:00.484449Z","steps":["trace[493790145] 'process raft request' (duration: 218.658365ms)","trace[493790145] 'compare' (duration: 35.843487ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:27:06.135792Z","caller":"traceutil/trace.go:171","msg":"trace[71348883] transaction","detail":"{read_only:false; response_revision:3030; number_of_response:1; }","duration":"111.718895ms","start":"2026-04-21T01:27:06.024048Z","end":"2026-04-21T01:27:06.135767Z","steps":["trace[71348883] 'process raft request' (duration: 111.577572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:44.020936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.920857ms","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-21T01:27:44.021084Z","caller":"traceutil/trace.go:171","msg":"trace[499702753] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3227; }","duration":"102.134311ms","start":"2026-04-21T01:27:43.918918Z","end":"2026-04-21T01:27:44.021052Z","steps":["trace[499702753] 'range keys from in-memory index tree' (duration: 101.810844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:44.897413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.664985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:27:44.897626Z","caller":"traceutil/trace.go:171","msg":"trace[1815640034] transaction","detail":"{read_only:false; response_revision:3232; number_of_response:1; }","duration":"160.613003ms","start":"2026-04-21T01:27:44.73697Z","end":"2026-04-21T01:27:44.897583Z","steps":["trace[1815640034] 'process raft request' (duration: 41.668532ms)","trace[1815640034] 'compare' (duration: 118.542012ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:27:45.447809Z","caller":"traceutil/trace.go:171","msg":"trace[1945656119] transaction","detail":"{read_only:false; response_revision:3234; number_of_response:1; }","duration":"161.110739ms","start":"2026-04-21T01:27:45.286652Z","end":"2026-04-21T01:27:45.447762Z","steps":["trace[1945656119] 'process raft request' (duration: 160.679421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.022536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.477241ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-21T01:29:03.255025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.635394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:29:03.255133Z","caller":"traceutil/trace.go:171","msg":"trace[1581346058] transaction","detail":"{read_only:false; response_revision:3735; number_of_response:1; }","duration":"130.580104ms","start":"2026-04-21T01:29:03.124537Z","end":"2026-04-21T01:29:03.255117Z","steps":["trace[1581346058] 'compare' (duration: 123.466651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:19.822875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.513692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:29:19.823017Z","caller":"traceutil/trace.go:171","msg":"trace[2118532026] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"274.577904ms","start":"2026-04-21T01:29:19.54841Z","end":"2026-04-21T01:29:19.822988Z","steps":["trace[2118532026] 'process raft request' (duration: 60.846439ms)","trace[2118532026] 'compare' (duration: 213.171506ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:19.826123Z","caller":"traceutil/trace.go:171","msg":"trace[985738892] transaction","detail":"{read_only:false; response_revision:3831; number_of_response:1; }","duration":"269.878545ms","start":"2026-04-21T01:29:19.556208Z","end":"2026-04-21T01:29:19.826086Z","steps":["trace[985738892] 'process raft request' (duration: 269.785693ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:19.826718Z","caller":"traceutil/trace.go:171","msg":"trace[1740616559] transaction","detail":"{read_only:false; response_revision:3830; number_of_response:1; }","duration":"277.178933ms","start":"2026-04-21T01:29:19.549488Z","end":"2026-04-21T01:29:19.826666Z","steps":["trace[1740616559] 'process raft request' (duration: 276.312681ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:19.850177Z","caller":"traceutil/trace.go:171","msg":"trace[1413970369] transaction","detail":"{read_only:false; response_revision:3832; number_of_response:1; }","duration":"116.643533ms","start":"2026-04-21T01:29:19.733509Z","end":"2026-04-21T01:29:19.850153Z","steps":["trace[1413970369] 'process raft request' (duration: 116.41848ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:31.976256Z","caller":"traceutil/trace.go:171","msg":"trace[1777910167] transaction","detail":"{read_only:false; response_revision:3955; number_of_response:1; }","duration":"140.094529ms","start":"2026-04-21T01:29:31.836136Z","end":"2026-04-21T01:29:31.97623Z","steps":["trace[1777910167] 'process raft request' (duration: 139.765264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:32.216642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.796588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:29:32.217012Z","caller":"traceutil/trace.go:171","msg":"trace[149383490] transaction","detail":"{read_only:false; response_revision:3956; number_of_response:1; }","duration":"291.172469ms","start":"2026-04-21T01:29:31.925799Z","end":"2026-04-21T01:29:32.216971Z","steps":["trace[149383490] 'process raft request' (duration: 132.898013ms)","trace[149383490] 'compare' (duration: 157.661337ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:32.217132Z","caller":"traceutil/trace.go:171","msg":"trace[1867964789] transaction","detail":"{read_only:false; response_revision:3957; number_of_response:1; }","duration":"278.324833ms","start":"2026-04-21T01:29:31.938788Z","end":"2026-04-21T01:29:32.217113Z","steps":["trace[1867964789] 'process raft request' (duration: 277.944778ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:40.562089Z","caller":"traceutil/trace.go:171","msg":"trace[1864430858] transaction","detail":"{read_only:false; response_revision:4009; number_of_response:1; }","duration":"102.180247ms","start":"2026-04-21T01:29:40.459865Z","end":"2026-04-21T01:29:40.562046Z","steps":["trace[1864430858] 'process raft request' (duration: 62.248681ms)","trace[1864430858] 'compare' (duration: 39.46577ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:02.515804Z","caller":"traceutil/trace.go:171","msg":"trace[1189306811] transaction","detail":"{read_only:false; response_revision:4144; number_of_response:1; }","duration":"141.443014ms","start":"2026-04-21T01:30:02.374331Z","end":"2026-04-21T01:30:02.515774Z","steps":["trace[1189306811] 'process raft request' (duration: 141.235732ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:02.569541Z","caller":"traceutil/trace.go:171","msg":"trace[1873364688] transaction","detail":"{read_only:false; response_revision:4145; number_of_response:1; }","duration":"156.127501ms","start":"2026-04-21T01:30:02.413367Z","end":"2026-04-21T01:30:02.569494Z","steps":["trace[1873364688] 'process raft request' (duration: 155.498603ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:04.028445Z","caller":"traceutil/trace.go:171","msg":"trace[1158452826] transaction","detail":"{read_only:false; response_revision:4151; number_of_response:1; }","duration":"204.139772ms","start":"2026-04-21T01:30:03.824276Z","end":"2026-04-21T01:30:04.028416Z","steps":["trace[1158452826] 'process raft request' (duration: 203.98729ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:04.031712Z","caller":"traceutil/trace.go:171","msg":"trace[622897279] linearizableReadLoop","detail":"{readStateIndex:4318; appliedIndex:4317; }","duration":"149.66038ms","start":"2026-04-21T01:30:03.88203Z","end":"2026-04-21T01:30:04.03169Z","steps":["trace[622897279] 'read index received' (duration: 146.400176ms)","trace[622897279] 'applied index is now lower than readState.Index' (duration: 3.259144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:04.031923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.881022ms","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-21T01:30:04.031968Z","caller":"traceutil/trace.go:171","msg":"trace[1558902909] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4152; }","duration":"150.000614ms","start":"2026-04-21T01:30:03.881946Z","end":"2026-04-21T01:30:04.031947Z","steps":["trace[1558902909] 'agreement among raft nodes before linearized reading' (duration: 149.819362ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:04.031991Z","caller":"traceutil/trace.go:171","msg":"trace[491699863] transaction","detail":"{read_only:false; response_revision:4152; number_of_response:1; }","duration":"206.664426ms","start":"2026-04-21T01:30:03.825294Z","end":"2026-04-21T01:30:04.031959Z","steps":["trace[491699863] 'process raft request' (duration: 206.317671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:04.991171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.789684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-px24q\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-04-21T01:30:04.991292Z","caller":"traceutil/trace.go:171","msg":"trace[250278824] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-px24q; range_end:; response_count:1; response_revision:4158; }","duration":"145.976246ms","start":"2026-04-21T01:30:04.845284Z","end":"2026-04-21T01:30:04.991261Z","steps":["trace[250278824] 'range keys from in-memory index tree' (duration: 145.431039ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:05.281701Z","caller":"traceutil/trace.go:171","msg":"trace[706165855] transaction","detail":"{read_only:false; response_revision:4159; number_of_response:1; }","duration":"281.394335ms","start":"2026-04-21T01:30:05.000276Z","end":"2026-04-21T01:30:05.28167Z","steps":["trace[706165855] 'process raft request' (duration: 281.147902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:05.527444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.527431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:05.527735Z","caller":"traceutil/trace.go:171","msg":"trace[2015333701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4159; }","duration":"102.895346ms","start":"2026-04-21T01:30:05.424816Z","end":"2026-04-21T01:30:05.527711Z","steps":["trace[2015333701] 'range keys from in-memory index tree' (duration: 102.41802ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.269207Z","caller":"traceutil/trace.go:171","msg":"trace[753140134] transaction","detail":"{read_only:false; response_revision:4162; number_of_response:1; }","duration":"231.775585ms","start":"2026-04-21T01:30:06.037398Z","end":"2026-04-21T01:30:06.269174Z","steps":["trace[753140134] 'process raft request' (duration: 231.608072ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.456895Z","caller":"traceutil/trace.go:171","msg":"trace[1432457644] linearizableReadLoop","detail":"{readStateIndex:4329; appliedIndex:4327; }","duration":"376.368244ms","start":"2026-04-21T01:30:06.08049Z","end":"2026-04-21T01:30:06.456858Z","steps":["trace[1432457644] 'read index received' (duration: 188.629638ms)","trace[1432457644] 'applied index is now lower than readState.Index' (duration: 187.737216ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:06.456978Z","caller":"traceutil/trace.go:171","msg":"trace[1378677962] transaction","detail":"{read_only:false; response_revision:4163; number_of_response:1; }","duration":"418.873552ms","start":"2026-04-21T01:30:06.038054Z","end":"2026-04-21T01:30:06.456927Z","steps":["trace[1378677962] 'process raft request' (duration: 348.613913ms)","trace[1378677962] 'compare' (duration: 70.041886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:06.457294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:06.038036Z","time spent":"419.084465ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:06.457381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.85688ms","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-21T01:30:06.457575Z","caller":"traceutil/trace.go:171","msg":"trace[1244893106] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4163; }","duration":"377.091263ms","start":"2026-04-21T01:30:06.080448Z","end":"2026-04-21T01:30:06.45754Z","steps":["trace[1244893106] 'agreement among raft nodes before linearized reading' (duration: 376.576077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.457726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:06.080436Z","time spent":"377.236045ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-21T01:30:06.490432Z","caller":"traceutil/trace.go:171","msg":"trace[79050776] transaction","detail":"{read_only:false; response_revision:4164; number_of_response:1; }","duration":"200.245683ms","start":"2026-04-21T01:30:06.290163Z","end":"2026-04-21T01:30:06.490408Z","steps":["trace[79050776] 'process raft request' (duration: 200.10885ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.686322Z","caller":"traceutil/trace.go:171","msg":"trace[1425545827] transaction","detail":"{read_only:false; response_revision:4167; number_of_response:1; }","duration":"188.144981ms","start":"2026-04-21T01:30:06.49815Z","end":"2026-04-21T01:30:06.686295Z","steps":["trace[1425545827] 'process raft request' (duration: 187.941899ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.752195Z","caller":"traceutil/trace.go:171","msg":"trace[508370403] linearizableReadLoop","detail":"{readStateIndex:4335; appliedIndex:4332; }","duration":"113.177141ms","start":"2026-04-21T01:30:06.638991Z","end":"2026-04-21T01:30:06.752168Z","steps":["trace[508370403] 'read index received' (duration: 47.192549ms)","trace[508370403] 'applied index is now lower than readState.Index' (duration: 65.982922ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:06.752228Z","caller":"traceutil/trace.go:171","msg":"trace[724571201] transaction","detail":"{read_only:false; response_revision:4168; number_of_response:1; }","duration":"214.468034ms","start":"2026-04-21T01:30:06.537719Z","end":"2026-04-21T01:30:06.752187Z","steps":["trace[724571201] 'process raft request' (duration: 214.183749ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.752295Z","caller":"traceutil/trace.go:171","msg":"trace[2013736272] transaction","detail":"{read_only:false; response_revision:4169; number_of_response:1; }","duration":"186.264436ms","start":"2026-04-21T01:30:06.565987Z","end":"2026-04-21T01:30:06.752251Z","steps":["trace[2013736272] 'process raft request' (duration: 186.081592ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.752356Z","caller":"traceutil/trace.go:171","msg":"trace[1440522481] transaction","detail":"{read_only:false; response_revision:4170; number_of_response:1; }","duration":"101.575327ms","start":"2026-04-21T01:30:06.650766Z","end":"2026-04-21T01:30:06.752341Z","steps":["trace[1440522481] 'process raft request' (duration: 101.350813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.752558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.547566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:06.752605Z","caller":"traceutil/trace.go:171","msg":"trace[516353351] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4170; }","duration":"113.664817ms","start":"2026-04-21T01:30:06.638927Z","end":"2026-04-21T01:30:06.752592Z","steps":["trace[516353351] 'agreement among raft nodes before linearized reading' (duration: 113.564526ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.976052Z","caller":"traceutil/trace.go:171","msg":"trace[1717591628] linearizableReadLoop","detail":"{readStateIndex:4337; appliedIndex:4336; }","duration":"223.744956ms","start":"2026-04-21T01:30:06.752275Z","end":"2026-04-21T01:30:06.97602Z","steps":["trace[1717591628] 'read index received' (duration: 223.417672ms)","trace[1717591628] 'applied index is now lower than readState.Index' (duration: 325.684µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:06.976161Z","caller":"traceutil/trace.go:171","msg":"trace[272717356] transaction","detail":"{read_only:false; response_revision:4171; number_of_response:1; }","duration":"281.144412ms","start":"2026-04-21T01:30:06.694983Z","end":"2026-04-21T01:30:06.976127Z","steps":["trace[272717356] 'process raft request' (duration: 280.813938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.976395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.206596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-21T01:30:06.976467Z","caller":"traceutil/trace.go:171","msg":"trace[759677928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4171; }","duration":"252.339389ms","start":"2026-04-21T01:30:06.72411Z","end":"2026-04-21T01:30:06.976449Z","steps":["trace[759677928] 'agreement among raft nodes before linearized reading' (duration: 252.100876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.976396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.06503ms","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-21T01:30:06.976584Z","caller":"traceutil/trace.go:171","msg":"trace[1698077957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4171; }","duration":"161.278823ms","start":"2026-04-21T01:30:06.815285Z","end":"2026-04-21T01:30:06.976564Z","steps":["trace[1698077957] 'agreement among raft nodes before linearized reading' (duration: 160.993959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.976608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.143375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-21T01:30:06.976714Z","caller":"traceutil/trace.go:171","msg":"trace[1815463436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4171; }","duration":"138.320506ms","start":"2026-04-21T01:30:06.838371Z","end":"2026-04-21T01:30:06.976692Z","steps":["trace[1815463436] 'agreement among raft nodes before linearized reading' (duration: 137.972672ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:08.909187Z","caller":"traceutil/trace.go:171","msg":"trace[697911433] transaction","detail":"{read_only:false; response_revision:4500; number_of_response:1; }","duration":"101.994996ms","start":"2026-04-21T01:30:08.807171Z","end":"2026-04-21T01:30:08.909166Z","steps":["trace[697911433] 'process raft request' (duration: 101.831244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:11.32383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.085208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-21T01:30:11.324025Z","caller":"traceutil/trace.go:171","msg":"trace[9789954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4547; }","duration":"307.35088ms","start":"2026-04-21T01:30:11.016651Z","end":"2026-04-21T01:30:11.324002Z","steps":["trace[9789954] 'range keys from in-memory index tree' (duration: 306.844265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:11.324178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:11.016627Z","time spent":"307.536654ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T01:30:11.323922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.959129ms","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-21T01:30:11.324426Z","caller":"traceutil/trace.go:171","msg":"trace[943211618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4547; }","duration":"302.526076ms","start":"2026-04-21T01:30:11.021874Z","end":"2026-04-21T01:30:11.3244Z","steps":["trace[943211618] 'range keys from in-memory index tree' (duration: 301.393171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:11.3245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:11.021847Z","time spent":"302.636668ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T01:30:13.098275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.070266ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T01:30:13.098594Z","caller":"traceutil/trace.go:171","msg":"trace[896841094] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"119.856377ms","start":"2026-04-21T01:30:12.978713Z","end":"2026-04-21T01:30:13.098569Z","steps":["trace[896841094] 'process raft request' (duration: 119.698805ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:13.098593Z","caller":"traceutil/trace.go:171","msg":"trace[1691403953] linearizableReadLoop","detail":"{readStateIndex:4800; appliedIndex:4799; }","duration":"203.276156ms","start":"2026-04-21T01:30:12.895266Z","end":"2026-04-21T01:30:13.098542Z","steps":["trace[1691403953] 'read index received' (duration: 55.840236ms)","trace[1691403953] 'applied index is now lower than readState.Index' (duration: 147.4331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:13.099014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.695271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d645640c-b995-4cea-888b-f4a9c996b2b4\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-04-21T01:30:13.099112Z","caller":"traceutil/trace.go:171","msg":"trace[1194820981] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d645640c-b995-4cea-888b-f4a9c996b2b4; range_end:; response_count:1; response_revision:4627; }","duration":"174.808942ms","start":"2026-04-21T01:30:12.924277Z","end":"2026-04-21T01:30:13.099086Z","steps":["trace[1194820981] 'agreement among raft nodes before linearized reading' (duration: 174.503387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:13.09986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.582365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-21T01:30:13.099918Z","caller":"traceutil/trace.go:171","msg":"trace[2116979168] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4627; }","duration":"204.694605ms","start":"2026-04-21T01:30:12.895205Z","end":"2026-04-21T01:30:13.0999Z","steps":["trace[2116979168] 'agreement among raft nodes before linearized reading' (duration: 203.51951ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:13.221132Z","caller":"traceutil/trace.go:171","msg":"trace[1441829876] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"110.18033ms","start":"2026-04-21T01:30:13.110919Z","end":"2026-04-21T01:30:13.221099Z","steps":["trace[1441829876] 'process raft request' (duration: 109.901086ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:13.284679Z","caller":"traceutil/trace.go:171","msg":"trace[1006897501] linearizableReadLoop","detail":"{readStateIndex:4803; appliedIndex:4801; }","duration":"150.818004ms","start":"2026-04-21T01:30:13.133837Z","end":"2026-04-21T01:30:13.284655Z","steps":["trace[1006897501] 'read index received' (duration: 87.005255ms)","trace[1006897501] 'applied index is now lower than readState.Index' (duration: 63.811819ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:13.28479Z","caller":"traceutil/trace.go:171","msg":"trace[144340253] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"172.913614ms","start":"2026-04-21T01:30:13.111835Z","end":"2026-04-21T01:30:13.284748Z","steps":["trace[144340253] 'process raft request' (duration: 172.697082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:13.284979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.093058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-21T01:30:13.285055Z","caller":"traceutil/trace.go:171","msg":"trace[1935322098] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4629; }","duration":"151.24239ms","start":"2026-04-21T01:30:13.133793Z","end":"2026-04-21T01:30:13.285036Z","steps":["trace[1935322098] 'agreement among raft nodes before linearized reading' (duration: 151.001917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:13.345786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.205952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:30:13.346006Z","caller":"traceutil/trace.go:171","msg":"trace[1642372343] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:4630; }","duration":"120.581776ms","start":"2026-04-21T01:30:13.225394Z","end":"2026-04-21T01:30:13.345976Z","steps":["trace[1642372343] 'agreement among raft nodes before linearized reading' (duration: 119.289719ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:15.810261Z","caller":"traceutil/trace.go:171","msg":"trace[199905957] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"411.940067ms","start":"2026-04-21T01:30:15.398012Z","end":"2026-04-21T01:30:15.809952Z","steps":["trace[199905957] 'process raft request' (duration: 409.948961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:15.810915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:15.397995Z","time spent":"412.819728ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:16.367537Z","caller":"traceutil/trace.go:171","msg":"trace[1694630752] linearizableReadLoop","detail":"{readStateIndex:4841; appliedIndex:4839; }","duration":"740.352962ms","start":"2026-04-21T01:30:15.627158Z","end":"2026-04-21T01:30:16.367511Z","steps":["trace[1694630752] 'read index received' (duration: 180.748258ms)","trace[1694630752] 'applied index is now lower than readState.Index' (duration: 559.603354ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:16.367645Z","caller":"traceutil/trace.go:171","msg":"trace[614426815] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"968.982497ms","start":"2026-04-21T01:30:15.398654Z","end":"2026-04-21T01:30:16.367637Z","steps":["trace[614426815] 'process raft request' (duration: 968.707334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:16.367754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:15.39864Z","time spent":"969.030038ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:16.367976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.783627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-21T01:30:16.368372Z","caller":"traceutil/trace.go:171","msg":"trace[862509838] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"398.30594ms","start":"2026-04-21T01:30:15.970007Z","end":"2026-04-21T01:30:16.368312Z","steps":["trace[862509838] 'process raft request' (duration: 397.863313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:16.368427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.503205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d645640c-b995-4cea-888b-f4a9c996b2b4\" ","response":"range_response_count:1 size:4080"} {"level":"info","ts":"2026-04-21T01:30:16.368461Z","caller":"traceutil/trace.go:171","msg":"trace[1186456497] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d645640c-b995-4cea-888b-f4a9c996b2b4; range_end:; response_count:1; response_revision:4667; }","duration":"256.587727ms","start":"2026-04-21T01:30:16.111865Z","end":"2026-04-21T01:30:16.368452Z","steps":["trace[1186456497] 'agreement among raft nodes before linearized reading' (duration: 256.507226ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:16.368374Z","caller":"traceutil/trace.go:171","msg":"trace[785004888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4666; }","duration":"741.215444ms","start":"2026-04-21T01:30:15.627096Z","end":"2026-04-21T01:30:16.368311Z","steps":["trace[785004888] 'agreement among raft nodes before linearized reading' (duration: 740.625356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:16.368571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:15.627075Z","time spent":"741.477707ms","remote":"127.0.0.1:42476","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-21T01:30:16.368626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.605124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:30:16.368651Z","caller":"traceutil/trace.go:171","msg":"trace[517810576] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4667; }","duration":"430.688455ms","start":"2026-04-21T01:30:15.937955Z","end":"2026-04-21T01:30:16.368644Z","steps":["trace[517810576] 'agreement among raft nodes before linearized reading' (duration: 430.642474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:16.36867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:15.937926Z","time spent":"430.738215ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:30:16.368035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.705466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:16.368841Z","caller":"traceutil/trace.go:171","msg":"trace[1486739622] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4667; }","duration":"657.576337ms","start":"2026-04-21T01:30:15.711245Z","end":"2026-04-21T01:30:16.368821Z","steps":["trace[1486739622] 'agreement among raft nodes before linearized reading' (duration: 656.727216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:16.368953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:15.711217Z","time spent":"657.71426ms","remote":"127.0.0.1:45778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:30:16.368462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:15.969969Z","time spent":"398.436752ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:16.749788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.620588ms","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-21T01:30:16.749868Z","caller":"traceutil/trace.go:171","msg":"trace[1371227366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4672; }","duration":"156.767411ms","start":"2026-04-21T01:30:16.593082Z","end":"2026-04-21T01:30:16.749849Z","steps":["trace[1371227366] 'range keys from in-memory index tree' (duration: 156.441046ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:20.04935Z","caller":"traceutil/trace.go:171","msg":"trace[634475717] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"209.771735ms","start":"2026-04-21T01:30:19.839542Z","end":"2026-04-21T01:30:20.049314Z","steps":["trace[634475717] 'process raft request' (duration: 209.40954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:20.049526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.055621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:20.049613Z","caller":"traceutil/trace.go:171","msg":"trace[414435878] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4754; }","duration":"154.232213ms","start":"2026-04-21T01:30:19.895363Z","end":"2026-04-21T01:30:20.049595Z","steps":["trace[414435878] 'agreement among raft nodes before linearized reading' (duration: 154.052611ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:20.049265Z","caller":"traceutil/trace.go:171","msg":"trace[1269938791] linearizableReadLoop","detail":"{readStateIndex:4932; appliedIndex:4931; }","duration":"153.793868ms","start":"2026-04-21T01:30:19.895433Z","end":"2026-04-21T01:30:20.049227Z","steps":["trace[1269938791] 'read index received' (duration: 153.534024ms)","trace[1269938791] 'applied index is now lower than readState.Index' (duration: 257.634µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:20.089129Z","caller":"traceutil/trace.go:171","msg":"trace[1086378767] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"151.873942ms","start":"2026-04-21T01:30:19.937235Z","end":"2026-04-21T01:30:20.089109Z","steps":["trace[1086378767] 'process raft request' (duration: 150.641355ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:21.111273Z","caller":"traceutil/trace.go:171","msg":"trace[2028617618] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"267.325906ms","start":"2026-04-21T01:30:20.843879Z","end":"2026-04-21T01:30:21.111205Z","steps":["trace[2028617618] 'process raft request' (duration: 266.939052ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:21.112737Z","caller":"traceutil/trace.go:171","msg":"trace[1043977447] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"263.730759ms","start":"2026-04-21T01:30:20.848939Z","end":"2026-04-21T01:30:21.11267Z","steps":["trace[1043977447] 'process raft request' (duration: 263.500457ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:21.113063Z","caller":"traceutil/trace.go:171","msg":"trace[1210796578] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"267.056572ms","start":"2026-04-21T01:30:20.845969Z","end":"2026-04-21T01:30:21.113026Z","steps":["trace[1210796578] 'process raft request' (duration: 266.296692ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:21.113572Z","caller":"traceutil/trace.go:171","msg":"trace[275879059] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"263.650358ms","start":"2026-04-21T01:30:20.849327Z","end":"2026-04-21T01:30:21.112977Z","steps":["trace[275879059] 'process raft request' (duration: 263.150762ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:22.139064Z","caller":"traceutil/trace.go:171","msg":"trace[1315485906] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"100.789183ms","start":"2026-04-21T01:30:22.038227Z","end":"2026-04-21T01:30:22.139016Z","steps":["trace[1315485906] 'process raft request' (duration: 100.454449ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:22.579428Z","caller":"traceutil/trace.go:171","msg":"trace[1256438131] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"152.917217ms","start":"2026-04-21T01:30:22.426478Z","end":"2026-04-21T01:30:22.579395Z","steps":["trace[1256438131] 'process raft request' (duration: 152.186437ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:22.579701Z","caller":"traceutil/trace.go:171","msg":"trace[145174482] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"151.802773ms","start":"2026-04-21T01:30:22.427883Z","end":"2026-04-21T01:30:22.579686Z","steps":["trace[145174482] 'process raft request' (duration: 151.441488ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:22.579711Z","caller":"traceutil/trace.go:171","msg":"trace[1202036068] linearizableReadLoop","detail":"{readStateIndex:4955; appliedIndex:4953; }","duration":"149.188619ms","start":"2026-04-21T01:30:22.43048Z","end":"2026-04-21T01:30:22.579668Z","steps":["trace[1202036068] 'read index received' (duration: 31.66761ms)","trace[1202036068] 'applied index is now lower than readState.Index' (duration: 117.516458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:22.579939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.433881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:22.580078Z","caller":"traceutil/trace.go:171","msg":"trace[1154425445] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:4776; }","duration":"149.566214ms","start":"2026-04-21T01:30:22.430448Z","end":"2026-04-21T01:30:22.580015Z","steps":["trace[1154425445] 'agreement among raft nodes before linearized reading' (duration: 149.413852ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:23.343007Z","caller":"traceutil/trace.go:171","msg":"trace[614111077] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"222.642751ms","start":"2026-04-21T01:30:23.12033Z","end":"2026-04-21T01:30:23.342973Z","steps":["trace[614111077] 'process raft request' (duration: 182.725107ms)","trace[614111077] 'compare' (duration: 39.704632ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:23.343361Z","caller":"traceutil/trace.go:171","msg":"trace[992988679] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"222.958996ms","start":"2026-04-21T01:30:23.120375Z","end":"2026-04-21T01:30:23.343334Z","steps":["trace[992988679] 'process raft request' (duration: 222.547521ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:23.34336Z","caller":"traceutil/trace.go:171","msg":"trace[1651634556] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"222.953876ms","start":"2026-04-21T01:30:23.120382Z","end":"2026-04-21T01:30:23.343336Z","steps":["trace[1651634556] 'process raft request' (duration: 222.823934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:25.680442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.552041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-21T01:30:25.68057Z","caller":"traceutil/trace.go:171","msg":"trace[1743171862] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3; range_end:; response_count:1; response_revision:4844; }","duration":"384.747195ms","start":"2026-04-21T01:30:25.29579Z","end":"2026-04-21T01:30:25.680537Z","steps":["trace[1743171862] 'range keys from in-memory index tree' (duration: 384.307868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:25.680631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.295765Z","time spent":"384.852636ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3\" "} {"level":"warn","ts":"2026-04-21T01:30:25.681043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.980201ms","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-21T01:30:25.6811Z","caller":"traceutil/trace.go:171","msg":"trace[177045135] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4844; }","duration":"330.079312ms","start":"2026-04-21T01:30:25.351002Z","end":"2026-04-21T01:30:25.681082Z","steps":["trace[177045135] 'range keys from in-memory index tree' (duration: 329.750229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:25.681084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.353826ms","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-21T01:30:25.681146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.35098Z","time spent":"330.151733ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-21T01:30:25.681242Z","caller":"traceutil/trace.go:171","msg":"trace[1224411425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4844; }","duration":"330.585928ms","start":"2026-04-21T01:30:25.350632Z","end":"2026-04-21T01:30:25.681218Z","steps":["trace[1224411425] 'range keys from in-memory index tree' (duration: 330.144213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:25.681301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.35061Z","time spent":"330.68033ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T01:30:25.740955Z","caller":"traceutil/trace.go:171","msg":"trace[63009725] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"389.12283ms","start":"2026-04-21T01:30:25.351793Z","end":"2026-04-21T01:30:25.740915Z","steps":["trace[63009725] 'process raft request' (duration: 388.909128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:25.741197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.351753Z","time spent":"389.302773ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:25.741298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.191336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:25.741387Z","caller":"traceutil/trace.go:171","msg":"trace[1829298223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4845; }","duration":"317.374309ms","start":"2026-04-21T01:30:25.423994Z","end":"2026-04-21T01:30:25.741368Z","steps":["trace[1829298223] 'agreement among raft nodes before linearized reading' (duration: 317.033025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:25.741453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.423968Z","time spent":"317.45533ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T01:30:25.740956Z","caller":"traceutil/trace.go:171","msg":"trace[1224632793] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5025; }","duration":"316.841062ms","start":"2026-04-21T01:30:25.424067Z","end":"2026-04-21T01:30:25.740909Z","steps":["trace[1224632793] 'read index received' (duration: 316.821152ms)","trace[1224632793] 'applied index is now lower than readState.Index' (duration: 16.23µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:26.243155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872211440067687,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T01:30:26.30966Z","caller":"traceutil/trace.go:171","msg":"trace[2015478140] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5025; }","duration":"566.646114ms","start":"2026-04-21T01:30:25.742986Z","end":"2026-04-21T01:30:26.309632Z","steps":["trace[2015478140] 'read index received' (duration: 544.387639ms)","trace[2015478140] 'applied index is now lower than readState.Index' (duration: 22.257045ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:26.309868Z","caller":"traceutil/trace.go:171","msg":"trace[352898808] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"622.946658ms","start":"2026-04-21T01:30:25.686899Z","end":"2026-04-21T01:30:26.309846Z","steps":["trace[352898808] 'process raft request' (duration: 600.552351ms)","trace[352898808] 'compare' (duration: 21.938821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:26.30996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.950869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T01:30:26.310024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.686869Z","time spent":"623.038178ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:26.310045Z","caller":"traceutil/trace.go:171","msg":"trace[1489013194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4847; }","duration":"567.08636ms","start":"2026-04-21T01:30:25.742938Z","end":"2026-04-21T01:30:26.310025Z","steps":["trace[1489013194] 'agreement among raft nodes before linearized reading' (duration: 566.948328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:26.31009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.742919Z","time spent":"567.163781ms","remote":"127.0.0.1:42202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T01:30:26.31033Z","caller":"traceutil/trace.go:171","msg":"trace[598698488] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"622.513071ms","start":"2026-04-21T01:30:25.6878Z","end":"2026-04-21T01:30:26.310313Z","steps":["trace[598698488] 'process raft request' (duration: 621.757752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:26.310487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:25.687788Z","time spent":"622.593692ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:27.432548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.399927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:30:27.433108Z","caller":"traceutil/trace.go:171","msg":"trace[1423157396] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"252.956979ms","start":"2026-04-21T01:30:27.180058Z","end":"2026-04-21T01:30:27.433015Z","steps":["trace[1423157396] 'process raft request' (duration: 40.949564ms)","trace[1423157396] 'compare' (duration: 211.124974ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:27.893968Z","caller":"traceutil/trace.go:171","msg":"trace[364976858] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5035; }","duration":"236.417726ms","start":"2026-04-21T01:30:27.657515Z","end":"2026-04-21T01:30:27.893932Z","steps":["trace[364976858] 'read index received' (duration: 10.650015ms)","trace[364976858] 'applied index is now lower than readState.Index' (duration: 225.765881ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:27.894113Z","caller":"traceutil/trace.go:171","msg":"trace[173447544] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"238.351162ms","start":"2026-04-21T01:30:27.655726Z","end":"2026-04-21T01:30:27.894078Z","steps":["trace[173447544] 'process raft request' (duration: 234.647834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:27.895031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.482089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-21T01:30:27.895093Z","caller":"traceutil/trace.go:171","msg":"trace[1382971030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4856; }","duration":"237.611441ms","start":"2026-04-21T01:30:27.657467Z","end":"2026-04-21T01:30:27.895078Z","steps":["trace[1382971030] 'agreement among raft nodes before linearized reading' (duration: 236.602149ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:27.91079Z","caller":"traceutil/trace.go:171","msg":"trace[1228710113] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"162.736773ms","start":"2026-04-21T01:30:27.748022Z","end":"2026-04-21T01:30:27.910759Z","steps":["trace[1228710113] 'process raft request' (duration: 162.51618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:27.910936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.561659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-21T01:30:27.911011Z","caller":"traceutil/trace.go:171","msg":"trace[1255658839] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3; range_end:; response_count:1; response_revision:4857; }","duration":"193.693041ms","start":"2026-04-21T01:30:27.717301Z","end":"2026-04-21T01:30:27.910994Z","steps":["trace[1255658839] 'agreement among raft nodes before linearized reading' (duration: 193.502667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:27.910947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.650901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-21T01:30:27.911112Z","caller":"traceutil/trace.go:171","msg":"trace[1485597249] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4857; }","duration":"241.015316ms","start":"2026-04-21T01:30:27.670083Z","end":"2026-04-21T01:30:27.911098Z","steps":["trace[1485597249] 'agreement among raft nodes before linearized reading' (duration: 240.685731ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:28.101668Z","caller":"traceutil/trace.go:171","msg":"trace[360408804] linearizableReadLoop","detail":"{readStateIndex:5039; appliedIndex:5038; }","duration":"118.404334ms","start":"2026-04-21T01:30:27.983225Z","end":"2026-04-21T01:30:28.101629Z","steps":["trace[360408804] 'read index received' (duration: 43.707479ms)","trace[360408804] 'applied index is now lower than readState.Index' (duration: 74.695225ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:28.101692Z","caller":"traceutil/trace.go:171","msg":"trace[810831415] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"167.525633ms","start":"2026-04-21T01:30:27.934112Z","end":"2026-04-21T01:30:28.101638Z","steps":["trace[810831415] 'process raft request' (duration: 92.737497ms)","trace[810831415] 'compare' (duration: 74.600094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:28.102145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.88177ms","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-21T01:30:28.102238Z","caller":"traceutil/trace.go:171","msg":"trace[980755119] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4858; }","duration":"119.045132ms","start":"2026-04-21T01:30:27.983171Z","end":"2026-04-21T01:30:28.102216Z","steps":["trace[980755119] 'agreement among raft nodes before linearized reading' (duration: 118.828759ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:30.296636Z","caller":"traceutil/trace.go:171","msg":"trace[301871198] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5061; }","duration":"119.808945ms","start":"2026-04-21T01:30:30.176803Z","end":"2026-04-21T01:30:30.296612Z","steps":["trace[301871198] 'read index received' (duration: 119.537472ms)","trace[301871198] 'applied index is now lower than readState.Index' (duration: 270.123µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:30.296763Z","caller":"traceutil/trace.go:171","msg":"trace[65457384] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"120.624857ms","start":"2026-04-21T01:30:30.176084Z","end":"2026-04-21T01:30:30.296709Z","steps":["trace[65457384] 'process raft request' (duration: 120.298922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:30.29697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.12781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d645640c-b995-4cea-888b-f4a9c996b2b4\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-21T01:30:30.297227Z","caller":"traceutil/trace.go:171","msg":"trace[155739780] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d645640c-b995-4cea-888b-f4a9c996b2b4; range_end:; response_count:1; response_revision:4881; }","duration":"120.435583ms","start":"2026-04-21T01:30:30.176775Z","end":"2026-04-21T01:30:30.297211Z","steps":["trace[155739780] 'agreement among raft nodes before linearized reading' (duration: 119.996858ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:30.319847Z","caller":"traceutil/trace.go:171","msg":"trace[813799983] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"135.375574ms","start":"2026-04-21T01:30:30.184458Z","end":"2026-04-21T01:30:30.319834Z","steps":["trace[813799983] 'process raft request' (duration: 135.294163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:32.440971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872211440067817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T01:30:32.516987Z","caller":"traceutil/trace.go:171","msg":"trace[1341196533] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5089; }","duration":"576.532965ms","start":"2026-04-21T01:30:31.94043Z","end":"2026-04-21T01:30:32.516963Z","steps":["trace[1341196533] 'read index received' (duration: 576.421903ms)","trace[1341196533] 'applied index is now lower than readState.Index' (duration: 109.642µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:32.517103Z","caller":"traceutil/trace.go:171","msg":"trace[343868123] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"579.076597ms","start":"2026-04-21T01:30:31.938016Z","end":"2026-04-21T01:30:32.517092Z","steps":["trace[343868123] 'process raft request' (duration: 578.788324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:32.517226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:31.937994Z","time spent":"579.132328ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:32.51768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.230294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3\" ","response":"range_response_count:1 size:3954"} {"level":"info","ts":"2026-04-21T01:30:32.51781Z","caller":"traceutil/trace.go:171","msg":"trace[1287302379] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3; range_end:; response_count:1; response_revision:4909; }","duration":"577.426928ms","start":"2026-04-21T01:30:31.940362Z","end":"2026-04-21T01:30:32.517789Z","steps":["trace[1287302379] 'agreement among raft nodes before linearized reading' (duration: 576.972812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:32.51786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:31.940341Z","time spent":"577.506728ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3977,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03876ac1-0875-439a-aaca-81cfe448c6a3\" "} {"level":"warn","ts":"2026-04-21T01:30:32.574294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.86993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-21T01:30:32.574365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.047706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:30:32.574385Z","caller":"traceutil/trace.go:171","msg":"trace[1565670960] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4910; }","duration":"120.017402ms","start":"2026-04-21T01:30:32.454347Z","end":"2026-04-21T01:30:32.574364Z","steps":["trace[1565670960] 'agreement among raft nodes before linearized reading' (duration: 119.741599ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.574578Z","caller":"traceutil/trace.go:171","msg":"trace[1572882908] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:4910; }","duration":"378.162798ms","start":"2026-04-21T01:30:32.196238Z","end":"2026-04-21T01:30:32.574401Z","steps":["trace[1572882908] 'agreement among raft nodes before linearized reading' (duration: 378.081777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:32.574661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:32.196206Z","time spent":"378.436401ms","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:30:32.574589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.26259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:32.574786Z","caller":"traceutil/trace.go:171","msg":"trace[1230398052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4910; }","duration":"148.531194ms","start":"2026-04-21T01:30:32.426231Z","end":"2026-04-21T01:30:32.574762Z","steps":["trace[1230398052] 'agreement among raft nodes before linearized reading' (duration: 148.244269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:32.574306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.532582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T01:30:32.574873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.670777ms","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-21T01:30:32.575127Z","caller":"traceutil/trace.go:171","msg":"trace[1759367315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4910; }","duration":"149.005191ms","start":"2026-04-21T01:30:32.426105Z","end":"2026-04-21T01:30:32.57511Z","steps":["trace[1759367315] 'agreement among raft nodes before linearized reading' (duration: 148.477974ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.575082Z","caller":"traceutil/trace.go:171","msg":"trace[585873943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4910; }","duration":"126.296513ms","start":"2026-04-21T01:30:32.448707Z","end":"2026-04-21T01:30:32.575004Z","steps":["trace[585873943] 'agreement among raft nodes before linearized reading' (duration: 125.393511ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.680872Z","caller":"traceutil/trace.go:171","msg":"trace[458158145] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"101.160603ms","start":"2026-04-21T01:30:32.579689Z","end":"2026-04-21T01:30:32.68085Z","steps":["trace[458158145] 'process raft request' (duration: 100.975101ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.681213Z","caller":"traceutil/trace.go:171","msg":"trace[969075590] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"101.284756ms","start":"2026-04-21T01:30:32.579898Z","end":"2026-04-21T01:30:32.681183Z","steps":["trace[969075590] 'process raft request' (duration: 100.91053ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.681248Z","caller":"traceutil/trace.go:171","msg":"trace[292576545] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"100.721038ms","start":"2026-04-21T01:30:32.580499Z","end":"2026-04-21T01:30:32.68122Z","steps":["trace[292576545] 'process raft request' (duration: 100.580106ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.681439Z","caller":"traceutil/trace.go:171","msg":"trace[2032454025] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"100.207512ms","start":"2026-04-21T01:30:32.581194Z","end":"2026-04-21T01:30:32.681401Z","steps":["trace[2032454025] 'process raft request' (duration: 99.98597ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:34.896868Z","caller":"traceutil/trace.go:171","msg":"trace[1550309969] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"110.020661ms","start":"2026-04-21T01:30:34.786818Z","end":"2026-04-21T01:30:34.896839Z","steps":["trace[1550309969] 'process raft request' (duration: 109.732417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:36.494735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.358183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:30:36.494856Z","caller":"traceutil/trace.go:171","msg":"trace[721561209] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5165; }","duration":"272.41193ms","start":"2026-04-21T01:30:36.222424Z","end":"2026-04-21T01:30:36.494836Z","steps":["trace[721561209] 'read index received' (duration: 156.780454ms)","trace[721561209] 'applied index is now lower than readState.Index' (duration: 115.630196ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:36.495085Z","caller":"traceutil/trace.go:171","msg":"trace[502002381] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"275.108506ms","start":"2026-04-21T01:30:36.219962Z","end":"2026-04-21T01:30:36.495071Z","steps":["trace[502002381] 'process raft request' (duration: 159.317687ms)","trace[502002381] 'compare' (duration: 115.225751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:36.495532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.22717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61660"} {"level":"info","ts":"2026-04-21T01:30:36.495649Z","caller":"traceutil/trace.go:171","msg":"trace[2064304450] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4980; }","duration":"272.402542ms","start":"2026-04-21T01:30:36.223223Z","end":"2026-04-21T01:30:36.495626Z","steps":["trace[2064304450] 'agreement among raft nodes before linearized reading' (duration: 271.937666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:36.496049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.663536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-21T01:30:36.496133Z","caller":"traceutil/trace.go:171","msg":"trace[1874976626] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4980; }","duration":"188.804868ms","start":"2026-04-21T01:30:36.307311Z","end":"2026-04-21T01:30:36.496116Z","steps":["trace[1874976626] 'agreement among raft nodes before linearized reading' (duration: 187.990999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:36.496205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.16394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-21T01:30:36.49688Z","caller":"traceutil/trace.go:171","msg":"trace[312338042] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4980; }","duration":"274.438947ms","start":"2026-04-21T01:30:36.222411Z","end":"2026-04-21T01:30:36.49685Z","steps":["trace[312338042] 'agreement among raft nodes before linearized reading' (duration: 272.986819ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:36.694621Z","caller":"traceutil/trace.go:171","msg":"trace[49755627] linearizableReadLoop","detail":"{readStateIndex:5168; appliedIndex:5167; }","duration":"126.836617ms","start":"2026-04-21T01:30:36.567753Z","end":"2026-04-21T01:30:36.69459Z","steps":["trace[49755627] 'read index received' (duration: 71.029195ms)","trace[49755627] 'applied index is now lower than readState.Index' (duration: 55.806052ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:36.69465Z","caller":"traceutil/trace.go:171","msg":"trace[42213297] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"131.462336ms","start":"2026-04-21T01:30:36.563128Z","end":"2026-04-21T01:30:36.69459Z","steps":["trace[42213297] 'process raft request' (duration: 75.671133ms)","trace[42213297] 'compare' (duration: 55.54464ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:36.694856Z","caller":"traceutil/trace.go:171","msg":"trace[805342977] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"104.486105ms","start":"2026-04-21T01:30:36.590336Z","end":"2026-04-21T01:30:36.694822Z","steps":["trace[805342977] 'process raft request' (duration: 104.204932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:36.694981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.202313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-21T01:30:36.695036Z","caller":"traceutil/trace.go:171","msg":"trace[2007114558] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4983; }","duration":"127.321704ms","start":"2026-04-21T01:30:36.567696Z","end":"2026-04-21T01:30:36.695018Z","steps":["trace[2007114558] 'agreement among raft nodes before linearized reading' (duration: 127.05531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:36.695703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.496186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61660"} {"level":"info","ts":"2026-04-21T01:30:36.696226Z","caller":"traceutil/trace.go:171","msg":"trace[944998988] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4983; }","duration":"128.034273ms","start":"2026-04-21T01:30:36.568156Z","end":"2026-04-21T01:30:36.69619Z","steps":["trace[944998988] 'agreement among raft nodes before linearized reading' (duration: 126.714106ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:38.729567Z","caller":"traceutil/trace.go:171","msg":"trace[558917205] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"161.75475ms","start":"2026-04-21T01:30:38.567783Z","end":"2026-04-21T01:30:38.729537Z","steps":["trace[558917205] 'process raft request' (duration: 161.536037ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:40.581379Z","caller":"traceutil/trace.go:171","msg":"trace[51541431] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"142.24997ms","start":"2026-04-21T01:30:40.439095Z","end":"2026-04-21T01:30:40.581345Z","steps":["trace[51541431] 'process raft request' (duration: 46.860737ms)","trace[51541431] 'compare' (duration: 95.207761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:43.336753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872211440068115,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T01:30:43.837525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872211440068115,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T01:30:44.301961Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.480559802s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T01:30:44.302683Z","caller":"traceutil/trace.go:171","msg":"trace[2053207245] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"1.481440113s","start":"2026-04-21T01:30:42.82121Z","end":"2026-04-21T01:30:44.30265Z","steps":["trace[2053207245] 'process raft request' (duration: 1.481230151s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:44.302882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.821166Z","time spent":"1.481608945s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:45.311905Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.009588386s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T01:30:45.312442Z","caller":"traceutil/trace.go:171","msg":"trace[148229437] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"2.482122058s","start":"2026-04-21T01:30:42.830273Z","end":"2026-04-21T01:30:45.312395Z","steps":["trace[148229437] 'process raft request' (duration: 2.481872805s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.31281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.830235Z","time spent":"2.482328271s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:45.374657Z","caller":"traceutil/trace.go:171","msg":"trace[1190197523] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5226; }","duration":"2.538614849s","start":"2026-04-21T01:30:42.836017Z","end":"2026-04-21T01:30:45.374632Z","steps":["trace[1190197523] 'read index received' (duration: 1.466439997s)","trace[1190197523] 'applied index is now lower than readState.Index' (duration: 1.072173982s)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:45.374728Z","caller":"traceutil/trace.go:171","msg":"trace[171009370] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"1.174370812s","start":"2026-04-21T01:30:44.200336Z","end":"2026-04-21T01:30:45.374707Z","steps":["trace[171009370] 'process raft request' (duration: 1.17422613s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.374962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:44.200312Z","time spent":"1.174520514s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:45.375008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.538964363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-21T01:30:45.375093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.536782435s","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-21T01:30:45.375131Z","caller":"traceutil/trace.go:171","msg":"trace[1810961434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5043; }","duration":"2.539145115s","start":"2026-04-21T01:30:42.835959Z","end":"2026-04-21T01:30:45.375104Z","steps":["trace[1810961434] 'agreement among raft nodes before linearized reading' (duration: 2.53875397s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.37515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.851082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:45.37516Z","caller":"traceutil/trace.go:171","msg":"trace[1271733672] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5043; }","duration":"2.536915318s","start":"2026-04-21T01:30:42.838232Z","end":"2026-04-21T01:30:45.375147Z","steps":["trace[1271733672] 'agreement among raft nodes before linearized reading' (duration: 2.536642814s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.835947Z","time spent":"2.539239486s","remote":"127.0.0.1:42476","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-21T01:30:45.375226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.838207Z","time spent":"2.537009818s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-21T01:30:45.375268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.101976081s","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":"warn","ts":"2026-04-21T01:30:45.375299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.386705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:30:45.375315Z","caller":"traceutil/trace.go:171","msg":"trace[692883155] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5043; }","duration":"2.102041071s","start":"2026-04-21T01:30:43.273257Z","end":"2026-04-21T01:30:45.375298Z","steps":["trace[692883155] 'agreement among raft nodes before linearized reading' (duration: 2.10193753s)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.375348Z","caller":"traceutil/trace.go:171","msg":"trace[1872869621] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5043; }","duration":"994.496277ms","start":"2026-04-21T01:30:44.38084Z","end":"2026-04-21T01:30:45.375336Z","steps":["trace[1872869621] 'agreement among raft nodes before linearized reading' (duration: 994.405076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.06414176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-21T01:30:45.375375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.273241Z","time spent":"2.102117722s","remote":"127.0.0.1:42364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-21T01:30:45.375397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:44.380809Z","time spent":"994.574487ms","remote":"127.0.0.1:42694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":17,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-21T01:30:45.375414Z","caller":"traceutil/trace.go:171","msg":"trace[803635767] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5043; }","duration":"2.064501214s","start":"2026-04-21T01:30:43.310895Z","end":"2026-04-21T01:30:45.375396Z","steps":["trace[803635767] 'agreement among raft nodes before linearized reading' (duration: 2.063982918s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.310803Z","time spent":"2.064658436s","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-21T01:30:45.375518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.53549299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-21T01:30:45.375582Z","caller":"traceutil/trace.go:171","msg":"trace[132151297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5043; }","duration":"2.53557581s","start":"2026-04-21T01:30:42.83999Z","end":"2026-04-21T01:30:45.375566Z","steps":["trace[132151297] 'agreement among raft nodes before linearized reading' (duration: 2.535416039s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.129595895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-04-21T01:30:45.375633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.839981Z","time spent":"2.535636481s","remote":"127.0.0.1:42476","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-21T01:30:45.37565Z","caller":"traceutil/trace.go:171","msg":"trace[1190486996] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5043; }","duration":"2.129661455s","start":"2026-04-21T01:30:43.245978Z","end":"2026-04-21T01:30:45.375639Z","steps":["trace[1190486996] 'agreement among raft nodes before linearized reading' (duration: 2.129565384s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.245951Z","time spent":"2.129720976s","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-21T01:30:45.375173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.518700471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T01:30:45.375743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.53716938s","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-21T01:30:45.375762Z","caller":"traceutil/trace.go:171","msg":"trace[1299758618] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5043; }","duration":"2.519305537s","start":"2026-04-21T01:30:42.856436Z","end":"2026-04-21T01:30:45.375742Z","steps":["trace[1299758618] 'agreement among raft nodes before linearized reading' (duration: 2.51870291s)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.375799Z","caller":"traceutil/trace.go:171","msg":"trace[1433737203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5043; }","duration":"2.537273311s","start":"2026-04-21T01:30:42.838516Z","end":"2026-04-21T01:30:45.37579Z","steps":["trace[1433737203] 'agreement among raft nodes before linearized reading' (duration: 2.537139989s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450075948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-21T01:30:45.375829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.856424Z","time spent":"2.519381748s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-04-21T01:30:45.375849Z","caller":"traceutil/trace.go:171","msg":"trace[1966396698] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5043; }","duration":"1.450176659s","start":"2026-04-21T01:30:43.925659Z","end":"2026-04-21T01:30:45.375835Z","steps":["trace[1966396698] 'agreement among raft nodes before linearized reading' (duration: 1.450064677s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.92563Z","time spent":"1.45025428s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-21T01:30:45.375918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.409268865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-21T01:30:45.375958Z","caller":"traceutil/trace.go:171","msg":"trace[1902418021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5043; }","duration":"2.409364746s","start":"2026-04-21T01:30:42.966583Z","end":"2026-04-21T01:30:45.375948Z","steps":["trace[1902418021] 'agreement among raft nodes before linearized reading' (duration: 2.409247254s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.375999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.966548Z","time spent":"2.409443378s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-21T01:30:45.375217Z","caller":"traceutil/trace.go:171","msg":"trace[1103421761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5043; }","duration":"184.956104ms","start":"2026-04-21T01:30:45.190241Z","end":"2026-04-21T01:30:45.375197Z","steps":["trace[1103421761] 'agreement among raft nodes before linearized reading' (duration: 184.874263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.37604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951488789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:45.376077Z","caller":"traceutil/trace.go:171","msg":"trace[478453587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5043; }","duration":"1.951559359s","start":"2026-04-21T01:30:43.424506Z","end":"2026-04-21T01:30:45.376065Z","steps":["trace[478453587] 'agreement among raft nodes before linearized reading' (duration: 1.951502609s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.376139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.424489Z","time spent":"1.95164106s","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:30:45.375845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.838495Z","time spent":"2.537326311s","remote":"127.0.0.1:42476","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-21T01:30:45.375697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379436459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"warn","ts":"2026-04-21T01:30:45.375899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.535841843s","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-21T01:30:45.376331Z","caller":"traceutil/trace.go:171","msg":"trace[2101972246] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5043; }","duration":"1.380113888s","start":"2026-04-21T01:30:43.996197Z","end":"2026-04-21T01:30:45.376311Z","steps":["trace[2101972246] 'agreement among raft nodes before linearized reading' (duration: 1.379404819s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.376393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.996108Z","time spent":"1.38026971s","remote":"127.0.0.1:35194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-21T01:30:45.376338Z","caller":"traceutil/trace.go:171","msg":"trace[1675918850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5043; }","duration":"2.53629096s","start":"2026-04-21T01:30:42.84003Z","end":"2026-04-21T01:30:45.376321Z","steps":["trace[1675918850] 'agreement among raft nodes before linearized reading' (duration: 2.535780513s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.376578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:42.840025Z","time spent":"2.536537583s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T01:30:45.374702Z","caller":"traceutil/trace.go:171","msg":"trace[1706795521] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"1.789757437s","start":"2026-04-21T01:30:43.584904Z","end":"2026-04-21T01:30:45.374661Z","steps":["trace[1706795521] 'process raft request' (duration: 1.78928268s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.379346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.584874Z","time spent":"1.794316263s","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:45.491478Z","caller":"traceutil/trace.go:171","msg":"trace[594036309] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"106.154087ms","start":"2026-04-21T01:30:45.385293Z","end":"2026-04-21T01:30:45.491447Z","steps":["trace[594036309] 'process raft request' (duration: 73.132427ms)","trace[594036309] 'compare' (duration: 32.885169ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:45.553261Z","caller":"traceutil/trace.go:171","msg":"trace[1214481491] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"167.749121ms","start":"2026-04-21T01:30:45.385463Z","end":"2026-04-21T01:30:45.553212Z","steps":["trace[1214481491] 'process raft request' (duration: 167.337806ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.55332Z","caller":"traceutil/trace.go:171","msg":"trace[1762490087] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"167.781141ms","start":"2026-04-21T01:30:45.385496Z","end":"2026-04-21T01:30:45.553277Z","steps":["trace[1762490087] 'process raft request' (duration: 167.605389ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.55335Z","caller":"traceutil/trace.go:171","msg":"trace[771919497] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"167.778701ms","start":"2026-04-21T01:30:45.385523Z","end":"2026-04-21T01:30:45.553301Z","steps":["trace[771919497] 'process raft request' (duration: 167.642339ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.55336Z","caller":"traceutil/trace.go:171","msg":"trace[1996159597] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"167.765011ms","start":"2026-04-21T01:30:45.38556Z","end":"2026-04-21T01:30:45.553325Z","steps":["trace[1996159597] 'process raft request' (duration: 167.64855ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.553557Z","caller":"traceutil/trace.go:171","msg":"trace[943032067] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"167.967104ms","start":"2026-04-21T01:30:45.385574Z","end":"2026-04-21T01:30:45.553541Z","steps":["trace[943032067] 'process raft request' (duration: 167.68187ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.553647Z","caller":"traceutil/trace.go:171","msg":"trace[665047556] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"168.046674ms","start":"2026-04-21T01:30:45.385593Z","end":"2026-04-21T01:30:45.55364Z","steps":["trace[665047556] 'process raft request' (duration: 167.840011ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.553657Z","caller":"traceutil/trace.go:171","msg":"trace[245049936] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"162.082039ms","start":"2026-04-21T01:30:45.391538Z","end":"2026-04-21T01:30:45.55362Z","steps":["trace[245049936] 'process raft request' (duration: 162.000479ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.553643Z","caller":"traceutil/trace.go:171","msg":"trace[313038987] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5231; }","duration":"166.95653ms","start":"2026-04-21T01:30:45.386653Z","end":"2026-04-21T01:30:45.553609Z","steps":["trace[313038987] 'read index received' (duration: 71.893441ms)","trace[313038987] 'applied index is now lower than readState.Index' (duration: 95.061999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:45.553956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.345906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10696"} {"level":"info","ts":"2026-04-21T01:30:45.554006Z","caller":"traceutil/trace.go:171","msg":"trace[1926014368] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5051; }","duration":"167.450557ms","start":"2026-04-21T01:30:45.386543Z","end":"2026-04-21T01:30:45.553994Z","steps":["trace[1926014368] 'agreement among raft nodes before linearized reading' (duration: 167.197503ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.725483Z","caller":"traceutil/trace.go:171","msg":"trace[1804046181] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"159.266916ms","start":"2026-04-21T01:30:45.566187Z","end":"2026-04-21T01:30:45.725454Z","steps":["trace[1804046181] 'process raft request' (duration: 159.181885ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.725615Z","caller":"traceutil/trace.go:171","msg":"trace[1760371764] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"159.423927ms","start":"2026-04-21T01:30:45.566167Z","end":"2026-04-21T01:30:45.725591Z","steps":["trace[1760371764] 'process raft request' (duration: 158.974042ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.79124Z","caller":"traceutil/trace.go:171","msg":"trace[880885481] linearizableReadLoop","detail":"{readStateIndex:5243; appliedIndex:5239; }","duration":"163.928133ms","start":"2026-04-21T01:30:45.627294Z","end":"2026-04-21T01:30:45.791222Z","steps":["trace[880885481] 'read index received' (duration: 97.965745ms)","trace[880885481] 'applied index is now lower than readState.Index' (duration: 65.961368ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:45.791492Z","caller":"traceutil/trace.go:171","msg":"trace[914021386] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"225.174483ms","start":"2026-04-21T01:30:45.566273Z","end":"2026-04-21T01:30:45.791447Z","steps":["trace[914021386] 'process raft request' (duration: 224.88842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:45.791633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.322337ms","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-21T01:30:45.791676Z","caller":"traceutil/trace.go:171","msg":"trace[1612643736] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5055; }","duration":"164.427639ms","start":"2026-04-21T01:30:45.627236Z","end":"2026-04-21T01:30:45.791663Z","steps":["trace[1612643736] 'agreement among raft nodes before linearized reading' (duration: 164.342588ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:45.79181Z","caller":"traceutil/trace.go:171","msg":"trace[1927778540] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"225.554827ms","start":"2026-04-21T01:30:45.566206Z","end":"2026-04-21T01:30:45.791761Z","steps":["trace[1927778540] 'process raft request' (duration: 224.864359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:46.146089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.499703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:46.146162Z","caller":"traceutil/trace.go:171","msg":"trace[1188967787] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:5055; }","duration":"150.643865ms","start":"2026-04-21T01:30:45.995501Z","end":"2026-04-21T01:30:46.146145Z","steps":["trace[1188967787] 'count revisions from in-memory index tree' (duration: 150.387412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:46.146223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.578839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:114746"} {"level":"info","ts":"2026-04-21T01:30:46.146328Z","caller":"traceutil/trace.go:171","msg":"trace[642200135] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5055; }","duration":"335.73961ms","start":"2026-04-21T01:30:45.81056Z","end":"2026-04-21T01:30:46.146299Z","steps":["trace[642200135] 'range keys from in-memory index tree' (duration: 335.284885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:46.146391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:45.810538Z","time spent":"335.840332ms","remote":"127.0.0.1:42268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":114769,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-21T01:30:46.146605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.139473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-21T01:30:46.147248Z","caller":"traceutil/trace.go:171","msg":"trace[1412466357] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5055; }","duration":"335.79882ms","start":"2026-04-21T01:30:45.811417Z","end":"2026-04-21T01:30:46.147215Z","steps":["trace[1412466357] 'range keys from in-memory index tree' (duration: 334.832979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:46.147651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:45.811412Z","time spent":"336.222176ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-21T01:31:17.697545Z","caller":"traceutil/trace.go:171","msg":"trace[1521223604] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"102.532831ms","start":"2026-04-21T01:31:17.594979Z","end":"2026-04-21T01:31:17.697512Z","steps":["trace[1521223604] 'process raft request' (duration: 102.47457ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:17.697604Z","caller":"traceutil/trace.go:171","msg":"trace[895427523] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"110.610297ms","start":"2026-04-21T01:31:17.58695Z","end":"2026-04-21T01:31:17.69756Z","steps":["trace[895427523] 'process raft request' (duration: 110.232682ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:20.050878Z","caller":"traceutil/trace.go:171","msg":"trace[1539762547] linearizableReadLoop","detail":"{readStateIndex:5563; appliedIndex:5562; }","duration":"305.060565ms","start":"2026-04-21T01:31:19.745781Z","end":"2026-04-21T01:31:20.050842Z","steps":["trace[1539762547] 'read index received' (duration: 304.911693ms)","trace[1539762547] 'applied index is now lower than readState.Index' (duration: 147.272µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:31:20.050982Z","caller":"traceutil/trace.go:171","msg":"trace[1267768753] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"310.345587ms","start":"2026-04-21T01:31:19.740594Z","end":"2026-04-21T01:31:20.050939Z","steps":["trace[1267768753] 'process raft request' (duration: 310.025033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:31:20.051205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.77363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-21T01:31:20.051246Z","caller":"traceutil/trace.go:171","msg":"trace[146695094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5367; }","duration":"164.896082ms","start":"2026-04-21T01:31:19.886337Z","end":"2026-04-21T01:31:20.051233Z","steps":["trace[146695094] 'agreement among raft nodes before linearized reading' (duration: 164.68935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:31:20.051254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:31:19.740559Z","time spent":"310.590069ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:31:20.051299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.727682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T01:31:20.051303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.371178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-21T01:31:20.05135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.195704ms","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-21T01:31:20.051402Z","caller":"traceutil/trace.go:171","msg":"trace[1201566743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5367; }","duration":"120.903734ms","start":"2026-04-21T01:31:19.930472Z","end":"2026-04-21T01:31:20.051375Z","steps":["trace[1201566743] 'agreement among raft nodes before linearized reading' (duration: 120.628411ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:20.051418Z","caller":"traceutil/trace.go:171","msg":"trace[1861616079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5367; }","duration":"120.56212ms","start":"2026-04-21T01:31:19.930827Z","end":"2026-04-21T01:31:20.05139Z","steps":["trace[1861616079] 'agreement among raft nodes before linearized reading' (duration: 120.248946ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:20.051466Z","caller":"traceutil/trace.go:171","msg":"trace[629016213] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5367; }","duration":"119.366557ms","start":"2026-04-21T01:31:19.932067Z","end":"2026-04-21T01:31:20.051434Z","steps":["trace[629016213] 'agreement among raft nodes before linearized reading' (duration: 118.981172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:31:20.051709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.947005ms","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-21T01:31:20.051793Z","caller":"traceutil/trace.go:171","msg":"trace[1550822319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5367; }","duration":"306.049286ms","start":"2026-04-21T01:31:19.745732Z","end":"2026-04-21T01:31:20.051781Z","steps":["trace[1550822319] 'agreement among raft nodes before linearized reading' (duration: 305.222556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:31:20.051826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:31:19.745713Z","time spent":"306.103746ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T01:31:30.254355Z","caller":"traceutil/trace.go:171","msg":"trace[492793082] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"153.22171ms","start":"2026-04-21T01:31:30.101096Z","end":"2026-04-21T01:31:30.254318Z","steps":["trace[492793082] 'process raft request' (duration: 153.053908ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:30.394281Z","caller":"traceutil/trace.go:171","msg":"trace[1261971859] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5620; }","duration":"290.843069ms","start":"2026-04-21T01:31:30.103411Z","end":"2026-04-21T01:31:30.394254Z","steps":["trace[1261971859] 'read index received' (duration: 150.736681ms)","trace[1261971859] 'applied index is now lower than readState.Index' (duration: 140.105158ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:31:30.394403Z","caller":"traceutil/trace.go:171","msg":"trace[1247872733] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"293.119466ms","start":"2026-04-21T01:31:30.101227Z","end":"2026-04-21T01:31:30.394346Z","steps":["trace[1247872733] 'process raft request' (duration: 245.394611ms)","trace[1247872733] 'compare' (duration: 47.521603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:31:30.394744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.293394ms","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-21T01:31:30.394824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.137079ms","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-21T01:31:30.394998Z","caller":"traceutil/trace.go:171","msg":"trace[2029749466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5423; }","duration":"246.428313ms","start":"2026-04-21T01:31:30.148551Z","end":"2026-04-21T01:31:30.394979Z","steps":["trace[2029749466] 'agreement among raft nodes before linearized reading' (duration: 246.007618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:31:30.395006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.069381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-21T01:31:30.395018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.575894ms","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-21T01:31:30.395103Z","caller":"traceutil/trace.go:171","msg":"trace[578216548] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5423; }","duration":"254.202423ms","start":"2026-04-21T01:31:30.14088Z","end":"2026-04-21T01:31:30.395083Z","steps":["trace[578216548] 'agreement among raft nodes before linearized reading' (duration: 253.91961ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:30.395115Z","caller":"traceutil/trace.go:171","msg":"trace[1116037252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5423; }","duration":"246.713446ms","start":"2026-04-21T01:31:30.148387Z","end":"2026-04-21T01:31:30.3951Z","steps":["trace[1116037252] 'agreement among raft nodes before linearized reading' (duration: 246.399623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:31:30.394803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.354374ms","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-21T01:31:30.395259Z","caller":"traceutil/trace.go:171","msg":"trace[279488339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5423; }","duration":"291.565106ms","start":"2026-04-21T01:31:30.103379Z","end":"2026-04-21T01:31:30.394944Z","steps":["trace[279488339] 'agreement among raft nodes before linearized reading' (duration: 291.169902ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:30.395305Z","caller":"traceutil/trace.go:171","msg":"trace[1497168972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5423; }","duration":"291.788719ms","start":"2026-04-21T01:31:30.103386Z","end":"2026-04-21T01:31:30.395175Z","steps":["trace[1497168972] 'agreement among raft nodes before linearized reading' (duration: 290.96804ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:30.568338Z","caller":"traceutil/trace.go:171","msg":"trace[1346283765] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"151.751142ms","start":"2026-04-21T01:31:30.416558Z","end":"2026-04-21T01:31:30.568309Z","steps":["trace[1346283765] 'process raft request' (duration: 87.095792ms)","trace[1346283765] 'compare' (duration: 64.515979ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:31:31.985091Z","caller":"traceutil/trace.go:171","msg":"trace[108939543] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"218.05355ms","start":"2026-04-21T01:31:31.766997Z","end":"2026-04-21T01:31:31.985051Z","steps":["trace[108939543] 'process raft request' (duration: 205.216601ms)","trace[108939543] 'compare' (duration: 12.606256ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:31:32.002292Z","caller":"traceutil/trace.go:171","msg":"trace[1804318087] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"235.154458ms","start":"2026-04-21T01:31:31.767104Z","end":"2026-04-21T01:31:32.002259Z","steps":["trace[1804318087] 'process raft request' (duration: 234.956586ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:42.454377Z","caller":"traceutil/trace.go:171","msg":"trace[1586745442] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"119.184015ms","start":"2026-04-21T01:31:42.335161Z","end":"2026-04-21T01:31:42.454345Z","steps":["trace[1586745442] 'process raft request' (duration: 118.71119ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:44.469737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2919} {"level":"info","ts":"2026-04-21T01:31:44.566374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2919,"took":"94.725932ms","hash":1157139636,"current-db-size-bytes":52813824,"current-db-size":"53 MB","current-db-size-in-use-bytes":28225536,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-21T01:31:44.566433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1157139636,"revision":2919,"compact-revision":-1} {"level":"info","ts":"2026-04-21T01:31:46.839181Z","caller":"traceutil/trace.go:171","msg":"trace[1558498705] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"115.391777ms","start":"2026-04-21T01:31:46.723717Z","end":"2026-04-21T01:31:46.839109Z","steps":["trace[1558498705] 'process raft request' (duration: 115.174204ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:47.139014Z","caller":"traceutil/trace.go:171","msg":"trace[459238862] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"406.061655ms","start":"2026-04-21T01:31:46.732904Z","end":"2026-04-21T01:31:47.138965Z","steps":["trace[459238862] 'process raft request' (duration: 384.259556ms)","trace[459238862] 'compare' (duration: 21.572106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:31:47.139278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:31:46.732861Z","time spent":"406.301248ms","remote":"127.0.0.1:42448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:31:54.340344Z","caller":"traceutil/trace.go:171","msg":"trace[75366712] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"192.945449ms","start":"2026-04-21T01:31:54.147367Z","end":"2026-04-21T01:31:54.340312Z","steps":["trace[75366712] 'process raft request' (duration: 192.738846ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:54.370182Z","caller":"traceutil/trace.go:171","msg":"trace[2100751368] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"204.618031ms","start":"2026-04-21T01:31:54.165531Z","end":"2026-04-21T01:31:54.370149Z","steps":["trace[2100751368] 'process raft request' (duration: 204.445459ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:55.052963Z","caller":"traceutil/trace.go:171","msg":"trace[1319080083] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"182.013676ms","start":"2026-04-21T01:31:54.870917Z","end":"2026-04-21T01:31:55.052931Z","steps":["trace[1319080083] 'process raft request' (duration: 180.887134ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:31:57.714341Z","caller":"traceutil/trace.go:171","msg":"trace[238058487] linearizableReadLoop","detail":"{readStateIndex:5827; appliedIndex:5826; }","duration":"149.686516ms","start":"2026-04-21T01:31:57.564624Z","end":"2026-04-21T01:31:57.71431Z","steps":["trace[238058487] 'read index received' (duration: 139.462481ms)","trace[238058487] 'applied index is now lower than readState.Index' (duration: 10.222125ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:31:57.714378Z","caller":"traceutil/trace.go:171","msg":"trace[440443308] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"152.80746ms","start":"2026-04-21T01:31:57.561527Z","end":"2026-04-21T01:31:57.714334Z","steps":["trace[440443308] 'process raft request' (duration: 142.607346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:31:57.714675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.02486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-21T01:31:57.714706Z","caller":"traceutil/trace.go:171","msg":"trace[1821082323] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5621; }","duration":"150.08453ms","start":"2026-04-21T01:31:57.56461Z","end":"2026-04-21T01:31:57.714694Z","steps":["trace[1821082323] 'agreement among raft nodes before linearized reading' (duration: 149.840658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:32:03.086888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.87806ms","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-21T01:32:03.086996Z","caller":"traceutil/trace.go:171","msg":"trace[1688999398] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5675; }","duration":"185.078292ms","start":"2026-04-21T01:32:02.901895Z","end":"2026-04-21T01:32:03.086973Z","steps":["trace[1688999398] 'range keys from in-memory index tree' (duration: 184.775569ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:12.090802Z","caller":"traceutil/trace.go:171","msg":"trace[4926347] transaction","detail":"{read_only:false; response_revision:6544; number_of_response:1; }","duration":"114.071262ms","start":"2026-04-21T01:34:11.976676Z","end":"2026-04-21T01:34:12.090747Z","steps":["trace[4926347] 'process raft request' (duration: 113.780119ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:12.090848Z","caller":"traceutil/trace.go:171","msg":"trace[915556768] transaction","detail":"{read_only:false; response_revision:6545; number_of_response:1; }","duration":"105.472405ms","start":"2026-04-21T01:34:11.985339Z","end":"2026-04-21T01:34:12.090811Z","steps":["trace[915556768] 'process raft request' (duration: 105.304933ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:12.235731Z","caller":"traceutil/trace.go:171","msg":"trace[958890121] transaction","detail":"{read_only:false; response_revision:6546; number_of_response:1; }","duration":"137.15352ms","start":"2026-04-21T01:34:12.098514Z","end":"2026-04-21T01:34:12.235667Z","steps":["trace[958890121] 'process raft request' (duration: 94.476181ms)","trace[958890121] 'compare' (duration: 42.469646ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:34:12.239344Z","caller":"traceutil/trace.go:171","msg":"trace[739138346] linearizableReadLoop","detail":"{readStateIndex:6784; appliedIndex:6782; }","duration":"138.378913ms","start":"2026-04-21T01:34:12.100939Z","end":"2026-04-21T01:34:12.239317Z","steps":["trace[739138346] 'read index received' (duration: 92.170978ms)","trace[739138346] 'applied index is now lower than readState.Index' (duration: 46.206615ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:34:12.239639Z","caller":"traceutil/trace.go:171","msg":"trace[531296793] transaction","detail":"{read_only:false; response_revision:6547; number_of_response:1; }","duration":"138.837848ms","start":"2026-04-21T01:34:12.100709Z","end":"2026-04-21T01:34:12.239547Z","steps":["trace[531296793] 'process raft request' (duration: 138.457874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:12.239737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.771427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-74dfd75959-xxg5k\" ","response":"range_response_count:1 size:9387"} {"level":"info","ts":"2026-04-21T01:34:12.239859Z","caller":"traceutil/trace.go:171","msg":"trace[1451582064] range","detail":"{range_begin:/registry/pods/openstack/horizon-74dfd75959-xxg5k; range_end:; response_count:1; response_revision:6547; }","duration":"138.959759ms","start":"2026-04-21T01:34:12.100883Z","end":"2026-04-21T01:34:12.239842Z","steps":["trace[1451582064] 'agreement among raft nodes before linearized reading' (duration: 138.539924ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:12.382561Z","caller":"traceutil/trace.go:171","msg":"trace[2139035216] transaction","detail":"{read_only:false; response_revision:6548; number_of_response:1; }","duration":"129.48471ms","start":"2026-04-21T01:34:12.253044Z","end":"2026-04-21T01:34:12.382529Z","steps":["trace[2139035216] 'process raft request' (duration: 126.648551ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:15.634691Z","caller":"traceutil/trace.go:171","msg":"trace[559587127] transaction","detail":"{read_only:false; response_revision:6572; number_of_response:1; }","duration":"109.05109ms","start":"2026-04-21T01:34:15.525609Z","end":"2026-04-21T01:34:15.63466Z","steps":["trace[559587127] 'process raft request' (duration: 107.611875ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:36.109093Z","caller":"traceutil/trace.go:171","msg":"trace[1670176125] linearizableReadLoop","detail":"{readStateIndex:6951; appliedIndex:6950; }","duration":"115.129683ms","start":"2026-04-21T01:34:35.993936Z","end":"2026-04-21T01:34:36.109066Z","steps":["trace[1670176125] 'read index received' (duration: 114.941051ms)","trace[1670176125] 'applied index is now lower than readState.Index' (duration: 187.572µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:34:36.109239Z","caller":"traceutil/trace.go:171","msg":"trace[754801009] transaction","detail":"{read_only:false; response_revision:6709; number_of_response:1; }","duration":"118.363316ms","start":"2026-04-21T01:34:35.990863Z","end":"2026-04-21T01:34:36.109226Z","steps":["trace[754801009] 'process raft request' (duration: 118.035092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:36.109954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.995643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-21T01:34:36.110002Z","caller":"traceutil/trace.go:171","msg":"trace[1162072298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6709; }","duration":"116.091933ms","start":"2026-04-21T01:34:35.993897Z","end":"2026-04-21T01:34:36.109989Z","steps":["trace[1162072298] 'agreement among raft nodes before linearized reading' (duration: 115.942132ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:37.233639Z","caller":"traceutil/trace.go:171","msg":"trace[1365102914] linearizableReadLoop","detail":"{readStateIndex:6954; appliedIndex:6953; }","duration":"114.232444ms","start":"2026-04-21T01:34:37.119384Z","end":"2026-04-21T01:34:37.233616Z","steps":["trace[1365102914] 'read index received' (duration: 34.610362ms)","trace[1365102914] 'applied index is now lower than readState.Index' (duration: 79.610372ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:34:37.233692Z","caller":"traceutil/trace.go:171","msg":"trace[1259137531] transaction","detail":"{read_only:false; response_revision:6712; number_of_response:1; }","duration":"115.176354ms","start":"2026-04-21T01:34:37.118493Z","end":"2026-04-21T01:34:37.233669Z","steps":["trace[1259137531] 'process raft request' (duration: 87.439282ms)","trace[1259137531] 'compare' (duration: 27.547591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:34:37.234589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.190813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-04-21T01:34:37.234676Z","caller":"traceutil/trace.go:171","msg":"trace[1586149805] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6712; }","duration":"115.319345ms","start":"2026-04-21T01:34:37.119334Z","end":"2026-04-21T01:34:37.234653Z","steps":["trace[1586149805] 'agreement among raft nodes before linearized reading' (duration: 114.410936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:37.631075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.537989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:34:37.631171Z","caller":"traceutil/trace.go:171","msg":"trace[1924550149] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:6712; }","duration":"175.722861ms","start":"2026-04-21T01:34:37.455425Z","end":"2026-04-21T01:34:37.631148Z","steps":["trace[1924550149] 'count revisions from in-memory index tree' (duration: 175.392327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:37.63161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.793616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-21T01:34:37.631693Z","caller":"traceutil/trace.go:171","msg":"trace[805028036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6712; }","duration":"147.915457ms","start":"2026-04-21T01:34:37.483758Z","end":"2026-04-21T01:34:37.631673Z","steps":["trace[805028036] 'range keys from in-memory index tree' (duration: 147.665645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:37.631928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.486208ms","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":"warn","ts":"2026-04-21T01:34:37.632165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.086935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:34:37.632205Z","caller":"traceutil/trace.go:171","msg":"trace[1238894982] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:6712; }","duration":"178.192375ms","start":"2026-04-21T01:34:37.454002Z","end":"2026-04-21T01:34:37.632194Z","steps":["trace[1238894982] 'count revisions from in-memory index tree' (duration: 177.950023ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:37.6328Z","caller":"traceutil/trace.go:171","msg":"trace[1507746677] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6712; }","duration":"181.61251ms","start":"2026-04-21T01:34:37.450359Z","end":"2026-04-21T01:34:37.631971Z","steps":["trace[1507746677] 'count revisions from in-memory index tree' (duration: 181.395137ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:47.312566Z","caller":"traceutil/trace.go:171","msg":"trace[680116476] transaction","detail":"{read_only:false; response_revision:6816; number_of_response:1; }","duration":"120.034269ms","start":"2026-04-21T01:34:47.192232Z","end":"2026-04-21T01:34:47.312266Z","steps":["trace[680116476] 'process raft request' (duration: 119.852158ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:54.508387Z","caller":"traceutil/trace.go:171","msg":"trace[1996985152] linearizableReadLoop","detail":"{readStateIndex:7102; appliedIndex:7101; }","duration":"232.752829ms","start":"2026-04-21T01:34:54.275606Z","end":"2026-04-21T01:34:54.508359Z","steps":["trace[1996985152] 'read index received' (duration: 232.556517ms)","trace[1996985152] 'applied index is now lower than readState.Index' (duration: 194.862µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:34:54.508548Z","caller":"traceutil/trace.go:171","msg":"trace[314645680] transaction","detail":"{read_only:false; response_revision:6853; number_of_response:1; }","duration":"234.897161ms","start":"2026-04-21T01:34:54.273636Z","end":"2026-04-21T01:34:54.508533Z","steps":["trace[314645680] 'process raft request' (duration: 234.553947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:54.508996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.540635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107259"} {"level":"warn","ts":"2026-04-21T01:34:54.509015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.367255ms","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-21T01:34:54.509098Z","caller":"traceutil/trace.go:171","msg":"trace[833853827] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6853; }","duration":"224.653816ms","start":"2026-04-21T01:34:54.284419Z","end":"2026-04-21T01:34:54.509073Z","steps":["trace[833853827] 'agreement among raft nodes before linearized reading' (duration: 224.365063ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:54.509122Z","caller":"traceutil/trace.go:171","msg":"trace[1760376176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6853; }","duration":"233.486446ms","start":"2026-04-21T01:34:54.275601Z","end":"2026-04-21T01:34:54.509088Z","steps":["trace[1760376176] 'agreement among raft nodes before linearized reading' (duration: 233.188933ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:56.210693Z","caller":"traceutil/trace.go:171","msg":"trace[496696638] linearizableReadLoop","detail":"{readStateIndex:7106; appliedIndex:7105; }","duration":"410.565999ms","start":"2026-04-21T01:34:55.800101Z","end":"2026-04-21T01:34:56.210667Z","steps":["trace[496696638] 'read index received' (duration: 410.365006ms)","trace[496696638] 'applied index is now lower than readState.Index' (duration: 199.813µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:34:56.21093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.809941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-21T01:34:56.210965Z","caller":"traceutil/trace.go:171","msg":"trace[809270559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6857; }","duration":"410.874692ms","start":"2026-04-21T01:34:55.800082Z","end":"2026-04-21T01:34:56.210957Z","steps":["trace[809270559] 'agreement among raft nodes before linearized reading' (duration: 410.705951ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:34:56.21091Z","caller":"traceutil/trace.go:171","msg":"trace[297282092] transaction","detail":"{read_only:false; response_revision:6857; number_of_response:1; }","duration":"511.845595ms","start":"2026-04-21T01:34:55.699022Z","end":"2026-04-21T01:34:56.210868Z","steps":["trace[297282092] 'process raft request' (duration: 511.470931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:56.210997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:34:55.800008Z","time spent":"410.980943ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T01:34:56.211152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:34:55.698997Z","time spent":"512.055317ms","remote":"127.0.0.1:42380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12282,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:34:56.271338Z","caller":"traceutil/trace.go:171","msg":"trace[820792586] transaction","detail":"{read_only:false; response_revision:6859; number_of_response:1; }","duration":"301.249521ms","start":"2026-04-21T01:34:55.970056Z","end":"2026-04-21T01:34:56.271305Z","steps":["trace[820792586] 'process raft request' (duration: 301.179221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:56.271522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:34:55.970025Z","time spent":"301.387572ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:34:56.27191Z","caller":"traceutil/trace.go:171","msg":"trace[1802935099] transaction","detail":"{read_only:false; response_revision:6858; number_of_response:1; }","duration":"424.264508ms","start":"2026-04-21T01:34:55.847634Z","end":"2026-04-21T01:34:56.271899Z","steps":["trace[1802935099] 'process raft request' (duration: 423.45474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:56.271984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:34:55.847612Z","time spent":"424.334808ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:34:56.272476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.129736ms","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-21T01:34:56.272527Z","caller":"traceutil/trace.go:171","msg":"trace[1514841990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6859; }","duration":"271.209847ms","start":"2026-04-21T01:34:56.001306Z","end":"2026-04-21T01:34:56.272516Z","steps":["trace[1514841990] 'agreement among raft nodes before linearized reading' (duration: 271.034295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:56.272697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.615902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:34:56.272754Z","caller":"traceutil/trace.go:171","msg":"trace[634344454] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:6859; }","duration":"121.702163ms","start":"2026-04-21T01:34:56.15104Z","end":"2026-04-21T01:34:56.272743Z","steps":["trace[634344454] 'agreement among raft nodes before linearized reading' (duration: 121.622552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:56.273026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.717781ms","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-21T01:34:56.273108Z","caller":"traceutil/trace.go:171","msg":"trace[1662355485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6859; }","duration":"262.857443ms","start":"2026-04-21T01:34:56.010233Z","end":"2026-04-21T01:34:56.27309Z","steps":["trace[1662355485] 'agreement among raft nodes before linearized reading' (duration: 262.50286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:34:56.273631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.282895ms","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-21T01:34:56.274173Z","caller":"traceutil/trace.go:171","msg":"trace[1126804331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6859; }","duration":"250.814551ms","start":"2026-04-21T01:34:56.023307Z","end":"2026-04-21T01:34:56.274121Z","steps":["trace[1126804331] 'agreement among raft nodes before linearized reading' (duration: 250.180554ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:36:02.012765Z","caller":"traceutil/trace.go:171","msg":"trace[9925874] transaction","detail":"{read_only:false; response_revision:7222; number_of_response:1; }","duration":"105.826667ms","start":"2026-04-21T01:36:01.906914Z","end":"2026-04-21T01:36:02.012741Z","steps":["trace[9925874] 'process raft request' (duration: 105.663005ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:36:44.477272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5518} {"level":"info","ts":"2026-04-21T01:36:44.561952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5518,"took":"82.907103ms","hash":1822784901,"current-db-size-bytes":52813824,"current-db-size":"53 MB","current-db-size-in-use-bytes":18362368,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-21T01:36:44.562008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822784901,"revision":5518,"compact-revision":2919}