{"level":"warn","ts":"2026-04-02T22:32:49.464762Z","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-02T22:32:49.464839Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-02T22:32:49.464907Z","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-02T22:32:49.464926Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-02T22:32:49.464967Z","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-02T22:32:49.465548Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-04-02T22:32:49.465642Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-02T22:32:49.472119Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.246017ms"} {"level":"info","ts":"2026-04-02T22:32:49.483789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-04-02T22:32:49.483926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T22:32:49.48396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-04-02T22:32:49.483988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T22:32:49.484018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-04-02T22:32:49.484083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-04-02T22:32:49.502423Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T22:32:49.504052Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T22:32:49.506273Z","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-02T22:32:49.509349Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T22:32:49.509664Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T22:32:49.509746Z","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-02T22:32:49.510003Z","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-02T22:32:49.510012Z","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-02T22:32:49.511179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-04-02T22:32:49.511645Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-02T22:32:49.515805Z","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-02T22:32:49.516082Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T22:32:49.516151Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T22:32:49.516146Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-02T22:32:49.516221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-02T22:32:50.084542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T22:32:50.084677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T22:32:50.084731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-04-02T22:32:50.084784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-04-02T22:32:50.084817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-02T22:32:50.084855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-04-02T22:32:50.084901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-02T22:32:50.086455Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:32:50.087396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:32:50.0874Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T22:32:50.08751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:32:50.087977Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T22:32:50.088075Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T22:32:50.088164Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:32:50.088296Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:32:50.08837Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:32:50.091342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-04-02T22:32:50.091378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T22:32:53.353745Z","caller":"traceutil/trace.go:171","msg":"trace[842243431] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"152.667334ms","start":"2026-04-02T22:32:53.201054Z","end":"2026-04-02T22:32:53.353721Z","steps":["trace[842243431] 'process raft request' (duration: 152.617193ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:53.353794Z","caller":"traceutil/trace.go:171","msg":"trace[1481524726] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"155.703706ms","start":"2026-04-02T22:32:53.198038Z","end":"2026-04-02T22:32:53.353742Z","steps":["trace[1481524726] 'process raft request' (duration: 149.794867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:03.486373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930021186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:33:03.987039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930021186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:33:04.488032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930021186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:33:04.492322Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.096949678s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:33:04.492873Z","caller":"traceutil/trace.go:171","msg":"trace[1847658846] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"1.506874096s","start":"2026-04-02T22:33:02.985965Z","end":"2026-04-02T22:33:04.492839Z","steps":["trace[1847658846] 'read index received' (duration: 1.506602789s)","trace[1847658846] 'applied index is now lower than readState.Index' (duration: 270.057µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:04.49299Z","caller":"traceutil/trace.go:171","msg":"trace[246026609] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"2.097681026s","start":"2026-04-02T22:33:02.395295Z","end":"2026-04-02T22:33:04.492976Z","steps":["trace[246026609] 'process raft request' (duration: 2.097341308s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:04.493207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459624137s","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-02T22:33:04.493322Z","caller":"traceutil/trace.go:171","msg":"trace[1243064866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:360; }","duration":"1.45978499s","start":"2026-04-02T22:33:03.033515Z","end":"2026-04-02T22:33:04.4933Z","steps":["trace[1243064866] 'agreement among raft nodes before linearized reading' (duration: 1.459622956s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:04.493375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:03.03349Z","time spent":"1.459875482s","remote":"127.0.0.1:44934","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-02T22:33:04.49354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507572172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T22:33:04.493568Z","caller":"traceutil/trace.go:171","msg":"trace[1359571342] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:360; }","duration":"1.507627813s","start":"2026-04-02T22:33:02.985931Z","end":"2026-04-02T22:33:04.493559Z","steps":["trace[1359571342] 'agreement among raft nodes before linearized reading' (duration: 1.507565461s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:04.493595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:02.985917Z","time spent":"1.507671724s","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"warn","ts":"2026-04-02T22:33:04.493785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.129705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-02T22:33:04.493803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:02.395285Z","time spent":"2.097744177s","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:04.493864Z","caller":"traceutil/trace.go:171","msg":"trace[1029891514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:360; }","duration":"688.199837ms","start":"2026-04-02T22:33:03.80562Z","end":"2026-04-02T22:33:04.49382Z","steps":["trace[1029891514] 'agreement among raft nodes before linearized reading' (duration: 688.135755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:04.493901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:03.805604Z","time spent":"688.288929ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T22:33:04.493937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05975507s","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-02T22:33:04.494004Z","caller":"traceutil/trace.go:171","msg":"trace[961293371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:360; }","duration":"1.059874453s","start":"2026-04-02T22:33:03.434109Z","end":"2026-04-02T22:33:04.493984Z","steps":["trace[961293371] 'agreement among raft nodes before linearized reading' (duration: 1.059705189s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:04.494091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:03.434088Z","time spent":"1.059993605s","remote":"127.0.0.1:44934","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-02T22:33:05.126397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930021189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:33:05.157606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.18779ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-02T22:33:05.157934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:04.494802Z","time spent":"663.125918ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-02T22:33:05.158065Z","caller":"traceutil/trace.go:171","msg":"trace[470192451] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"659.894762ms","start":"2026-04-02T22:33:04.498146Z","end":"2026-04-02T22:33:05.158041Z","steps":["trace[470192451] 'process raft request' (duration: 659.846371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:05.158213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:04.498104Z","time spent":"660.022165ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:05.158185Z","caller":"traceutil/trace.go:171","msg":"trace[495408094] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"660.378454ms","start":"2026-04-02T22:33:04.497758Z","end":"2026-04-02T22:33:05.158136Z","steps":["trace[495408094] 'process raft request' (duration: 660.016545ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:05.158235Z","caller":"traceutil/trace.go:171","msg":"trace[639075782] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"660.162528ms","start":"2026-04-02T22:33:04.498025Z","end":"2026-04-02T22:33:05.158188Z","steps":["trace[639075782] 'process raft request' (duration: 659.930652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:05.15841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:04.497736Z","time spent":"660.551897ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:33:05.15851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:04.498016Z","time spent":"660.405844ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:05.160566Z","caller":"traceutil/trace.go:171","msg":"trace[320298371] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:374; }","duration":"534.859597ms","start":"2026-04-02T22:33:04.62568Z","end":"2026-04-02T22:33:05.16054Z","steps":["trace[320298371] 'read index received' (duration: 505.339884ms)","trace[320298371] 'applied index is now lower than readState.Index' (duration: 29.518333ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:05.160637Z","caller":"traceutil/trace.go:171","msg":"trace[1302756447] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"136.719519ms","start":"2026-04-02T22:33:05.023879Z","end":"2026-04-02T22:33:05.160598Z","steps":["trace[1302756447] 'process raft request' (duration: 136.518755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:05.160717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.02872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-02T22:33:05.160761Z","caller":"traceutil/trace.go:171","msg":"trace[332365320] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:364; }","duration":"535.139763ms","start":"2026-04-02T22:33:04.625611Z","end":"2026-04-02T22:33:05.16075Z","steps":["trace[332365320] 'agreement among raft nodes before linearized reading' (duration: 535.03287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:05.1608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:04.625592Z","time spent":"535.199915ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-02T22:33:23.431028Z","caller":"traceutil/trace.go:171","msg":"trace[2102561099] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"332.070006ms","start":"2026-04-02T22:33:23.098932Z","end":"2026-04-02T22:33:23.431002Z","steps":["trace[2102561099] 'process raft request' (duration: 331.974854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:23.431196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:23.098903Z","time spent":"332.207619ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:23.43155Z","caller":"traceutil/trace.go:171","msg":"trace[1080000877] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"328.241346ms","start":"2026-04-02T22:33:23.103286Z","end":"2026-04-02T22:33:23.431528Z","steps":["trace[1080000877] 'read index received' (duration: 322.394909ms)","trace[1080000877] 'applied index is now lower than readState.Index' (duration: 5.845627ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:23.431597Z","caller":"traceutil/trace.go:171","msg":"trace[8772402] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"105.508507ms","start":"2026-04-02T22:33:23.32607Z","end":"2026-04-02T22:33:23.431578Z","steps":["trace[8772402] 'process raft request' (duration: 105.373284ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:23.431714Z","caller":"traceutil/trace.go:171","msg":"trace[884328130] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"305.503302ms","start":"2026-04-02T22:33:23.126069Z","end":"2026-04-02T22:33:23.431572Z","steps":["trace[884328130] 'process raft request' (duration: 304.896788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:23.431772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.60246ms","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-02T22:33:23.43178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.481362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-02T22:33:23.43182Z","caller":"traceutil/trace.go:171","msg":"trace[1651391706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"180.706023ms","start":"2026-04-02T22:33:23.251103Z","end":"2026-04-02T22:33:23.431809Z","steps":["trace[1651391706] 'agreement among raft nodes before linearized reading' (duration: 180.541859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:23.43183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.651904ms","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-02T22:33:23.431833Z","caller":"traceutil/trace.go:171","msg":"trace[1217028001] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:572; }","duration":"328.562484ms","start":"2026-04-02T22:33:23.103255Z","end":"2026-04-02T22:33:23.431817Z","steps":["trace[1217028001] 'agreement among raft nodes before linearized reading' (duration: 328.352279ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:23.431864Z","caller":"traceutil/trace.go:171","msg":"trace[1518667477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"175.715285ms","start":"2026-04-02T22:33:23.256137Z","end":"2026-04-02T22:33:23.431853Z","steps":["trace[1518667477] 'agreement among raft nodes before linearized reading' (duration: 175.650683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:23.431869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:23.103247Z","time spent":"328.614155ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7732,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-02T22:33:23.431999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:23.126031Z","time spent":"305.900472ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:33:23.431995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.968168ms","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-02T22:33:23.431525Z","caller":"traceutil/trace.go:171","msg":"trace[1310998585] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"378.169688ms","start":"2026-04-02T22:33:23.05333Z","end":"2026-04-02T22:33:23.431499Z","steps":["trace[1310998585] 'process raft request' (duration: 372.460524ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:23.432119Z","caller":"traceutil/trace.go:171","msg":"trace[1025378457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"175.175303ms","start":"2026-04-02T22:33:23.256932Z","end":"2026-04-02T22:33:23.432107Z","steps":["trace[1025378457] 'agreement among raft nodes before linearized reading' (duration: 174.846165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:23.432278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:23.053311Z","time spent":"378.897575ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:24.460548Z","caller":"traceutil/trace.go:171","msg":"trace[1835096723] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"270.171063ms","start":"2026-04-02T22:33:24.190357Z","end":"2026-04-02T22:33:24.460528Z","steps":["trace[1835096723] 'read index received' (duration: 270.013179ms)","trace[1835096723] 'applied index is now lower than readState.Index' (duration: 157.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:24.460632Z","caller":"traceutil/trace.go:171","msg":"trace[640734260] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"330.519539ms","start":"2026-04-02T22:33:24.130106Z","end":"2026-04-02T22:33:24.460626Z","steps":["trace[640734260] 'process raft request' (duration: 330.321234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:24.46073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:24.130067Z","time spent":"330.58275ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:33:24.460937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.857583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-02T22:33:24.461Z","caller":"traceutil/trace.go:171","msg":"trace[395102926] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:584; }","duration":"136.928544ms","start":"2026-04-02T22:33:24.324057Z","end":"2026-04-02T22:33:24.460986Z","steps":["trace[395102926] 'agreement among raft nodes before linearized reading' (duration: 136.794881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:24.461085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.737406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:24.461136Z","caller":"traceutil/trace.go:171","msg":"trace[2139655920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"270.91108ms","start":"2026-04-02T22:33:24.190214Z","end":"2026-04-02T22:33:24.461125Z","steps":["trace[2139655920] 'agreement among raft nodes before linearized reading' (duration: 270.838888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:24.46118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.905268ms","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-02T22:33:24.461201Z","caller":"traceutil/trace.go:171","msg":"trace[331841771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:584; }","duration":"120.98364ms","start":"2026-04-02T22:33:24.340213Z","end":"2026-04-02T22:33:24.461197Z","steps":["trace[331841771] 'agreement among raft nodes before linearized reading' (duration: 120.897028ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:24.607362Z","caller":"traceutil/trace.go:171","msg":"trace[670151913] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"142.887414ms","start":"2026-04-02T22:33:24.46445Z","end":"2026-04-02T22:33:24.607338Z","steps":["trace[670151913] 'process raft request' (duration: 142.72262ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:24.611794Z","caller":"traceutil/trace.go:171","msg":"trace[1885476846] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"134.611621ms","start":"2026-04-02T22:33:24.477169Z","end":"2026-04-02T22:33:24.611781Z","steps":["trace[1885476846] 'process raft request' (duration: 134.505138ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.138584Z","caller":"traceutil/trace.go:171","msg":"trace[1630871877] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"139.966436ms","start":"2026-04-02T22:33:29.998594Z","end":"2026-04-02T22:33:30.13856Z","steps":["trace[1630871877] 'process raft request' (duration: 139.373922ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.836224Z","caller":"traceutil/trace.go:171","msg":"trace[824666293] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"202.104915ms","start":"2026-04-02T22:33:30.634102Z","end":"2026-04-02T22:33:30.836207Z","steps":["trace[824666293] 'read index received' (duration: 201.956552ms)","trace[824666293] 'applied index is now lower than readState.Index' (duration: 147.703µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:30.836322Z","caller":"traceutil/trace.go:171","msg":"trace[241441451] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"204.580553ms","start":"2026-04-02T22:33:30.631717Z","end":"2026-04-02T22:33:30.836298Z","steps":["trace[241441451] 'process raft request' (duration: 204.380769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.258679ms","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-02T22:33:30.836476Z","caller":"traceutil/trace.go:171","msg":"trace[1126328446] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:641; }","duration":"202.33697ms","start":"2026-04-02T22:33:30.634127Z","end":"2026-04-02T22:33:30.836464Z","steps":["trace[1126328446] 'agreement among raft nodes before linearized reading' (duration: 202.243378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.710861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:33:30.83669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.275969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.836683Z","caller":"traceutil/trace.go:171","msg":"trace[485962793] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:641; }","duration":"145.894425ms","start":"2026-04-02T22:33:30.690772Z","end":"2026-04-02T22:33:30.836666Z","steps":["trace[485962793] 'agreement among raft nodes before linearized reading' (duration: 145.68984ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.836715Z","caller":"traceutil/trace.go:171","msg":"trace[797006366] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:641; }","duration":"202.31648ms","start":"2026-04-02T22:33:30.634392Z","end":"2026-04-02T22:33:30.836708Z","steps":["trace[797006366] 'agreement among raft nodes before linearized reading' (duration: 202.260949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.905896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:33:30.836776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.102193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:33:30.836829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.044221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:33:30.836853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.388273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.83688Z","caller":"traceutil/trace.go:171","msg":"trace[2142066429] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:641; }","duration":"169.038128ms","start":"2026-04-02T22:33:30.667814Z","end":"2026-04-02T22:33:30.836853Z","steps":["trace[2142066429] 'agreement among raft nodes before linearized reading' (duration: 168.778682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.056019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.836911Z","caller":"traceutil/trace.go:171","msg":"trace[684006416] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:641; }","duration":"168.461195ms","start":"2026-04-02T22:33:30.66844Z","end":"2026-04-02T22:33:30.836901Z","steps":["trace[684006416] 'agreement among raft nodes before linearized reading' (duration: 168.391814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.83676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.378661ms","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-02T22:33:30.836906Z","caller":"traceutil/trace.go:171","msg":"trace[810015541] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:641; }","duration":"184.160644ms","start":"2026-04-02T22:33:30.65272Z","end":"2026-04-02T22:33:30.83688Z","steps":["trace[810015541] 'agreement among raft nodes before linearized reading' (duration: 183.99309ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.836945Z","caller":"traceutil/trace.go:171","msg":"trace[2080747274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"173.619166ms","start":"2026-04-02T22:33:30.663317Z","end":"2026-04-02T22:33:30.836936Z","steps":["trace[2080747274] 'agreement among raft nodes before linearized reading' (duration: 173.190346ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.836946Z","caller":"traceutil/trace.go:171","msg":"trace[223925314] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:641; }","duration":"169.148821ms","start":"2026-04-02T22:33:30.667776Z","end":"2026-04-02T22:33:30.836925Z","steps":["trace[223925314] 'agreement among raft nodes before linearized reading' (duration: 169.025008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.186231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.8369Z","caller":"traceutil/trace.go:171","msg":"trace[1716410947] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:641; }","duration":"184.244016ms","start":"2026-04-02T22:33:30.652625Z","end":"2026-04-02T22:33:30.836869Z","steps":["trace[1716410947] 'agreement among raft nodes before linearized reading' (duration: 184.024421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.10316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:33:30.837081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.068561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:33:30.836913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.833516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.837073Z","caller":"traceutil/trace.go:171","msg":"trace[39740907] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:641; }","duration":"111.258342ms","start":"2026-04-02T22:33:30.725742Z","end":"2026-04-02T22:33:30.837Z","steps":["trace[39740907] 'agreement among raft nodes before linearized reading' (duration: 111.19598ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.837134Z","caller":"traceutil/trace.go:171","msg":"trace[1983830237] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:641; }","duration":"112.098542ms","start":"2026-04-02T22:33:30.725026Z","end":"2026-04-02T22:33:30.837124Z","steps":["trace[1983830237] 'agreement among raft nodes before linearized reading' (duration: 111.835006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.837167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.816391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.837174Z","caller":"traceutil/trace.go:171","msg":"trace[977635087] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:641; }","duration":"112.205265ms","start":"2026-04-02T22:33:30.724954Z","end":"2026-04-02T22:33:30.837159Z","steps":["trace[977635087] 'agreement among raft nodes before linearized reading' (duration: 112.102432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.538118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.837211Z","caller":"traceutil/trace.go:171","msg":"trace[1379752929] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:641; }","duration":"127.917163ms","start":"2026-04-02T22:33:30.709283Z","end":"2026-04-02T22:33:30.8372Z","steps":["trace[1379752929] 'agreement among raft nodes before linearized reading' (duration: 127.838662ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.837221Z","caller":"traceutil/trace.go:171","msg":"trace[527668947] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:641; }","duration":"128.896137ms","start":"2026-04-02T22:33:30.708316Z","end":"2026-04-02T22:33:30.837212Z","steps":["trace[527668947] 'agreement among raft nodes before linearized reading' (duration: 128.558969ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:30.837106Z","caller":"traceutil/trace.go:171","msg":"trace[919924684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:641; }","duration":"146.262574ms","start":"2026-04-02T22:33:30.690829Z","end":"2026-04-02T22:33:30.837092Z","steps":["trace[919924684] 'agreement among raft nodes before linearized reading' (duration: 146.1032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.836637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.391776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.837327Z","caller":"traceutil/trace.go:171","msg":"trace[1746471979] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:641; }","duration":"184.090152ms","start":"2026-04-02T22:33:30.653218Z","end":"2026-04-02T22:33:30.837309Z","steps":["trace[1746471979] 'agreement among raft nodes before linearized reading' (duration: 183.396446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.837369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.535558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.837464Z","caller":"traceutil/trace.go:171","msg":"trace[1060197786] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:641; }","duration":"129.006559ms","start":"2026-04-02T22:33:30.708435Z","end":"2026-04-02T22:33:30.837442Z","steps":["trace[1060197786] 'agreement among raft nodes before linearized reading' (duration: 128.429946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:30.838119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.99841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:30.838208Z","caller":"traceutil/trace.go:171","msg":"trace[764311172] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:641; }","duration":"204.103552ms","start":"2026-04-02T22:33:30.634091Z","end":"2026-04-02T22:33:30.838195Z","steps":["trace[764311172] 'agreement among raft nodes before linearized reading' (duration: 202.243898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:31.362809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.640284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:31.362909Z","caller":"traceutil/trace.go:171","msg":"trace[1371985444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"172.802897ms","start":"2026-04-02T22:33:31.190079Z","end":"2026-04-02T22:33:31.362882Z","steps":["trace[1371985444] 'range keys from in-memory index tree' (duration: 172.586672ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:47.326755Z","caller":"traceutil/trace.go:171","msg":"trace[397974861] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"337.950604ms","start":"2026-04-02T22:33:46.98878Z","end":"2026-04-02T22:33:47.326731Z","steps":["trace[397974861] 'read index received' (duration: 337.917213ms)","trace[397974861] 'applied index is now lower than readState.Index' (duration: 32.181µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:47.326867Z","caller":"traceutil/trace.go:171","msg":"trace[946858078] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"415.106585ms","start":"2026-04-02T22:33:46.911716Z","end":"2026-04-02T22:33:47.326823Z","steps":["trace[946858078] 'process raft request' (duration: 414.88089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:47.327109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:46.911695Z","time spent":"415.27214ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:33:47.327082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.284232ms","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-02T22:33:47.327191Z","caller":"traceutil/trace.go:171","msg":"trace[1249086071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"338.394494ms","start":"2026-04-02T22:33:46.988774Z","end":"2026-04-02T22:33:47.327169Z","steps":["trace[1249086071] 'agreement among raft nodes before linearized reading' (duration: 338.089437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:47.327251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:46.9887Z","time spent":"338.538648ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:33:47.517665Z","caller":"traceutil/trace.go:171","msg":"trace[232361675] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"190.81818ms","start":"2026-04-02T22:33:47.326828Z","end":"2026-04-02T22:33:47.517646Z","steps":["trace[232361675] 'read index received' (duration: 190.696577ms)","trace[232361675] 'applied index is now lower than readState.Index' (duration: 120.543µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:47.517719Z","caller":"traceutil/trace.go:171","msg":"trace[1361758738] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"417.383529ms","start":"2026-04-02T22:33:47.100311Z","end":"2026-04-02T22:33:47.517694Z","steps":["trace[1361758738] 'process raft request' (duration: 417.222445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:47.517771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.627011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:47.517811Z","caller":"traceutil/trace.go:171","msg":"trace[1363504059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"327.724764ms","start":"2026-04-02T22:33:47.190073Z","end":"2026-04-02T22:33:47.517797Z","steps":["trace[1363504059] 'agreement among raft nodes before linearized reading' (duration: 327.641852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:47.517866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:47.190059Z","time spent":"327.800615ms","remote":"127.0.0.1:44668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:33:47.517903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:47.100278Z","time spent":"417.523362ms","remote":"127.0.0.1:44912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:47.521031Z","caller":"traceutil/trace.go:171","msg":"trace[1133603145] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"189.12993ms","start":"2026-04-02T22:33:47.331866Z","end":"2026-04-02T22:33:47.520996Z","steps":["trace[1133603145] 'process raft request' (duration: 188.878904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.600139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930022733,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:34:21.849272Z","caller":"traceutil/trace.go:171","msg":"trace[1323892225] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"749.193483ms","start":"2026-04-02T22:34:21.100059Z","end":"2026-04-02T22:34:21.849253Z","steps":["trace[1323892225] 'read index received' (duration: 748.990188ms)","trace[1323892225] 'applied index is now lower than readState.Index' (duration: 202.355µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:34:21.849393Z","caller":"traceutil/trace.go:171","msg":"trace[1502295574] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"834.394384ms","start":"2026-04-02T22:34:21.014978Z","end":"2026-04-02T22:34:21.849373Z","steps":["trace[1502295574] 'process raft request' (duration: 834.132938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.849434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.361047ms","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-02T22:34:21.849469Z","caller":"traceutil/trace.go:171","msg":"trace[1913643556] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1171; }","duration":"749.413928ms","start":"2026-04-02T22:34:21.100046Z","end":"2026-04-02T22:34:21.84946Z","steps":["trace[1913643556] 'agreement among raft nodes before linearized reading' (duration: 749.329366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.849505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:34:21.100003Z","time spent":"749.49281ms","remote":"127.0.0.1:45010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":20,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:34:21.849818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.693855ms","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-02T22:34:21.849503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:34:21.014962Z","time spent":"834.474206ms","remote":"127.0.0.1:44912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:34:21.849907Z","caller":"traceutil/trace.go:171","msg":"trace[180920931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"149.809168ms","start":"2026-04-02T22:34:21.700079Z","end":"2026-04-02T22:34:21.849888Z","steps":["trace[180920931] 'agreement among raft nodes before linearized reading' (duration: 149.608693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.849916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.190759ms","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-02T22:34:21.84995Z","caller":"traceutil/trace.go:171","msg":"trace[466713553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"363.2659ms","start":"2026-04-02T22:34:21.486674Z","end":"2026-04-02T22:34:21.84994Z","steps":["trace[466713553] 'agreement among raft nodes before linearized reading' (duration: 363.133127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.849984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:34:21.486655Z","time spent":"363.322031ms","remote":"127.0.0.1:44934","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-02T22:34:21.84964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.686581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:34:21.8502Z","caller":"traceutil/trace.go:171","msg":"trace[449825197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"660.259955ms","start":"2026-04-02T22:34:21.189899Z","end":"2026-04-02T22:34:21.850159Z","steps":["trace[449825197] 'agreement among raft nodes before linearized reading' (duration: 659.670831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.850245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:34:21.189885Z","time spent":"660.348046ms","remote":"127.0.0.1:44668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:34:21.962719Z","caller":"traceutil/trace.go:171","msg":"trace[1978691026] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"104.389741ms","start":"2026-04-02T22:34:21.858301Z","end":"2026-04-02T22:34:21.962691Z","steps":["trace[1978691026] 'process raft request' (duration: 101.251947ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:21.964428Z","caller":"traceutil/trace.go:171","msg":"trace[1764875214] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"105.935317ms","start":"2026-04-02T22:34:21.858475Z","end":"2026-04-02T22:34:21.96441Z","steps":["trace[1764875214] 'process raft request' (duration: 105.879126ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:21.964452Z","caller":"traceutil/trace.go:171","msg":"trace[708052094] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"106.03519ms","start":"2026-04-02T22:34:21.858394Z","end":"2026-04-02T22:34:21.964429Z","steps":["trace[708052094] 'process raft request' (duration: 105.879026ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:21.96465Z","caller":"traceutil/trace.go:171","msg":"trace[1142087563] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1208; }","duration":"100.885739ms","start":"2026-04-02T22:34:21.86374Z","end":"2026-04-02T22:34:21.964625Z","steps":["trace[1142087563] 'read index received' (duration: 95.82307ms)","trace[1142087563] 'applied index is now lower than readState.Index' (duration: 5.061639ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:34:21.964672Z","caller":"traceutil/trace.go:171","msg":"trace[255500387] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"106.092101ms","start":"2026-04-02T22:34:21.858563Z","end":"2026-04-02T22:34:21.964655Z","steps":["trace[255500387] 'process raft request' (duration: 105.819904ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:21.964735Z","caller":"traceutil/trace.go:171","msg":"trace[1465348697] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"106.05616ms","start":"2026-04-02T22:34:21.858608Z","end":"2026-04-02T22:34:21.964664Z","steps":["trace[1465348697] 'process raft request' (duration: 105.949148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.9649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.135865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-04-02T22:34:21.964948Z","caller":"traceutil/trace.go:171","msg":"trace[107707322] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1176; }","duration":"101.224556ms","start":"2026-04-02T22:34:21.863709Z","end":"2026-04-02T22:34:21.964933Z","steps":["trace[107707322] 'agreement among raft nodes before linearized reading' (duration: 101.035732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:21.965527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.054742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rpplh\" ","response":"range_response_count:1 size:4125"} {"level":"info","ts":"2026-04-02T22:34:21.965623Z","caller":"traceutil/trace.go:171","msg":"trace[1667083068] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rpplh; range_end:; response_count:1; response_revision:1176; }","duration":"101.737609ms","start":"2026-04-02T22:34:21.863864Z","end":"2026-04-02T22:34:21.965602Z","steps":["trace[1667083068] 'agreement among raft nodes before linearized reading' (duration: 100.90801ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:36.274713Z","caller":"traceutil/trace.go:171","msg":"trace[928750179] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"227.163374ms","start":"2026-04-02T22:34:36.047525Z","end":"2026-04-02T22:34:36.274688Z","steps":["trace[928750179] 'process raft request' (duration: 226.98854ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:36.283695Z","caller":"traceutil/trace.go:171","msg":"trace[1464615959] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"235.426568ms","start":"2026-04-02T22:34:36.048239Z","end":"2026-04-02T22:34:36.283666Z","steps":["trace[1464615959] 'process raft request' (duration: 235.180122ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:36.283864Z","caller":"traceutil/trace.go:171","msg":"trace[1935709609] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"231.319731ms","start":"2026-04-02T22:34:36.052527Z","end":"2026-04-02T22:34:36.283847Z","steps":["trace[1935709609] 'process raft request' (duration: 231.078336ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:36.283963Z","caller":"traceutil/trace.go:171","msg":"trace[1868983009] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1347; }","duration":"232.10079ms","start":"2026-04-02T22:34:36.051842Z","end":"2026-04-02T22:34:36.283943Z","steps":["trace[1868983009] 'read index received' (duration: 222.785501ms)","trace[1868983009] 'applied index is now lower than readState.Index' (duration: 9.314089ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:34:36.284049Z","caller":"traceutil/trace.go:171","msg":"trace[343222388] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"229.908649ms","start":"2026-04-02T22:34:36.054113Z","end":"2026-04-02T22:34:36.284022Z","steps":["trace[343222388] 'process raft request' (duration: 229.675273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:36.28412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.217143ms","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-02T22:34:36.284144Z","caller":"traceutil/trace.go:171","msg":"trace[1295416842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"232.315785ms","start":"2026-04-02T22:34:36.051822Z","end":"2026-04-02T22:34:36.284137Z","steps":["trace[1295416842] 'agreement among raft nodes before linearized reading' (duration: 232.197422ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:44.259465Z","caller":"traceutil/trace.go:171","msg":"trace[1675898302] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"195.535701ms","start":"2026-04-02T22:34:44.063895Z","end":"2026-04-02T22:34:44.259431Z","steps":["trace[1675898302] 'process raft request' (duration: 195.292446ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:54.202724Z","caller":"traceutil/trace.go:171","msg":"trace[277689448] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"140.641252ms","start":"2026-04-02T22:34:54.062057Z","end":"2026-04-02T22:34:54.202698Z","steps":["trace[277689448] 'process raft request' (duration: 140.065978ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:00.196884Z","caller":"traceutil/trace.go:171","msg":"trace[252585302] linearizableReadLoop","detail":"{readStateIndex:1786; appliedIndex:1785; }","duration":"310.002179ms","start":"2026-04-02T22:34:59.886863Z","end":"2026-04-02T22:35:00.196865Z","steps":["trace[252585302] 'read index received' (duration: 309.801344ms)","trace[252585302] 'applied index is now lower than readState.Index' (duration: 199.895µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:35:00.19703Z","caller":"traceutil/trace.go:171","msg":"trace[767574763] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"312.064828ms","start":"2026-04-02T22:34:59.884877Z","end":"2026-04-02T22:35:00.196942Z","steps":["trace[767574763] 'process raft request' (duration: 311.862173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.126233ms","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:5"} {"level":"warn","ts":"2026-04-02T22:35:00.197164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.55739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:00.19721Z","caller":"traceutil/trace.go:171","msg":"trace[2106729760] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"310.235945ms","start":"2026-04-02T22:34:59.886958Z","end":"2026-04-02T22:35:00.197194Z","steps":["trace[2106729760] 'agreement among raft nodes before linearized reading' (duration: 310.099512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.488095ms","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:5"} {"level":"info","ts":"2026-04-02T22:35:00.197238Z","caller":"traceutil/trace.go:171","msg":"trace[325756994] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"233.657722ms","start":"2026-04-02T22:34:59.963568Z","end":"2026-04-02T22:35:00.197225Z","steps":["trace[325756994] 'agreement among raft nodes before linearized reading' (duration: 233.56919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:34:59.88695Z","time spent":"310.293346ms","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:35:00.197274Z","caller":"traceutil/trace.go:171","msg":"trace[1549403240] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"282.564797ms","start":"2026-04-02T22:34:59.914696Z","end":"2026-04-02T22:35:00.197261Z","steps":["trace[1549403240] 'agreement among raft nodes before linearized reading' (duration: 282.478695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.178951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:00.19737Z","caller":"traceutil/trace.go:171","msg":"trace[24430632] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1716; }","duration":"233.365885ms","start":"2026-04-02T22:34:59.963993Z","end":"2026-04-02T22:35:00.197359Z","steps":["trace[24430632] 'agreement among raft nodes before linearized reading' (duration: 233.15444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.744701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:35:00.197395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.381364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:00.19743Z","caller":"traceutil/trace.go:171","msg":"trace[1648240921] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"282.812423ms","start":"2026-04-02T22:34:59.914601Z","end":"2026-04-02T22:35:00.197413Z","steps":["trace[1648240921] 'agreement among raft nodes before linearized reading' (duration: 282.746482ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:00.197456Z","caller":"traceutil/trace.go:171","msg":"trace[734547598] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"256.469846ms","start":"2026-04-02T22:34:59.940972Z","end":"2026-04-02T22:35:00.197442Z","steps":["trace[734547598] 'agreement among raft nodes before linearized reading' (duration: 256.294532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.551059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:35:00.197434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.599316ms","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:5"} {"level":"info","ts":"2026-04-02T22:35:00.197496Z","caller":"traceutil/trace.go:171","msg":"trace[1512689801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"268.59079ms","start":"2026-04-02T22:34:59.928896Z","end":"2026-04-02T22:35:00.197486Z","steps":["trace[1512689801] 'agreement among raft nodes before linearized reading' (duration: 268.535248ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:00.197526Z","caller":"traceutil/trace.go:171","msg":"trace[597057479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"296.718168ms","start":"2026-04-02T22:34:59.900786Z","end":"2026-04-02T22:35:00.197504Z","steps":["trace[597057479] 'agreement among raft nodes before linearized reading' (duration: 296.566415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.537068ms","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:5"} {"level":"warn","ts":"2026-04-02T22:35:00.197554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.056521ms","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:5"} {"level":"info","ts":"2026-04-02T22:35:00.197575Z","caller":"traceutil/trace.go:171","msg":"trace[1720402224] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"256.580589ms","start":"2026-04-02T22:34:59.940984Z","end":"2026-04-02T22:35:00.197564Z","steps":["trace[1720402224] 'agreement among raft nodes before linearized reading' (duration: 256.523507ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:00.197596Z","caller":"traceutil/trace.go:171","msg":"trace[732450562] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"234.131323ms","start":"2026-04-02T22:34:59.963455Z","end":"2026-04-02T22:35:00.197587Z","steps":["trace[732450562] 'agreement among raft nodes before linearized reading' (duration: 234.068422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.19763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.84439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T22:35:00.197309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.392851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:00.197713Z","caller":"traceutil/trace.go:171","msg":"trace[26832133] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"296.843312ms","start":"2026-04-02T22:34:59.900859Z","end":"2026-04-02T22:35:00.197702Z","steps":["trace[26832133] 'agreement among raft nodes before linearized reading' (duration: 296.377541ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:00.197714Z","caller":"traceutil/trace.go:171","msg":"trace[2069472034] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1716; }","duration":"269.962332ms","start":"2026-04-02T22:34:59.927735Z","end":"2026-04-02T22:35:00.197697Z","steps":["trace[2069472034] 'agreement among raft nodes before linearized reading' (duration: 269.842039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.149753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:00.197919Z","caller":"traceutil/trace.go:171","msg":"trace[932484852] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"311.065815ms","start":"2026-04-02T22:34:59.88684Z","end":"2026-04-02T22:35:00.197906Z","steps":["trace[932484852] 'agreement among raft nodes before linearized reading' (duration: 310.147143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:00.197932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:34:59.884861Z","time spent":"312.256452ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:35:00.197957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.702792ms","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:5"} {"level":"warn","ts":"2026-04-02T22:35:00.197967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:34:59.886829Z","time spent":"311.121906ms","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 "} {"level":"info","ts":"2026-04-02T22:35:00.198009Z","caller":"traceutil/trace.go:171","msg":"trace[489426500] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1716; }","duration":"268.781155ms","start":"2026-04-02T22:34:59.929215Z","end":"2026-04-02T22:35:00.197996Z","steps":["trace[489426500] 'agreement among raft nodes before linearized reading' (duration: 268.681862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:06.928449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930024042,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:35:07.108288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.323716ms","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-02T22:35:07.10838Z","caller":"traceutil/trace.go:171","msg":"trace[64762550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1825; }","duration":"693.454109ms","start":"2026-04-02T22:35:06.4149Z","end":"2026-04-02T22:35:07.108354Z","steps":["trace[64762550] 'range keys from in-memory index tree' (duration: 693.131781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.108362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.081451ms","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-02T22:35:07.108518Z","caller":"traceutil/trace.go:171","msg":"trace[1918455776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1825; }","duration":"690.279995ms","start":"2026-04-02T22:35:06.418207Z","end":"2026-04-02T22:35:07.108487Z","steps":["trace[1918455776] 'range keys from in-memory index tree' (duration: 689.774634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.108603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.41814Z","time spent":"690.445829ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:35:07.108423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.414883Z","time spent":"693.530881ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T22:35:07.266929Z","caller":"traceutil/trace.go:171","msg":"trace[933733059] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"839.51812ms","start":"2026-04-02T22:35:06.42739Z","end":"2026-04-02T22:35:07.266908Z","steps":["trace[933733059] 'read index received' (duration: 839.301805ms)","trace[933733059] 'applied index is now lower than readState.Index' (duration: 214.825µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:35:07.267027Z","caller":"traceutil/trace.go:171","msg":"trace[88030905] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"845.661783ms","start":"2026-04-02T22:35:06.42133Z","end":"2026-04-02T22:35:07.266992Z","steps":["trace[88030905] 'process raft request' (duration: 845.383476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.267163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.760985ms","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-02T22:35:07.267212Z","caller":"traceutil/trace.go:171","msg":"trace[381747911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1826; }","duration":"839.813417ms","start":"2026-04-02T22:35:06.427384Z","end":"2026-04-02T22:35:07.267197Z","steps":["trace[381747911] 'agreement among raft nodes before linearized reading' (duration: 839.652863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.267221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.421313Z","time spent":"845.804217ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:35:07.267255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.427335Z","time spent":"839.910919ms","remote":"127.0.0.1:44934","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-02T22:35:07.283041Z","caller":"traceutil/trace.go:171","msg":"trace[1962146486] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"170.176305ms","start":"2026-04-02T22:35:07.112839Z","end":"2026-04-02T22:35:07.283015Z","steps":["trace[1962146486] 'process raft request' (duration: 169.96713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.283319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.152147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:07.283408Z","caller":"traceutil/trace.go:171","msg":"trace[540106945] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1828; }","duration":"557.295491ms","start":"2026-04-02T22:35:06.72609Z","end":"2026-04-02T22:35:07.283385Z","steps":["trace[540106945] 'agreement among raft nodes before linearized reading' (duration: 557.083666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.283455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.726065Z","time spent":"557.379062ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:35:07.283336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.248148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T22:35:07.283513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.769285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-02T22:35:07.283559Z","caller":"traceutil/trace.go:171","msg":"trace[680532553] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49; range_end:; response_count:1; response_revision:1828; }","duration":"830.809936ms","start":"2026-04-02T22:35:06.452732Z","end":"2026-04-02T22:35:07.283542Z","steps":["trace[680532553] 'agreement among raft nodes before linearized reading' (duration: 830.641612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.28355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.69935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-02T22:35:07.283605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.452708Z","time spent":"830.889338ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49\" "} {"level":"info","ts":"2026-04-02T22:35:07.28363Z","caller":"traceutil/trace.go:171","msg":"trace[1382793999] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49; range_end:; response_count:1; response_revision:1828; }","duration":"127.819253ms","start":"2026-04-02T22:35:07.155791Z","end":"2026-04-02T22:35:07.28361Z","steps":["trace[1382793999] 'agreement among raft nodes before linearized reading' (duration: 127.585978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.283607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.277174ms","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-02T22:35:07.283695Z","caller":"traceutil/trace.go:171","msg":"trace[1026435258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1828; }","duration":"851.401768ms","start":"2026-04-02T22:35:06.432276Z","end":"2026-04-02T22:35:07.283677Z","steps":["trace[1026435258] 'agreement among raft nodes before linearized reading' (duration: 851.187303ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:07.283566Z","caller":"traceutil/trace.go:171","msg":"trace[472682706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1828; }","duration":"855.495853ms","start":"2026-04-02T22:35:06.428033Z","end":"2026-04-02T22:35:07.283529Z","steps":["trace[472682706] 'agreement among raft nodes before linearized reading' (duration: 855.102474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.283734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.432261Z","time spent":"851.465929ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T22:35:07.283317Z","caller":"traceutil/trace.go:171","msg":"trace[1962078192] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"170.064242ms","start":"2026-04-02T22:35:07.113169Z","end":"2026-04-02T22:35:07.283234Z","steps":["trace[1962078192] 'process raft request' (duration: 169.802976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:07.283761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:06.428017Z","time spent":"855.722549ms","remote":"127.0.0.1:44934","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-02T22:35:07.435031Z","caller":"traceutil/trace.go:171","msg":"trace[942978904] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"149.743126ms","start":"2026-04-02T22:35:07.285264Z","end":"2026-04-02T22:35:07.435007Z","steps":["trace[942978904] 'read index received' (duration: 113.065748ms)","trace[942978904] 'applied index is now lower than readState.Index' (duration: 36.676048ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:35:07.435051Z","caller":"traceutil/trace.go:171","msg":"trace[1536011523] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"163.065049ms","start":"2026-04-02T22:35:07.27196Z","end":"2026-04-02T22:35:07.435025Z","steps":["trace[1536011523] 'process raft request' (duration: 126.37561ms)","trace[1536011523] 'compare' (duration: 36.449223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:35:07.435313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.037074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:07.435412Z","caller":"traceutil/trace.go:171","msg":"trace[1975315187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1829; }","duration":"150.199927ms","start":"2026-04-02T22:35:07.285189Z","end":"2026-04-02T22:35:07.435389Z","steps":["trace[1975315187] 'agreement among raft nodes before linearized reading' (duration: 149.88847ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:07.494265Z","caller":"traceutil/trace.go:171","msg":"trace[903397996] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"207.089479ms","start":"2026-04-02T22:35:07.287154Z","end":"2026-04-02T22:35:07.494243Z","steps":["trace[903397996] 'process raft request' (duration: 206.915835ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:07.494502Z","caller":"traceutil/trace.go:171","msg":"trace[259328948] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"206.721781ms","start":"2026-04-02T22:35:07.287757Z","end":"2026-04-02T22:35:07.494478Z","steps":["trace[259328948] 'process raft request' (duration: 206.436494ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:07.494562Z","caller":"traceutil/trace.go:171","msg":"trace[1736280058] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"203.977246ms","start":"2026-04-02T22:35:07.290567Z","end":"2026-04-02T22:35:07.494544Z","steps":["trace[1736280058] 'process raft request' (duration: 203.774722ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:07.792812Z","caller":"traceutil/trace.go:171","msg":"trace[1216485789] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"283.878188ms","start":"2026-04-02T22:35:07.508909Z","end":"2026-04-02T22:35:07.792787Z","steps":["trace[1216485789] 'process raft request' (duration: 188.31565ms)","trace[1216485789] 'compare' (duration: 95.290512ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:35:07.823612Z","caller":"traceutil/trace.go:171","msg":"trace[355845672] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1904; }","duration":"122.267383ms","start":"2026-04-02T22:35:07.701325Z","end":"2026-04-02T22:35:07.823592Z","steps":["trace[355845672] 'read index received' (duration: 122.049208ms)","trace[355845672] 'applied index is now lower than readState.Index' (duration: 217.015µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:35:07.823821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.476208ms","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-02T22:35:07.823792Z","caller":"traceutil/trace.go:171","msg":"trace[500601673] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"239.397796ms","start":"2026-04-02T22:35:07.584372Z","end":"2026-04-02T22:35:07.82377Z","steps":["trace[500601673] 'process raft request' (duration: 239.037198ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:07.82386Z","caller":"traceutil/trace.go:171","msg":"trace[545011520] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1835; }","duration":"122.58369ms","start":"2026-04-02T22:35:07.701262Z","end":"2026-04-02T22:35:07.823846Z","steps":["trace[545011520] 'agreement among raft nodes before linearized reading' (duration: 122.407776ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:07.823886Z","caller":"traceutil/trace.go:171","msg":"trace[751718762] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"224.17861ms","start":"2026-04-02T22:35:07.599671Z","end":"2026-04-02T22:35:07.823849Z","steps":["trace[751718762] 'process raft request' (duration: 223.863653ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:08.623112Z","caller":"traceutil/trace.go:171","msg":"trace[827380858] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"824.18568ms","start":"2026-04-02T22:35:07.798901Z","end":"2026-04-02T22:35:08.623087Z","steps":["trace[827380858] 'process raft request' (duration: 823.985035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:08.623282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:07.798883Z","time spent":"824.303562ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:35:08.623506Z","caller":"traceutil/trace.go:171","msg":"trace[2113115299] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"871.499837ms","start":"2026-04-02T22:35:07.751452Z","end":"2026-04-02T22:35:08.622952Z","steps":["trace[2113115299] 'process raft request' (duration: 849.281527ms)","trace[2113115299] 'compare' (duration: 21.526574ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:35:08.623607Z","caller":"traceutil/trace.go:171","msg":"trace[545625019] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"823.75329ms","start":"2026-04-02T22:35:07.799841Z","end":"2026-04-02T22:35:08.623594Z","steps":["trace[545625019] 'process raft request' (duration: 823.227247ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:08.623123Z","caller":"traceutil/trace.go:171","msg":"trace[1108558457] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"823.544435ms","start":"2026-04-02T22:35:07.799551Z","end":"2026-04-02T22:35:08.623096Z","steps":["trace[1108558457] 'process raft request' (duration: 823.455853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:08.623676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:07.799833Z","time spent":"823.809851ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:35:08.623668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:07.751436Z","time spent":"872.150122ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:35:08.623774Z","caller":"traceutil/trace.go:171","msg":"trace[1200816123] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"823.515824ms","start":"2026-04-02T22:35:07.800226Z","end":"2026-04-02T22:35:08.623742Z","steps":["trace[1200816123] 'process raft request' (duration: 823.122985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:08.623814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:07.799534Z","time spent":"824.146089ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:35:08.623935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:07.80022Z","time spent":"823.626806ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:35:08.624273Z","caller":"traceutil/trace.go:171","msg":"trace[1175557506] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"796.242415ms","start":"2026-04-02T22:35:07.828014Z","end":"2026-04-02T22:35:08.624257Z","steps":["trace[1175557506] 'process raft request' (duration: 795.446326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:08.624374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:07.827995Z","time spent":"796.337878ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:35:08.631145Z","caller":"traceutil/trace.go:171","msg":"trace[1050186056] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1906; }","duration":"342.674723ms","start":"2026-04-02T22:35:08.28846Z","end":"2026-04-02T22:35:08.631135Z","steps":["trace[1050186056] 'read index received' (duration: 312.290582ms)","trace[1050186056] 'applied index is now lower than readState.Index' (duration: 30.383311ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:35:08.631351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.903811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:35:08.631403Z","caller":"traceutil/trace.go:171","msg":"trace[1493908804] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1841; }","duration":"243.985263ms","start":"2026-04-02T22:35:08.3874Z","end":"2026-04-02T22:35:08.631385Z","steps":["trace[1493908804] 'agreement among raft nodes before linearized reading' (duration: 243.84358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:08.631476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.089123ms","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-02T22:35:08.632095Z","caller":"traceutil/trace.go:171","msg":"trace[744914783] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1841; }","duration":"340.80264ms","start":"2026-04-02T22:35:08.291274Z","end":"2026-04-02T22:35:08.632077Z","steps":["trace[744914783] 'agreement among raft nodes before linearized reading' (duration: 339.95439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:08.632147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:08.291254Z","time spent":"340.882872ms","remote":"127.0.0.1:47444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-02T22:35:08.631709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.197786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-02T22:35:08.632303Z","caller":"traceutil/trace.go:171","msg":"trace[2050312248] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49; range_end:; response_count:1; response_revision:1841; }","duration":"343.884502ms","start":"2026-04-02T22:35:08.288388Z","end":"2026-04-02T22:35:08.632273Z","steps":["trace[2050312248] 'agreement among raft nodes before linearized reading' (duration: 342.829357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:08.632371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:08.288359Z","time spent":"343.995955ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-123d45a4-0595-4990-a9aa-a2b2270f6e49\" "} {"level":"info","ts":"2026-04-02T22:35:13.983834Z","caller":"traceutil/trace.go:171","msg":"trace[210426839] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"141.36789ms","start":"2026-04-02T22:35:13.842366Z","end":"2026-04-02T22:35:13.983734Z","steps":["trace[210426839] 'process raft request' (duration: 141.237947ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:13.984695Z","caller":"traceutil/trace.go:171","msg":"trace[860979248] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"138.426392ms","start":"2026-04-02T22:35:13.846237Z","end":"2026-04-02T22:35:13.984663Z","steps":["trace[860979248] 'process raft request' (duration: 138.279468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:20.717295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.115804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:35:20.71735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.416919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-02T22:35:20.717377Z","caller":"traceutil/trace.go:171","msg":"trace[291332800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1940; }","duration":"527.246807ms","start":"2026-04-02T22:35:20.190112Z","end":"2026-04-02T22:35:20.717359Z","steps":["trace[291332800] 'range keys from in-memory index tree' (duration: 526.960971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:20.717417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:20.190087Z","time spent":"527.321889ms","remote":"127.0.0.1:44668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:35:20.717418Z","caller":"traceutil/trace.go:171","msg":"trace[1862405617] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1940; }","duration":"193.494411ms","start":"2026-04-02T22:35:20.523907Z","end":"2026-04-02T22:35:20.717401Z","steps":["trace[1862405617] 'range keys from in-memory index tree' (duration: 193.272406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:20.717523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.700733ms","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-02T22:35:20.717602Z","caller":"traceutil/trace.go:171","msg":"trace[1057292182] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1940; }","duration":"435.847056ms","start":"2026-04-02T22:35:20.281737Z","end":"2026-04-02T22:35:20.717584Z","steps":["trace[1057292182] 'count revisions from in-memory index tree' (duration: 435.562159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:20.717649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:20.281715Z","time spent":"435.923779ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:35:20.957362Z","caller":"traceutil/trace.go:171","msg":"trace[45730850] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"234.113973ms","start":"2026-04-02T22:35:20.723219Z","end":"2026-04-02T22:35:20.957333Z","steps":["trace[45730850] 'process raft request' (duration: 233.937279ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:21.216483Z","caller":"traceutil/trace.go:171","msg":"trace[291051607] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"493.21709ms","start":"2026-04-02T22:35:20.723231Z","end":"2026-04-02T22:35:21.216448Z","steps":["trace[291051607] 'process raft request' (duration: 431.567586ms)","trace[291051607] 'compare' (duration: 61.322066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:35:21.216713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:20.723207Z","time spent":"493.346483ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:35:21.601274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.064148ms","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-02T22:35:21.601361Z","caller":"traceutil/trace.go:171","msg":"trace[786415723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1945; }","duration":"110.274892ms","start":"2026-04-02T22:35:21.491066Z","end":"2026-04-02T22:35:21.601341Z","steps":["trace[786415723] 'range keys from in-memory index tree' (duration: 109.806232ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:25.176594Z","caller":"traceutil/trace.go:171","msg":"trace[954655746] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2047; }","duration":"417.978508ms","start":"2026-04-02T22:35:24.758592Z","end":"2026-04-02T22:35:25.17657Z","steps":["trace[954655746] 'read index received' (duration: 417.857445ms)","trace[954655746] 'applied index is now lower than readState.Index' (duration: 120.043µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:35:25.17673Z","caller":"traceutil/trace.go:171","msg":"trace[527671675] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"483.218656ms","start":"2026-04-02T22:35:24.6935Z","end":"2026-04-02T22:35:25.176719Z","steps":["trace[527671675] 'process raft request' (duration: 482.924689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:25.176839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:24.693469Z","time spent":"483.293608ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:35:25.176933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.680688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11985"} {"level":"info","ts":"2026-04-02T22:35:25.176995Z","caller":"traceutil/trace.go:171","msg":"trace[2037800974] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1973; }","duration":"229.789261ms","start":"2026-04-02T22:35:24.94719Z","end":"2026-04-02T22:35:25.176979Z","steps":["trace[2037800974] 'agreement among raft nodes before linearized reading' (duration: 229.632687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:25.177245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.624813ms","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-02T22:35:25.177326Z","caller":"traceutil/trace.go:171","msg":"trace[827747982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1973; }","duration":"418.763576ms","start":"2026-04-02T22:35:24.758542Z","end":"2026-04-02T22:35:25.177306Z","steps":["trace[827747982] 'agreement among raft nodes before linearized reading' (duration: 418.523321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:25.177371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:24.75852Z","time spent":"418.843089ms","remote":"127.0.0.1:47444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-02T22:35:57.613971Z","caller":"traceutil/trace.go:171","msg":"trace[1333009504] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2230; }","duration":"228.912361ms","start":"2026-04-02T22:35:57.385034Z","end":"2026-04-02T22:35:57.613946Z","steps":["trace[1333009504] 'read index received' (duration: 228.833649ms)","trace[1333009504] 'applied index is now lower than readState.Index' (duration: 77.592µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:35:57.614055Z","caller":"traceutil/trace.go:171","msg":"trace[604565151] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"242.786425ms","start":"2026-04-02T22:35:57.37124Z","end":"2026-04-02T22:35:57.614027Z","steps":["trace[604565151] 'process raft request' (duration: 242.544689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:57.614178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.124396ms","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-02T22:35:57.614224Z","caller":"traceutil/trace.go:171","msg":"trace[1480033678] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2150; }","duration":"229.209097ms","start":"2026-04-02T22:35:57.385Z","end":"2026-04-02T22:35:57.61421Z","steps":["trace[1480033678] 'agreement among raft nodes before linearized reading' (duration: 229.063144ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:57.657281Z","caller":"traceutil/trace.go:171","msg":"trace[1802305109] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"147.973445ms","start":"2026-04-02T22:35:57.509278Z","end":"2026-04-02T22:35:57.657252Z","steps":["trace[1802305109] 'process raft request' (duration: 147.178077ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:57.788629Z","caller":"traceutil/trace.go:171","msg":"trace[470727600] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"127.436424ms","start":"2026-04-02T22:35:57.66116Z","end":"2026-04-02T22:35:57.788596Z","steps":["trace[470727600] 'process raft request' (duration: 51.961457ms)","trace[470727600] 'compare' (duration: 75.221721ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:35:58.444586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.722741ms","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":"warn","ts":"2026-04-02T22:35:58.444625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.13732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:35:58.444681Z","caller":"traceutil/trace.go:171","msg":"trace[364591063] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2156; }","duration":"326.904515ms","start":"2026-04-02T22:35:58.117756Z","end":"2026-04-02T22:35:58.44466Z","steps":["trace[364591063] 'count revisions from in-memory index tree' (duration: 326.548908ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:58.444705Z","caller":"traceutil/trace.go:171","msg":"trace[1466604818] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2156; }","duration":"520.285324ms","start":"2026-04-02T22:35:57.9244Z","end":"2026-04-02T22:35:58.444685Z","steps":["trace[1466604818] 'count revisions from in-memory index tree' (duration: 520.045238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:58.444742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:58.117733Z","time spent":"326.996938ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:35:58.44475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:57.92438Z","time spent":"520.360175ms","remote":"127.0.0.1:47322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:35:58.690287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930024819,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:35:58.887486Z","caller":"traceutil/trace.go:171","msg":"trace[1410077195] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"738.965044ms","start":"2026-04-02T22:35:58.148489Z","end":"2026-04-02T22:35:58.887454Z","steps":["trace[1410077195] 'process raft request' (duration: 738.78481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:58.887674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:58.148463Z","time spent":"739.108247ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:35:59.645717Z","caller":"traceutil/trace.go:171","msg":"trace[579999826] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"1.493124674s","start":"2026-04-02T22:35:58.152562Z","end":"2026-04-02T22:35:59.645687Z","steps":["trace[579999826] 'process raft request' (duration: 1.492893399s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:59.645905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:58.152545Z","time spent":"1.493248728s","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:35:59.95851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.887987ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-02T22:35:59.95924Z","caller":"traceutil/trace.go:171","msg":"trace[662655889] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"410.224226ms","start":"2026-04-02T22:35:59.548992Z","end":"2026-04-02T22:35:59.959216Z","steps":["trace[662655889] 'process raft request' (duration: 410.133564ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:59.959283Z","caller":"traceutil/trace.go:171","msg":"trace[1632464937] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"1.296598393s","start":"2026-04-02T22:35:58.662653Z","end":"2026-04-02T22:35:59.959251Z","steps":["trace[1632464937] 'process raft request' (duration: 1.295955318s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:59.959295Z","caller":"traceutil/trace.go:171","msg":"trace[1597577764] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2237; }","duration":"1.769467526s","start":"2026-04-02T22:35:58.189801Z","end":"2026-04-02T22:35:59.959268Z","steps":["trace[1597577764] 'read index received' (duration: 697.594375ms)","trace[1597577764] 'applied index is now lower than readState.Index' (duration: 1.07187062s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:35:59.959419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769606819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:35:59.959437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:58.662628Z","time spent":"1.296724675s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:35:59.959456Z","caller":"traceutil/trace.go:171","msg":"trace[1261334454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2160; }","duration":"1.769685441s","start":"2026-04-02T22:35:58.189759Z","end":"2026-04-02T22:35:59.959444Z","steps":["trace[1261334454] 'agreement among raft nodes before linearized reading' (duration: 1.769603689s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:35:59.959485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:58.189747Z","time spent":"1.769728542s","remote":"127.0.0.1:44668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:35:59.959478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:59.548967Z","time spent":"410.330739ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:36:00.110572Z","caller":"traceutil/trace.go:171","msg":"trace[2086973657] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"151.19311ms","start":"2026-04-02T22:35:59.959355Z","end":"2026-04-02T22:36:00.110548Z","steps":["trace[2086973657] 'read index received' (duration: 106.266208ms)","trace[2086973657] 'applied index is now lower than readState.Index' (duration: 44.925592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:36:00.110754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.312653ms","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-02T22:36:00.110828Z","caller":"traceutil/trace.go:171","msg":"trace[1014931360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2161; }","duration":"271.433426ms","start":"2026-04-02T22:35:59.839377Z","end":"2026-04-02T22:36:00.110811Z","steps":["trace[1014931360] 'agreement among raft nodes before linearized reading' (duration: 271.239512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.110843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.199681ms","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-02T22:36:00.110877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.463015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:36:00.110883Z","caller":"traceutil/trace.go:171","msg":"trace[1945934542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2161; }","duration":"318.342955ms","start":"2026-04-02T22:35:59.792527Z","end":"2026-04-02T22:36:00.11087Z","steps":["trace[1945934542] 'agreement among raft nodes before linearized reading' (duration: 318.16248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.110901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182299867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:36:00.110912Z","caller":"traceutil/trace.go:171","msg":"trace[561942506] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"448.627986ms","start":"2026-04-02T22:35:59.662248Z","end":"2026-04-02T22:36:00.110876Z","steps":["trace[561942506] 'process raft request' (duration: 403.393736ms)","trace[561942506] 'compare' (duration: 44.783149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:36:00.111162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.089727ms","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-02T22:36:00.111278Z","caller":"traceutil/trace.go:171","msg":"trace[434322824] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2161; }","duration":"493.248791ms","start":"2026-04-02T22:35:59.618008Z","end":"2026-04-02T22:36:00.111257Z","steps":["trace[434322824] 'agreement among raft nodes before linearized reading' (duration: 492.908383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.11126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:59.662231Z","time spent":"448.914772ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:36:00.110939Z","caller":"traceutil/trace.go:171","msg":"trace[437184337] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2161; }","duration":"259.554738ms","start":"2026-04-02T22:35:59.85137Z","end":"2026-04-02T22:36:00.110925Z","steps":["trace[437184337] 'agreement among raft nodes before linearized reading' (duration: 259.423475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.111598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:59.617989Z","time spent":"493.341072ms","remote":"127.0.0.1:44934","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-02T22:36:00.110963Z","caller":"traceutil/trace.go:171","msg":"trace[1463393064] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2161; }","duration":"1.182412459s","start":"2026-04-02T22:35:58.928536Z","end":"2026-04-02T22:36:00.110949Z","steps":["trace[1463393064] 'agreement among raft nodes before linearized reading' (duration: 1.182307346s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.111862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:58.928516Z","time spent":"1.18332372s","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:36:00.110964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:59.792503Z","time spent":"318.445578ms","remote":"127.0.0.1:44934","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-02T22:36:00.110989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.927756ms","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-02T22:36:00.112149Z","caller":"traceutil/trace.go:171","msg":"trace[537140965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2161; }","duration":"258.141094ms","start":"2026-04-02T22:35:59.853992Z","end":"2026-04-02T22:36:00.112133Z","steps":["trace[537140965] 'agreement among raft nodes before linearized reading' (duration: 256.715391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.110755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.476804ms","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-02T22:36:00.112279Z","caller":"traceutil/trace.go:171","msg":"trace[439263236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2161; }","duration":"237.046721ms","start":"2026-04-02T22:35:59.875215Z","end":"2026-04-02T22:36:00.112262Z","steps":["trace[439263236] 'agreement among raft nodes before linearized reading' (duration: 235.424893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.111074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.770666ms","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-02T22:36:00.112355Z","caller":"traceutil/trace.go:171","msg":"trace[297622666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2161; }","duration":"414.076607ms","start":"2026-04-02T22:35:59.698265Z","end":"2026-04-02T22:36:00.112341Z","steps":["trace[297622666] 'agreement among raft nodes before linearized reading' (duration: 412.742866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.11108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.723129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:36:00.112406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:59.698252Z","time spent":"414.141208ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:36:00.112458Z","caller":"traceutil/trace.go:171","msg":"trace[1717978386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2161; }","duration":"152.131433ms","start":"2026-04-02T22:35:59.960308Z","end":"2026-04-02T22:36:00.112439Z","steps":["trace[1717978386] 'agreement among raft nodes before linearized reading' (duration: 150.72842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.111095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.968838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a2ab1abbc87dc7\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-02T22:36:00.112549Z","caller":"traceutil/trace.go:171","msg":"trace[48156331] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a2ab1abbc87dc7; range_end:; response_count:1; response_revision:2161; }","duration":"662.428322ms","start":"2026-04-02T22:35:59.450077Z","end":"2026-04-02T22:36:00.112506Z","steps":["trace[48156331] 'agreement among raft nodes before linearized reading' (duration: 660.911326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.111195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488494297s","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-02T22:36:00.112601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:59.450058Z","time spent":"662.526785ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a2ab1abbc87dc7\" "} {"level":"info","ts":"2026-04-02T22:36:00.112613Z","caller":"traceutil/trace.go:171","msg":"trace[500633301] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"1.489949691s","start":"2026-04-02T22:35:58.622647Z","end":"2026-04-02T22:36:00.112596Z","steps":["trace[500633301] 'agreement among raft nodes before linearized reading' (duration: 1.488410015s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:00.112685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:35:58.622628Z","time spent":"1.490041943s","remote":"127.0.0.1:47444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-02T22:36:33.876359Z","caller":"traceutil/trace.go:171","msg":"trace[319213587] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"118.695132ms","start":"2026-04-02T22:36:33.757642Z","end":"2026-04-02T22:36:33.876337Z","steps":["trace[319213587] 'process raft request' (duration: 118.585519ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:36:36.629113Z","caller":"traceutil/trace.go:171","msg":"trace[39280514] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"329.440325ms","start":"2026-04-02T22:36:36.29965Z","end":"2026-04-02T22:36:36.629091Z","steps":["trace[39280514] 'process raft request' (duration: 329.308282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:36.629297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:36:36.299636Z","time spent":"329.594428ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:36:36.696535Z","caller":"traceutil/trace.go:171","msg":"trace[1393548152] linearizableReadLoop","detail":"{readStateIndex:2636; appliedIndex:2634; }","duration":"391.631697ms","start":"2026-04-02T22:36:36.304892Z","end":"2026-04-02T22:36:36.696523Z","steps":["trace[1393548152] 'read index received' (duration: 324.071409ms)","trace[1393548152] 'applied index is now lower than readState.Index' (duration: 67.559538ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:36:36.696621Z","caller":"traceutil/trace.go:171","msg":"trace[1233270212] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"396.271906ms","start":"2026-04-02T22:36:36.300324Z","end":"2026-04-02T22:36:36.696595Z","steps":["trace[1233270212] 'process raft request' (duration: 395.15576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:36.696668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.777892ms","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-02T22:36:36.6967Z","caller":"traceutil/trace.go:171","msg":"trace[618615945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2539; }","duration":"391.829902ms","start":"2026-04-02T22:36:36.304859Z","end":"2026-04-02T22:36:36.696689Z","steps":["trace[618615945] 'agreement among raft nodes before linearized reading' (duration: 391.73827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:36.696755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:36:36.304848Z","time spent":"391.896294ms","remote":"127.0.0.1:44934","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-02T22:36:36.696748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:36:36.300312Z","time spent":"396.375718ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:36:36.696866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.133665ms","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-02T22:36:36.696931Z","caller":"traceutil/trace.go:171","msg":"trace[1482393839] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2539; }","duration":"352.226898ms","start":"2026-04-02T22:36:36.344687Z","end":"2026-04-02T22:36:36.696914Z","steps":["trace[1482393839] 'agreement among raft nodes before linearized reading' (duration: 352.076154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:36.696977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:36:36.344676Z","time spent":"352.289789ms","remote":"127.0.0.1:44874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-02T22:36:37.847443Z","caller":"traceutil/trace.go:171","msg":"trace[1083361569] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"972.183818ms","start":"2026-04-02T22:36:36.875235Z","end":"2026-04-02T22:36:37.847419Z","steps":["trace[1083361569] 'process raft request' (duration: 971.963823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:37.847607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:36:36.875209Z","time spent":"972.307661ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:36:37.848826Z","caller":"traceutil/trace.go:171","msg":"trace[1313255242] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"836.887708ms","start":"2026-04-02T22:36:37.011903Z","end":"2026-04-02T22:36:37.848791Z","steps":["trace[1313255242] 'process raft request' (duration: 836.619242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:37.84895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.804128ms","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":"warn","ts":"2026-04-02T22:36:37.848972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:36:37.011874Z","time spent":"837.020251ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:36:37.849003Z","caller":"traceutil/trace.go:171","msg":"trace[822122533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2542; }","duration":"250.890511ms","start":"2026-04-02T22:36:37.598096Z","end":"2026-04-02T22:36:37.848987Z","steps":["trace[822122533] 'agreement among raft nodes before linearized reading' (duration: 250.793718ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:36:37.848989Z","caller":"traceutil/trace.go:171","msg":"trace[281517701] linearizableReadLoop","detail":"{readStateIndex:2639; appliedIndex:2637; }","duration":"250.564762ms","start":"2026-04-02T22:36:37.598142Z","end":"2026-04-02T22:36:37.848707Z","steps":["trace[281517701] 'read index received' (duration: 249.221071ms)","trace[281517701] 'applied index is now lower than readState.Index' (duration: 1.342651ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:36:42.547898Z","caller":"traceutil/trace.go:171","msg":"trace[1411380903] linearizableReadLoop","detail":"{readStateIndex:2674; appliedIndex:2673; }","duration":"257.073425ms","start":"2026-04-02T22:36:42.290802Z","end":"2026-04-02T22:36:42.547875Z","steps":["trace[1411380903] 'read index received' (duration: 161.958023ms)","trace[1411380903] 'applied index is now lower than readState.Index' (duration: 95.114602ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:36:42.547997Z","caller":"traceutil/trace.go:171","msg":"trace[1634354833] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"259.541782ms","start":"2026-04-02T22:36:42.288447Z","end":"2026-04-02T22:36:42.547989Z","steps":["trace[1634354833] 'process raft request' (duration: 164.351069ms)","trace[1634354833] 'compare' (duration: 94.983498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:36:42.548201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.388292ms","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-02T22:36:42.548223Z","caller":"traceutil/trace.go:171","msg":"trace[1788563417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2576; }","duration":"257.463324ms","start":"2026-04-02T22:36:42.290752Z","end":"2026-04-02T22:36:42.548216Z","steps":["trace[1788563417] 'agreement among raft nodes before linearized reading' (duration: 257.349021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:42.548235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.356004ms","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-02T22:36:42.548324Z","caller":"traceutil/trace.go:171","msg":"trace[1182577693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2576; }","duration":"246.496348ms","start":"2026-04-02T22:36:42.301804Z","end":"2026-04-02T22:36:42.548301Z","steps":["trace[1182577693] 'agreement among raft nodes before linearized reading' (duration: 246.249022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:36:42.548412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.711195ms","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-02T22:36:42.548474Z","caller":"traceutil/trace.go:171","msg":"trace[280761585] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2576; }","duration":"202.799336ms","start":"2026-04-02T22:36:42.34566Z","end":"2026-04-02T22:36:42.548459Z","steps":["trace[280761585] 'agreement among raft nodes before linearized reading' (duration: 202.613292ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:04.867297Z","caller":"traceutil/trace.go:171","msg":"trace[2053745648] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"102.899233ms","start":"2026-04-02T22:37:04.764369Z","end":"2026-04-02T22:37:04.867268Z","steps":["trace[2053745648] 'process raft request' (duration: 69.944654ms)","trace[2053745648] 'compare' (duration: 32.783685ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:37:11.405151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537608930026198,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:37:11.546795Z","caller":"traceutil/trace.go:171","msg":"trace[519039344] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"653.061758ms","start":"2026-04-02T22:37:10.893689Z","end":"2026-04-02T22:37:11.54675Z","steps":["trace[519039344] 'process raft request' (duration: 652.902964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:11.546911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.652274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:37:11.546954Z","caller":"traceutil/trace.go:171","msg":"trace[960024859] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2745; }","duration":"642.710205ms","start":"2026-04-02T22:37:10.90423Z","end":"2026-04-02T22:37:11.546941Z","steps":["trace[960024859] 'agreement among raft nodes before linearized reading' (duration: 642.621723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:11.546975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:10.893663Z","time spent":"653.212842ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:37:11.546988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:10.904198Z","time spent":"642.780917ms","remote":"127.0.0.1:46276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:37:11.546812Z","caller":"traceutil/trace.go:171","msg":"trace[334756046] linearizableReadLoop","detail":"{readStateIndex:2850; appliedIndex:2850; }","duration":"642.556602ms","start":"2026-04-02T22:37:10.904235Z","end":"2026-04-02T22:37:11.546791Z","steps":["trace[334756046] 'read index received' (duration: 642.546602ms)","trace[334756046] 'applied index is now lower than readState.Index' (duration: 8.48µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:37:11.551656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.821088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:37:11.551709Z","caller":"traceutil/trace.go:171","msg":"trace[1256853332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2746; }","duration":"360.89501ms","start":"2026-04-02T22:37:11.1908Z","end":"2026-04-02T22:37:11.551695Z","steps":["trace[1256853332] 'agreement among raft nodes before linearized reading' (duration: 360.817268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:11.551706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.469329ms","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":"warn","ts":"2026-04-02T22:37:11.551741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:11.190788Z","time spent":"360.947451ms","remote":"127.0.0.1:44668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:37:11.551796Z","caller":"traceutil/trace.go:171","msg":"trace[833092422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2746; }","duration":"639.606333ms","start":"2026-04-02T22:37:10.912167Z","end":"2026-04-02T22:37:11.551774Z","steps":["trace[833092422] 'agreement among raft nodes before linearized reading' (duration: 639.387517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:11.55186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:10.912143Z","time spent":"639.704394ms","remote":"127.0.0.1:44934","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-02T22:37:11.551872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.401955ms","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-02T22:37:11.551944Z","caller":"traceutil/trace.go:171","msg":"trace[827552881] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2746; }","duration":"365.535368ms","start":"2026-04-02T22:37:11.186392Z","end":"2026-04-02T22:37:11.551927Z","steps":["trace[827552881] 'agreement among raft nodes before linearized reading' (duration: 365.400475ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:11.551959Z","caller":"traceutil/trace.go:171","msg":"trace[568017570] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"140.203596ms","start":"2026-04-02T22:37:11.411719Z","end":"2026-04-02T22:37:11.551923Z","steps":["trace[568017570] 'process raft request' (duration: 139.711115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:11.551992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.122267ms","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-02T22:37:11.551976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.06815ms","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-02T22:37:11.552058Z","caller":"traceutil/trace.go:171","msg":"trace[1497035590] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2746; }","duration":"635.209379ms","start":"2026-04-02T22:37:10.916831Z","end":"2026-04-02T22:37:11.55204Z","steps":["trace[1497035590] 'agreement among raft nodes before linearized reading' (duration: 635.027235ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:11.552067Z","caller":"traceutil/trace.go:171","msg":"trace[2136441011] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2746; }","duration":"363.218654ms","start":"2026-04-02T22:37:11.18883Z","end":"2026-04-02T22:37:11.552049Z","steps":["trace[2136441011] 'agreement among raft nodes before linearized reading' (duration: 363.05844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:11.552054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:11.186369Z","time spent":"365.650301ms","remote":"127.0.0.1:46342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:37:11.552117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:10.916819Z","time spent":"635.284371ms","remote":"127.0.0.1:44934","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-02T22:37:11.55212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:11.188809Z","time spent":"363.299195ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:37:12.059277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.87567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:37:12.059618Z","caller":"traceutil/trace.go:171","msg":"trace[1510078309] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"503.566054ms","start":"2026-04-02T22:37:11.556033Z","end":"2026-04-02T22:37:12.059599Z","steps":["trace[1510078309] 'process raft request' (duration: 503.42134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:12.059724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:11.556012Z","time spent":"503.673306ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:37:12.060338Z","caller":"traceutil/trace.go:171","msg":"trace[858162245] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"504.409283ms","start":"2026-04-02T22:37:11.555693Z","end":"2026-04-02T22:37:12.060103Z","steps":["trace[858162245] 'process raft request' (duration: 34.627162ms)","trace[858162245] 'compare' (duration: 468.646785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:37:12.060527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:11.555667Z","time spent":"504.755331ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:37:12.086013Z","caller":"traceutil/trace.go:171","msg":"trace[1237872464] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"104.656563ms","start":"2026-04-02T22:37:11.981336Z","end":"2026-04-02T22:37:12.085992Z","steps":["trace[1237872464] 'process raft request' (duration: 104.478839ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:12.648659Z","caller":"traceutil/trace.go:171","msg":"trace[786589617] linearizableReadLoop","detail":"{readStateIndex:2855; appliedIndex:2853; }","duration":"573.727448ms","start":"2026-04-02T22:37:12.074909Z","end":"2026-04-02T22:37:12.648636Z","steps":["trace[786589617] 'read index received' (duration: 10.953377ms)","trace[786589617] 'applied index is now lower than readState.Index' (duration: 562.773191ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:37:12.648703Z","caller":"traceutil/trace.go:171","msg":"trace[817493384] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"657.671095ms","start":"2026-04-02T22:37:11.991002Z","end":"2026-04-02T22:37:12.648673Z","steps":["trace[817493384] 'process raft request' (duration: 657.483841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:12.648842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:11.990976Z","time spent":"657.783848ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:37:12.648906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.944243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a2ab2cc18f5e8a\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-02T22:37:12.648985Z","caller":"traceutil/trace.go:171","msg":"trace[372739792] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a2ab2cc18f5e8a; range_end:; response_count:1; response_revision:2750; }","duration":"574.072836ms","start":"2026-04-02T22:37:12.074871Z","end":"2026-04-02T22:37:12.648943Z","steps":["trace[372739792] 'agreement among raft nodes before linearized reading' (duration: 573.886062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:12.649033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.074854Z","time spent":"574.168418ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":789,"request content":"key:\"/registry/events/openstack/valkey-node-0.18a2ab2cc18f5e8a\" "} {"level":"info","ts":"2026-04-02T22:37:12.697256Z","caller":"traceutil/trace.go:171","msg":"trace[946979115] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"295.780463ms","start":"2026-04-02T22:37:12.401382Z","end":"2026-04-02T22:37:12.697163Z","steps":["trace[946979115] 'process raft request' (duration: 295.67456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:12.697362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.850164ms","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-02T22:37:12.697343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.412386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:37:12.697464Z","caller":"traceutil/trace.go:171","msg":"trace[1813999748] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2751; }","duration":"350.951547ms","start":"2026-04-02T22:37:12.346458Z","end":"2026-04-02T22:37:12.697409Z","steps":["trace[1813999748] 'agreement among raft nodes before linearized reading' (duration: 350.740512ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:12.697452Z","caller":"traceutil/trace.go:171","msg":"trace[1031818507] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2751; }","duration":"124.58909ms","start":"2026-04-02T22:37:12.572836Z","end":"2026-04-02T22:37:12.697425Z","steps":["trace[1031818507] 'agreement among raft nodes before linearized reading' (duration: 124.352944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:12.697539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.346432Z","time spent":"351.068669ms","remote":"127.0.0.1:44874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-02T22:37:13.232445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.893624Z","time spent":"338.811162ms","remote":"127.0.0.1:36802","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T22:37:13.233283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.000379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:37:13.233621Z","caller":"traceutil/trace.go:171","msg":"trace[472388498] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"521.279569ms","start":"2026-04-02T22:37:12.712303Z","end":"2026-04-02T22:37:13.233583Z","steps":["trace[472388498] 'process raft request' (duration: 340.704116ms)","trace[472388498] 'compare' (duration: 179.660251ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:37:13.233639Z","caller":"traceutil/trace.go:171","msg":"trace[1141482502] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"520.993522ms","start":"2026-04-02T22:37:12.712616Z","end":"2026-04-02T22:37:13.23361Z","steps":["trace[1141482502] 'process raft request' (duration: 520.860349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:13.233778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.016353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a2ab2f2842e27f\" ","response":"range_response_count:1 size:768"} {"level":"info","ts":"2026-04-02T22:37:13.233818Z","caller":"traceutil/trace.go:171","msg":"trace[1366020375] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a2ab2f2842e27f; range_end:; response_count:1; response_revision:2754; }","duration":"521.076664ms","start":"2026-04-02T22:37:12.712731Z","end":"2026-04-02T22:37:13.233808Z","steps":["trace[1366020375] 'agreement among raft nodes before linearized reading' (duration: 520.948351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:13.233877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.712721Z","time spent":"521.148885ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":792,"request content":"key:\"/registry/events/openstack/valkey-node-0.18a2ab2f2842e27f\" "} {"level":"warn","ts":"2026-04-02T22:37:13.233882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.712603Z","time spent":"521.124705ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:37:13.233546Z","caller":"traceutil/trace.go:171","msg":"trace[1938892621] linearizableReadLoop","detail":"{readStateIndex:2859; appliedIndex:2857; }","duration":"520.768946ms","start":"2026-04-02T22:37:12.712762Z","end":"2026-04-02T22:37:13.233531Z","steps":["trace[1938892621] 'read index received' (duration: 340.216875ms)","trace[1938892621] 'applied index is now lower than readState.Index' (duration: 180.551201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:37:13.23429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.500715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:37:13.234337Z","caller":"traceutil/trace.go:171","msg":"trace[753497083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2754; }","duration":"190.566707ms","start":"2026-04-02T22:37:13.043761Z","end":"2026-04-02T22:37:13.234328Z","steps":["trace[753497083] 'agreement among raft nodes before linearized reading' (duration: 190.495565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:13.23441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.745041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:37:13.234427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.692205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-04-02T22:37:13.234478Z","caller":"traceutil/trace.go:171","msg":"trace[1806783142] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2754; }","duration":"228.876555ms","start":"2026-04-02T22:37:13.00558Z","end":"2026-04-02T22:37:13.234456Z","steps":["trace[1806783142] 'agreement among raft nodes before linearized reading' (duration: 228.768473ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:13.234483Z","caller":"traceutil/trace.go:171","msg":"trace[1999478263] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2754; }","duration":"520.764556ms","start":"2026-04-02T22:37:12.7137Z","end":"2026-04-02T22:37:13.234464Z","steps":["trace[1999478263] 'agreement among raft nodes before linearized reading' (duration: 520.550581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:13.234478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.584496ms","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-02T22:37:13.235301Z","caller":"traceutil/trace.go:171","msg":"trace[1399611212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2754; }","duration":"493.440966ms","start":"2026-04-02T22:37:12.741847Z","end":"2026-04-02T22:37:13.235288Z","steps":["trace[1399611212] 'agreement among raft nodes before linearized reading' (duration: 492.473684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:13.23528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.713692Z","time spent":"521.506253ms","remote":"127.0.0.1:45138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7486,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"warn","ts":"2026-04-02T22:37:13.235339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.741758Z","time spent":"493.568989ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:37:13.23476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.668537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-02T22:37:13.234684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:12.712289Z","time spent":"521.918193ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:37:13.23549Z","caller":"traceutil/trace.go:171","msg":"trace[1901560308] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:2754; }","duration":"237.714292ms","start":"2026-04-02T22:37:12.997715Z","end":"2026-04-02T22:37:13.235429Z","steps":["trace[1901560308] 'agreement among raft nodes before linearized reading' (duration: 236.557555ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:13.409517Z","caller":"traceutil/trace.go:171","msg":"trace[673911773] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"103.621889ms","start":"2026-04-02T22:37:13.305872Z","end":"2026-04-02T22:37:13.409494Z","steps":["trace[673911773] 'process raft request' (duration: 103.564867ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:13.409576Z","caller":"traceutil/trace.go:171","msg":"trace[1124106186] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"104.312095ms","start":"2026-04-02T22:37:13.305236Z","end":"2026-04-02T22:37:13.409548Z","steps":["trace[1124106186] 'process raft request' (duration: 29.402639ms)","trace[1124106186] 'compare' (duration: 74.66615ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:39:34.33477Z","caller":"traceutil/trace.go:171","msg":"trace[2096531529] linearizableReadLoop","detail":"{readStateIndex:3833; appliedIndex:3832; }","duration":"413.969183ms","start":"2026-04-02T22:39:33.920778Z","end":"2026-04-02T22:39:34.334747Z","steps":["trace[2096531529] 'read index received' (duration: 413.878801ms)","trace[2096531529] 'applied index is now lower than readState.Index' (duration: 89.262µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:39:34.334888Z","caller":"traceutil/trace.go:171","msg":"trace[248080764] transaction","detail":"{read_only:false; response_revision:3694; number_of_response:1; }","duration":"414.337131ms","start":"2026-04-02T22:39:33.92054Z","end":"2026-04-02T22:39:34.334877Z","steps":["trace[248080764] 'process raft request' (duration: 414.073135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:39:34.334996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:39:33.920504Z","time spent":"414.408043ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:39:34.335274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.490105ms","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-02T22:39:34.335356Z","caller":"traceutil/trace.go:171","msg":"trace[2031427162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3694; }","duration":"414.623908ms","start":"2026-04-02T22:39:33.920714Z","end":"2026-04-02T22:39:34.335338Z","steps":["trace[2031427162] 'agreement among raft nodes before linearized reading' (duration: 414.276399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:39:34.335435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:39:33.920681Z","time spent":"414.743171ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:39:34.519639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.557163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:39:34.519828Z","caller":"traceutil/trace.go:171","msg":"trace[141828000] transaction","detail":"{read_only:false; response_revision:3696; number_of_response:1; }","duration":"180.002258ms","start":"2026-04-02T22:39:34.339805Z","end":"2026-04-02T22:39:34.519807Z","steps":["trace[141828000] 'process raft request' (duration: 179.947987ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:39:34.520024Z","caller":"traceutil/trace.go:171","msg":"trace[629861476] linearizableReadLoop","detail":"{readStateIndex:3834; appliedIndex:3833; }","duration":"185.156899ms","start":"2026-04-02T22:39:34.334844Z","end":"2026-04-02T22:39:34.520001Z","steps":["trace[629861476] 'read index received' (duration: 59.223987ms)","trace[629861476] 'applied index is now lower than readState.Index' (duration: 125.930252ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:39:34.520029Z","caller":"traceutil/trace.go:171","msg":"trace[1830687787] transaction","detail":"{read_only:false; response_revision:3695; number_of_response:1; }","duration":"214.043795ms","start":"2026-04-02T22:39:34.305944Z","end":"2026-04-02T22:39:34.519988Z","steps":["trace[1830687787] 'process raft request' (duration: 88.041532ms)","trace[1830687787] 'compare' (duration: 125.42557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:39:34.520243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.685866ms","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-02T22:39:34.520256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.394639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:39:34.520278Z","caller":"traceutil/trace.go:171","msg":"trace[951070108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3696; }","duration":"489.751208ms","start":"2026-04-02T22:39:34.030515Z","end":"2026-04-02T22:39:34.520267Z","steps":["trace[951070108] 'agreement among raft nodes before linearized reading' (duration: 489.582364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:39:34.520317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:39:34.030503Z","time spent":"489.805719ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:39:34.520319Z","caller":"traceutil/trace.go:171","msg":"trace[48823346] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3696; }","duration":"204.461421ms","start":"2026-04-02T22:39:34.315841Z","end":"2026-04-02T22:39:34.520302Z","steps":["trace[48823346] 'agreement among raft nodes before linearized reading' (duration: 204.365648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:39:34.52032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.02052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:39:34.520403Z","caller":"traceutil/trace.go:171","msg":"trace[1262262689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3696; }","duration":"329.149524ms","start":"2026-04-02T22:39:34.191234Z","end":"2026-04-02T22:39:34.520384Z","steps":["trace[1262262689] 'agreement among raft nodes before linearized reading' (duration: 329.00288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:39:34.520447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:39:34.191211Z","time spent":"329.228826ms","remote":"127.0.0.1:44668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:39:34.520319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.312513ms","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-02T22:39:34.520523Z","caller":"traceutil/trace.go:171","msg":"trace[1783981567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3696; }","duration":"255.581839ms","start":"2026-04-02T22:39:34.264926Z","end":"2026-04-02T22:39:34.520508Z","steps":["trace[1783981567] 'agreement among raft nodes before linearized reading' (duration: 255.17945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:50.426221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.864381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:40:50.426366Z","caller":"traceutil/trace.go:171","msg":"trace[824070188] linearizableReadLoop","detail":"{readStateIndex:4817; appliedIndex:4816; }","duration":"252.223621ms","start":"2026-04-02T22:40:50.174122Z","end":"2026-04-02T22:40:50.426346Z","steps":["trace[824070188] 'read index received' (duration: 82.109014ms)","trace[824070188] 'applied index is now lower than readState.Index' (duration: 170.113027ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:50.426479Z","caller":"traceutil/trace.go:171","msg":"trace[1258913159] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"260.065164ms","start":"2026-04-02T22:40:50.166394Z","end":"2026-04-02T22:40:50.426459Z","steps":["trace[1258913159] 'process raft request' (duration: 89.835865ms)","trace[1258913159] 'compare' (duration: 169.443551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:40:50.427028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.903096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-04-02T22:40:50.427089Z","caller":"traceutil/trace.go:171","msg":"trace[542945205] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4642; }","duration":"253.02094ms","start":"2026-04-02T22:40:50.174051Z","end":"2026-04-02T22:40:50.427072Z","steps":["trace[542945205] 'agreement among raft nodes before linearized reading' (duration: 252.856846ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:52.711558Z","caller":"traceutil/trace.go:171","msg":"trace[768907855] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4892; }","duration":"314.664354ms","start":"2026-04-02T22:40:52.396865Z","end":"2026-04-02T22:40:52.711529Z","steps":["trace[768907855] 'read index received' (duration: 314.50016ms)","trace[768907855] 'applied index is now lower than readState.Index' (duration: 163.224µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:52.711642Z","caller":"traceutil/trace.go:171","msg":"trace[1212720929] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"315.924623ms","start":"2026-04-02T22:40:52.395664Z","end":"2026-04-02T22:40:52.711588Z","steps":["trace[1212720929] 'process raft request' (duration: 315.704508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:52.711801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.91048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-02T22:40:52.711913Z","caller":"traceutil/trace.go:171","msg":"trace[540324265] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4715; }","duration":"315.044083ms","start":"2026-04-02T22:40:52.396845Z","end":"2026-04-02T22:40:52.711889Z","steps":["trace[540324265] 'agreement among raft nodes before linearized reading' (duration: 314.768547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:52.711918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.08398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:73725"} {"level":"warn","ts":"2026-04-02T22:40:52.711923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:52.395647Z","time spent":"316.123049ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14481,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:40:52.711967Z","caller":"traceutil/trace.go:171","msg":"trace[1938649569] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4715; }","duration":"314.168812ms","start":"2026-04-02T22:40:52.397786Z","end":"2026-04-02T22:40:52.711954Z","steps":["trace[1938649569] 'agreement among raft nodes before linearized reading' (duration: 313.924526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:52.711968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:52.396839Z","time spent":"315.117345ms","remote":"127.0.0.1:45042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-04-02T22:40:52.712006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:52.39778Z","time spent":"314.215994ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":9,"response size":73749,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-04-02T22:40:53.123054Z","caller":"traceutil/trace.go:171","msg":"trace[1166383807] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"207.106244ms","start":"2026-04-02T22:40:52.915905Z","end":"2026-04-02T22:40:53.123011Z","steps":["trace[1166383807] 'process raft request' (duration: 207.050342ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:53.123048Z","caller":"traceutil/trace.go:171","msg":"trace[890776778] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"268.44941ms","start":"2026-04-02T22:40:52.854546Z","end":"2026-04-02T22:40:53.122995Z","steps":["trace[890776778] 'process raft request' (duration: 178.570594ms)","trace[890776778] 'compare' (duration: 89.701942ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:53.123088Z","caller":"traceutil/trace.go:171","msg":"trace[1722440392] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"247.649474ms","start":"2026-04-02T22:40:52.875365Z","end":"2026-04-02T22:40:53.123014Z","steps":["trace[1722440392] 'read index received' (duration: 157.769077ms)","trace[1722440392] 'applied index is now lower than readState.Index' (duration: 89.875977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:40:53.123481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.443709ms","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-02T22:40:53.123579Z","caller":"traceutil/trace.go:171","msg":"trace[2116633133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4721; }","duration":"247.563712ms","start":"2026-04-02T22:40:52.875993Z","end":"2026-04-02T22:40:53.123557Z","steps":["trace[2116633133] 'agreement among raft nodes before linearized reading' (duration: 247.295105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:53.123532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.120415ms","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-02T22:40:53.123693Z","caller":"traceutil/trace.go:171","msg":"trace[1433762265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4721; }","duration":"248.33656ms","start":"2026-04-02T22:40:52.875319Z","end":"2026-04-02T22:40:53.123656Z","steps":["trace[1433762265] 'agreement among raft nodes before linearized reading' (duration: 247.853549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:53.123599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.980411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T22:40:53.123869Z","caller":"traceutil/trace.go:171","msg":"trace[1743632768] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:4721; }","duration":"117.284819ms","start":"2026-04-02T22:40:53.006546Z","end":"2026-04-02T22:40:53.123831Z","steps":["trace[1743632768] 'agreement among raft nodes before linearized reading' (duration: 116.737486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:53.123926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.761299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"warn","ts":"2026-04-02T22:40:53.12394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.497196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-02T22:40:53.12401Z","caller":"traceutil/trace.go:171","msg":"trace[1520091593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4721; }","duration":"246.576469ms","start":"2026-04-02T22:40:52.877418Z","end":"2026-04-02T22:40:53.123994Z","steps":["trace[1520091593] 'agreement among raft nodes before linearized reading' (duration: 246.448346ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:53.123961Z","caller":"traceutil/trace.go:171","msg":"trace[822977189] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4721; }","duration":"175.855891ms","start":"2026-04-02T22:40:52.94809Z","end":"2026-04-02T22:40:53.123946Z","steps":["trace[822977189] 'agreement among raft nodes before linearized reading' (duration: 175.762609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:53.124345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.843051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196036"} {"level":"warn","ts":"2026-04-02T22:40:53.124386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.456838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:521826"} {"level":"info","ts":"2026-04-02T22:40:53.124484Z","caller":"traceutil/trace.go:171","msg":"trace[2040056418] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4721; }","duration":"197.579861ms","start":"2026-04-02T22:40:52.926881Z","end":"2026-04-02T22:40:53.124461Z","steps":["trace[2040056418] 'agreement among raft nodes before linearized reading' (duration: 197.005677ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:53.124412Z","caller":"traceutil/trace.go:171","msg":"trace[1859176393] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4721; }","duration":"181.941093ms","start":"2026-04-02T22:40:52.942452Z","end":"2026-04-02T22:40:53.124393Z","steps":["trace[1859176393] 'agreement among raft nodes before linearized reading' (duration: 181.016501ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:54.281746Z","caller":"traceutil/trace.go:171","msg":"trace[1799695722] linearizableReadLoop","detail":"{readStateIndex:4918; appliedIndex:4917; }","duration":"204.263547ms","start":"2026-04-02T22:40:54.077462Z","end":"2026-04-02T22:40:54.281725Z","steps":["trace[1799695722] 'read index received' (duration: 204.092733ms)","trace[1799695722] 'applied index is now lower than readState.Index' (duration: 169.944µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:54.281883Z","caller":"traceutil/trace.go:171","msg":"trace[871917281] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"236.777239ms","start":"2026-04-02T22:40:54.045095Z","end":"2026-04-02T22:40:54.281872Z","steps":["trace[871917281] 'process raft request' (duration: 236.501123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:54.282063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.588174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:40:54.282133Z","caller":"traceutil/trace.go:171","msg":"trace[128576952] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4739; }","duration":"204.714327ms","start":"2026-04-02T22:40:54.077404Z","end":"2026-04-02T22:40:54.282118Z","steps":["trace[128576952] 'agreement among raft nodes before linearized reading' (duration: 204.611645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:54.282463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.001445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-9j98p.18a2ab64f070f783\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-04-02T22:40:54.282596Z","caller":"traceutil/trace.go:171","msg":"trace[1335706953] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-9j98p.18a2ab64f070f783; range_end:; response_count:1; response_revision:4739; }","duration":"106.201018ms","start":"2026-04-02T22:40:54.176362Z","end":"2026-04-02T22:40:54.282563Z","steps":["trace[1335706953] 'agreement among raft nodes before linearized reading' (duration: 105.789869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:54.282697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.531803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-9j98p\" ","response":"range_response_count:1 size:9826"} {"level":"info","ts":"2026-04-02T22:40:54.282821Z","caller":"traceutil/trace.go:171","msg":"trace[2143215569] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-9j98p; range_end:; response_count:1; response_revision:4739; }","duration":"108.708528ms","start":"2026-04-02T22:40:54.174084Z","end":"2026-04-02T22:40:54.282793Z","steps":["trace[2143215569] 'agreement among raft nodes before linearized reading' (duration: 107.991531ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:54.419219Z","caller":"traceutil/trace.go:171","msg":"trace[430343662] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"128.073981ms","start":"2026-04-02T22:40:54.291123Z","end":"2026-04-02T22:40:54.419197Z","steps":["trace[430343662] 'process raft request' (duration: 127.863656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.634715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.24099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:40:57.634867Z","caller":"traceutil/trace.go:171","msg":"trace[1876604861] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"497.98049ms","start":"2026-04-02T22:40:57.136844Z","end":"2026-04-02T22:40:57.634824Z","steps":["trace[1876604861] 'process raft request' (duration: 318.457593ms)","trace[1876604861] 'compare' (duration: 178.435701ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:57.63492Z","caller":"traceutil/trace.go:171","msg":"trace[1320292816] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"166.40884ms","start":"2026-04-02T22:40:57.468494Z","end":"2026-04-02T22:40:57.634902Z","steps":["trace[1320292816] 'process raft request' (duration: 166.343808ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:57.635032Z","caller":"traceutil/trace.go:171","msg":"trace[459609920] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5039; }","duration":"487.459034ms","start":"2026-04-02T22:40:57.147528Z","end":"2026-04-02T22:40:57.634987Z","steps":["trace[459609920] 'read index received' (duration: 307.770453ms)","trace[459609920] 'applied index is now lower than readState.Index' (duration: 179.65993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:40:57.635182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.648218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4pd8j\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-02T22:40:57.635245Z","caller":"traceutil/trace.go:171","msg":"trace[433316920] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4pd8j; range_end:; response_count:1; response_revision:4860; }","duration":"487.76194ms","start":"2026-04-02T22:40:57.147469Z","end":"2026-04-02T22:40:57.635231Z","steps":["trace[433316920] 'agreement among raft nodes before linearized reading' (duration: 487.587106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.635285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.147448Z","time spent":"487.829732ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-4pd8j\" "} {"level":"warn","ts":"2026-04-02T22:40:57.635318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.136818Z","time spent":"498.121334ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:40:57.66612Z","caller":"traceutil/trace.go:171","msg":"trace[894943863] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"194.15945ms","start":"2026-04-02T22:40:57.471943Z","end":"2026-04-02T22:40:57.666103Z","steps":["trace[894943863] 'process raft request' (duration: 193.990016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.666228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.285131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:40:57.666309Z","caller":"traceutil/trace.go:171","msg":"trace[181385348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4861; }","duration":"476.398604ms","start":"2026-04-02T22:40:57.189891Z","end":"2026-04-02T22:40:57.66629Z","steps":["trace[181385348] 'agreement among raft nodes before linearized reading' (duration: 476.23339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.666355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.189879Z","time spent":"476.467095ms","remote":"127.0.0.1:44668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:40:57.666693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.052552ms","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-02T22:40:57.666687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.22043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719273"} {"level":"info","ts":"2026-04-02T22:40:57.666738Z","caller":"traceutil/trace.go:171","msg":"trace[1976520382] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4861; }","duration":"510.135994ms","start":"2026-04-02T22:40:57.156591Z","end":"2026-04-02T22:40:57.666727Z","steps":["trace[1976520382] 'agreement among raft nodes before linearized reading' (duration: 510.008031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.666772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.167615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T22:40:57.666748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.512205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8c28e12-327c-4e25-be72-7e34ce4fa4cc\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-02T22:40:57.666766Z","caller":"traceutil/trace.go:171","msg":"trace[2029188564] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4861; }","duration":"246.307632ms","start":"2026-04-02T22:40:57.420435Z","end":"2026-04-02T22:40:57.666743Z","steps":["trace[2029188564] 'agreement among raft nodes before linearized reading' (duration: 245.699287ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:57.666821Z","caller":"traceutil/trace.go:171","msg":"trace[719675633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4861; }","duration":"507.225446ms","start":"2026-04-02T22:40:57.159584Z","end":"2026-04-02T22:40:57.666809Z","steps":["trace[719675633] 'agreement among raft nodes before linearized reading' (duration: 507.136854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.66687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.159578Z","time spent":"507.281667ms","remote":"127.0.0.1:44934","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-02T22:40:57.666775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.15657Z","time spent":"510.197026ms","remote":"127.0.0.1:44934","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-02T22:40:57.666831Z","caller":"traceutil/trace.go:171","msg":"trace[1825484498] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8c28e12-327c-4e25-be72-7e34ce4fa4cc; range_end:; response_count:1; response_revision:4861; }","duration":"458.621747ms","start":"2026-04-02T22:40:57.208192Z","end":"2026-04-02T22:40:57.666814Z","steps":["trace[1825484498] 'agreement among raft nodes before linearized reading' (duration: 458.425543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.666916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.657458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8c28e12-327c-4e25-be72-7e34ce4fa4cc\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-04-02T22:40:57.666924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.572454ms","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":"warn","ts":"2026-04-02T22:40:57.666954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.208181Z","time spent":"458.7572ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8c28e12-327c-4e25-be72-7e34ce4fa4cc\" "} {"level":"warn","ts":"2026-04-02T22:40:57.666695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.119684ms","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-02T22:40:57.667008Z","caller":"traceutil/trace.go:171","msg":"trace[298286909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4861; }","duration":"507.454622ms","start":"2026-04-02T22:40:57.159547Z","end":"2026-04-02T22:40:57.667001Z","steps":["trace[298286909] 'agreement among raft nodes before linearized reading' (duration: 507.062022ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:57.667015Z","caller":"traceutil/trace.go:171","msg":"trace[856952594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4861; }","duration":"507.693087ms","start":"2026-04-02T22:40:57.159299Z","end":"2026-04-02T22:40:57.666992Z","steps":["trace[856952594] 'agreement among raft nodes before linearized reading' (duration: 507.504403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.667045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.15954Z","time spent":"507.497793ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:40:57.666739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.555464ms","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-02T22:40:57.667075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.159292Z","time spent":"507.768589ms","remote":"127.0.0.1:44934","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-02T22:40:57.667091Z","caller":"traceutil/trace.go:171","msg":"trace[400612860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4861; }","duration":"507.953844ms","start":"2026-04-02T22:40:57.159127Z","end":"2026-04-02T22:40:57.667081Z","steps":["trace[400612860] 'agreement among raft nodes before linearized reading' (duration: 507.412881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.667133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.159116Z","time spent":"508.006155ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T22:40:57.666955Z","caller":"traceutil/trace.go:171","msg":"trace[1193368565] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8c28e12-327c-4e25-be72-7e34ce4fa4cc; range_end:; response_count:1; response_revision:4861; }","duration":"324.686819ms","start":"2026-04-02T22:40:57.342249Z","end":"2026-04-02T22:40:57.666936Z","steps":["trace[1193368565] 'agreement among raft nodes before linearized reading' (duration: 324.604157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:57.667262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:57.342156Z","time spent":"325.051178ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8c28e12-327c-4e25-be72-7e34ce4fa4cc\" "} {"level":"warn","ts":"2026-04-02T22:41:07.658573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.612353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:10 size:380250"} {"level":"info","ts":"2026-04-02T22:41:07.658713Z","caller":"traceutil/trace.go:171","msg":"trace[1193012781] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:10; response_revision:5069; }","duration":"157.791398ms","start":"2026-04-02T22:41:07.500861Z","end":"2026-04-02T22:41:07.658653Z","steps":["trace[1193012781] 'range keys from in-memory index tree' (duration: 156.561909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:08.718488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.757297ms","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-02T22:41:08.718556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.814209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:41:08.718591Z","caller":"traceutil/trace.go:171","msg":"trace[183052794] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5086; }","duration":"230.943892ms","start":"2026-04-02T22:41:08.487623Z","end":"2026-04-02T22:41:08.718567Z","steps":["trace[183052794] 'count revisions from in-memory index tree' (duration: 230.598864ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:08.718624Z","caller":"traceutil/trace.go:171","msg":"trace[255250264] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5086; }","duration":"230.957993ms","start":"2026-04-02T22:41:08.487648Z","end":"2026-04-02T22:41:08.718606Z","steps":["trace[255250264] 'count revisions from in-memory index tree' (duration: 230.685796ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:09.456357Z","caller":"traceutil/trace.go:171","msg":"trace[1366575154] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"132.216849ms","start":"2026-04-02T22:41:09.32411Z","end":"2026-04-02T22:41:09.456327Z","steps":["trace[1366575154] 'process raft request' (duration: 74.321422ms)","trace[1366575154] 'compare' (duration: 57.797984ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:41:10.23807Z","caller":"traceutil/trace.go:171","msg":"trace[1364364099] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"776.731662ms","start":"2026-04-02T22:41:09.461305Z","end":"2026-04-02T22:41:10.238037Z","steps":["trace[1364364099] 'process raft request' (duration: 751.687765ms)","trace[1364364099] 'compare' (duration: 24.509874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:41:10.238241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.461276Z","time spent":"776.868294ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:41:10.241307Z","caller":"traceutil/trace.go:171","msg":"trace[1484981612] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"779.077177ms","start":"2026-04-02T22:41:09.462202Z","end":"2026-04-02T22:41:10.241279Z","steps":["trace[1484981612] 'process raft request' (duration: 778.928823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.241401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.462175Z","time spent":"779.194129ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:41:10.241703Z","caller":"traceutil/trace.go:171","msg":"trace[14739263] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"779.005334ms","start":"2026-04-02T22:41:09.462689Z","end":"2026-04-02T22:41:10.241694Z","steps":["trace[14739263] 'process raft request' (duration: 778.542574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.241783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.462667Z","time spent":"779.088697ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:41:10.24191Z","caller":"traceutil/trace.go:171","msg":"trace[543099277] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"778.866012ms","start":"2026-04-02T22:41:09.463013Z","end":"2026-04-02T22:41:10.241879Z","steps":["trace[543099277] 'process raft request' (duration: 778.511664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.242061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.463Z","time spent":"778.978454ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:41:10.242189Z","caller":"traceutil/trace.go:171","msg":"trace[946842615] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"701.341465ms","start":"2026-04-02T22:41:09.540807Z","end":"2026-04-02T22:41:10.242148Z","steps":["trace[946842615] 'process raft request' (duration: 700.867384ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:10.242407Z","caller":"traceutil/trace.go:171","msg":"trace[1565703398] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"523.934627ms","start":"2026-04-02T22:41:09.718463Z","end":"2026-04-02T22:41:10.242397Z","steps":["trace[1565703398] 'process raft request' (duration: 523.669741ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:10.242462Z","caller":"traceutil/trace.go:171","msg":"trace[1500731021] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"528.944325ms","start":"2026-04-02T22:41:09.713509Z","end":"2026-04-02T22:41:10.242453Z","steps":["trace[1500731021] 'process raft request' (duration: 528.543996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.242524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.713478Z","time spent":"529.016336ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:41:10.242535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.718452Z","time spent":"524.056921ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:41:10.242405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.540789Z","time spent":"701.501648ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:41:10.242694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.887861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4pd8j\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-02T22:41:10.242742Z","caller":"traceutil/trace.go:171","msg":"trace[1558049343] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4pd8j; range_end:; response_count:1; response_revision:5097; }","duration":"498.969703ms","start":"2026-04-02T22:41:09.743762Z","end":"2026-04-02T22:41:10.242732Z","steps":["trace[1558049343] 'agreement among raft nodes before linearized reading' (duration: 498.808639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.24278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.74375Z","time spent":"499.022914ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-4pd8j\" "} {"level":"info","ts":"2026-04-02T22:41:10.242436Z","caller":"traceutil/trace.go:171","msg":"trace[517278132] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"520.403094ms","start":"2026-04-02T22:41:09.722017Z","end":"2026-04-02T22:41:10.242421Z","steps":["trace[517278132] 'process raft request' (duration: 520.168319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.242875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.721984Z","time spent":"520.866876ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:41:10.242281Z","caller":"traceutil/trace.go:171","msg":"trace[1604498455] linearizableReadLoop","detail":"{readStateIndex:5286; appliedIndex:5278; }","duration":"498.435669ms","start":"2026-04-02T22:41:09.743808Z","end":"2026-04-02T22:41:10.242243Z","steps":["trace[1604498455] 'read index received' (duration: 469.205524ms)","trace[1604498455] 'applied index is now lower than readState.Index' (duration: 29.228635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:41:10.243838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.27974ms","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-02T22:41:10.243905Z","caller":"traceutil/trace.go:171","msg":"trace[126557716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5097; }","duration":"441.345632ms","start":"2026-04-02T22:41:09.802549Z","end":"2026-04-02T22:41:10.243894Z","steps":["trace[126557716] 'agreement among raft nodes before linearized reading' (duration: 441.148697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.243942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.802521Z","time spent":"441.414754ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:41:10.243982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.587822ms","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-02T22:41:10.244047Z","caller":"traceutil/trace.go:171","msg":"trace[734211194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5097; }","duration":"448.664923ms","start":"2026-04-02T22:41:09.795365Z","end":"2026-04-02T22:41:10.24403Z","steps":["trace[734211194] 'agreement among raft nodes before linearized reading' (duration: 448.509029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.2441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.795356Z","time spent":"448.734215ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T22:41:10.244114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.717177ms","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-02T22:41:10.244192Z","caller":"traceutil/trace.go:171","msg":"trace[945739849] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5097; }","duration":"449.83675ms","start":"2026-04-02T22:41:09.794337Z","end":"2026-04-02T22:41:10.244173Z","steps":["trace[945739849] 'agreement among raft nodes before linearized reading' (duration: 449.606175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.244236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.794311Z","time spent":"449.916963ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T22:41:10.244199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.238889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:41:10.244324Z","caller":"traceutil/trace.go:171","msg":"trace[1784438691] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5097; }","duration":"336.412663ms","start":"2026-04-02T22:41:09.907893Z","end":"2026-04-02T22:41:10.244306Z","steps":["trace[1784438691] 'agreement among raft nodes before linearized reading' (duration: 336.257889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.244399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.907871Z","time spent":"336.518655ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:41:10.244352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.743252ms","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-02T22:41:10.244453Z","caller":"traceutil/trace.go:171","msg":"trace[624521583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5097; }","duration":"453.844445ms","start":"2026-04-02T22:41:09.790596Z","end":"2026-04-02T22:41:10.24444Z","steps":["trace[624521583] 'agreement among raft nodes before linearized reading' (duration: 453.676511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.244531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.790552Z","time spent":"453.968618ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T22:41:10.243837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.473544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-02T22:41:10.244647Z","caller":"traceutil/trace.go:171","msg":"trace[157115811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5097; }","duration":"500.310614ms","start":"2026-04-02T22:41:09.744302Z","end":"2026-04-02T22:41:10.244613Z","steps":["trace[157115811] 'agreement among raft nodes before linearized reading' (duration: 499.360342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:10.244828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:09.744291Z","time spent":"500.418966ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:41:11.689937Z","caller":"traceutil/trace.go:171","msg":"trace[2141148383] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"316.327563ms","start":"2026-04-02T22:41:11.373587Z","end":"2026-04-02T22:41:11.689914Z","steps":["trace[2141148383] 'process raft request' (duration: 316.171969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:11.690092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:41:11.373568Z","time spent":"316.440576ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5957,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:41:14.382036Z","caller":"traceutil/trace.go:171","msg":"trace[2000903004] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"128.610204ms","start":"2026-04-02T22:41:14.253395Z","end":"2026-04-02T22:41:14.382005Z","steps":["trace[2000903004] 'process raft request' (duration: 126.186097ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:14.383291Z","caller":"traceutil/trace.go:171","msg":"trace[833624149] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"128.068151ms","start":"2026-04-02T22:41:14.255197Z","end":"2026-04-02T22:41:14.383265Z","steps":["trace[833624149] 'process raft request' (duration: 127.883967ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:14.383492Z","caller":"traceutil/trace.go:171","msg":"trace[1085217537] linearizableReadLoop","detail":"{readStateIndex:5339; appliedIndex:5336; }","duration":"121.680082ms","start":"2026-04-02T22:41:14.261785Z","end":"2026-04-02T22:41:14.383465Z","steps":["trace[1085217537] 'read index received' (duration: 117.75702ms)","trace[1085217537] 'applied index is now lower than readState.Index' (duration: 3.921572ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:41:14.383568Z","caller":"traceutil/trace.go:171","msg":"trace[1059782470] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"128.096662ms","start":"2026-04-02T22:41:14.255453Z","end":"2026-04-02T22:41:14.38355Z","steps":["trace[1059782470] 'process raft request' (duration: 127.763624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:14.383749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.948418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4pd8j\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-02T22:41:14.383786Z","caller":"traceutil/trace.go:171","msg":"trace[1750229320] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4pd8j; range_end:; response_count:1; response_revision:5148; }","duration":"122.0384ms","start":"2026-04-02T22:41:14.261736Z","end":"2026-04-02T22:41:14.383774Z","steps":["trace[1750229320] 'agreement among raft nodes before linearized reading' (duration: 121.875746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:14.383778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.240653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T22:41:14.383856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.009725ms","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-02T22:41:14.383891Z","caller":"traceutil/trace.go:171","msg":"trace[552947450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5148; }","duration":"113.580052ms","start":"2026-04-02T22:41:14.270292Z","end":"2026-04-02T22:41:14.383872Z","steps":["trace[552947450] 'agreement among raft nodes before linearized reading' (duration: 113.364357ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:14.383901Z","caller":"traceutil/trace.go:171","msg":"trace[158669728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5148; }","duration":"112.086577ms","start":"2026-04-02T22:41:14.271802Z","end":"2026-04-02T22:41:14.383888Z","steps":["trace[158669728] 'agreement among raft nodes before linearized reading' (duration: 111.893562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:14.383928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.416584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:4 size:218834"} {"level":"info","ts":"2026-04-02T22:41:14.383991Z","caller":"traceutil/trace.go:171","msg":"trace[788727611] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:4; response_revision:5148; }","duration":"106.506046ms","start":"2026-04-02T22:41:14.277469Z","end":"2026-04-02T22:41:14.383975Z","steps":["trace[788727611] 'agreement among raft nodes before linearized reading' (duration: 106.228349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:14.384035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.135141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:41:14.384069Z","caller":"traceutil/trace.go:171","msg":"trace[1252089798] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5148; }","duration":"107.191873ms","start":"2026-04-02T22:41:14.276866Z","end":"2026-04-02T22:41:14.384058Z","steps":["trace[1252089798] 'agreement among raft nodes before linearized reading' (duration: 107.133211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:14.384115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.09805ms","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-02T22:41:14.384161Z","caller":"traceutil/trace.go:171","msg":"trace[1819750600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5148; }","duration":"113.182673ms","start":"2026-04-02T22:41:14.270966Z","end":"2026-04-02T22:41:14.384149Z","steps":["trace[1819750600] 'agreement among raft nodes before linearized reading' (duration: 113.004439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:14.384159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.547571ms","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-02T22:41:14.384204Z","caller":"traceutil/trace.go:171","msg":"trace[1125087343] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5148; }","duration":"113.630683ms","start":"2026-04-02T22:41:14.270556Z","end":"2026-04-02T22:41:14.384187Z","steps":["trace[1125087343] 'agreement among raft nodes before linearized reading' (duration: 113.52179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:14.384296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.223133ms","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-02T22:41:14.384332Z","caller":"traceutil/trace.go:171","msg":"trace[1900394431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5148; }","duration":"113.259404ms","start":"2026-04-02T22:41:14.271062Z","end":"2026-04-02T22:41:14.384321Z","steps":["trace[1900394431] 'agreement among raft nodes before linearized reading' (duration: 113.157871ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:46.636814Z","caller":"traceutil/trace.go:171","msg":"trace[776260428] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"127.141585ms","start":"2026-04-02T22:41:46.509651Z","end":"2026-04-02T22:41:46.636792Z","steps":["trace[776260428] 'process raft request' (duration: 127.005451ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:46.690742Z","caller":"traceutil/trace.go:171","msg":"trace[799181321] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"180.264298ms","start":"2026-04-02T22:41:46.510454Z","end":"2026-04-02T22:41:46.690718Z","steps":["trace[799181321] 'process raft request' (duration: 180.083443ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:46.690922Z","caller":"traceutil/trace.go:171","msg":"trace[1263025095] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"179.50858ms","start":"2026-04-02T22:41:46.511403Z","end":"2026-04-02T22:41:46.690911Z","steps":["trace[1263025095] 'process raft request' (duration: 179.278355ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:46.690929Z","caller":"traceutil/trace.go:171","msg":"trace[1332727118] linearizableReadLoop","detail":"{readStateIndex:5634; appliedIndex:5631; }","duration":"167.644303ms","start":"2026-04-02T22:41:46.523256Z","end":"2026-04-02T22:41:46.6909Z","steps":["trace[1332727118] 'read index received' (duration: 113.415554ms)","trace[1332727118] 'applied index is now lower than readState.Index' (duration: 54.226729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:41:46.691178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.053279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-02T22:41:46.691182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.907579ms","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-02T22:41:46.691224Z","caller":"traceutil/trace.go:171","msg":"trace[1448676189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5435; }","duration":"167.994171ms","start":"2026-04-02T22:41:46.523221Z","end":"2026-04-02T22:41:46.691215Z","steps":["trace[1448676189] 'agreement among raft nodes before linearized reading' (duration: 167.804757ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:46.691221Z","caller":"traceutil/trace.go:171","msg":"trace[1317581751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5435; }","duration":"164.13507ms","start":"2026-04-02T22:41:46.527075Z","end":"2026-04-02T22:41:46.69121Z","steps":["trace[1317581751] 'agreement among raft nodes before linearized reading' (duration: 163.980496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:46.691405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.881987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-02T22:41:46.691421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.639205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/secretgen-controller/\" range_end:\"/registry/cronjobs/secretgen-controller0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:41:46.691438Z","caller":"traceutil/trace.go:171","msg":"trace[1125897903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5435; }","duration":"158.929078ms","start":"2026-04-02T22:41:46.5325Z","end":"2026-04-02T22:41:46.691429Z","steps":["trace[1125897903] 'agreement among raft nodes before linearized reading' (duration: 158.844156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:46.691475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.509326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/secretgen-controller/\" range_end:\"/registry/configmaps/secretgen-controller0\" limit:500 ","response":"range_response_count:1 size:1764"} {"level":"info","ts":"2026-04-02T22:41:46.691487Z","caller":"traceutil/trace.go:171","msg":"trace[1584632987] range","detail":"{range_begin:/registry/cronjobs/secretgen-controller/; range_end:/registry/cronjobs/secretgen-controller0; response_count:0; response_revision:5435; }","duration":"106.737367ms","start":"2026-04-02T22:41:46.584729Z","end":"2026-04-02T22:41:46.691466Z","steps":["trace[1584632987] 'agreement among raft nodes before linearized reading' (duration: 106.618774ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:41:46.69151Z","caller":"traceutil/trace.go:171","msg":"trace[1236718294] range","detail":"{range_begin:/registry/configmaps/secretgen-controller/; range_end:/registry/configmaps/secretgen-controller0; response_count:1; response_revision:5435; }","duration":"110.591568ms","start":"2026-04-02T22:41:46.580906Z","end":"2026-04-02T22:41:46.691498Z","steps":["trace[1236718294] 'agreement among raft nodes before linearized reading' (duration: 110.508006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:46.691488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.828016ms","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-02T22:41:46.691558Z","caller":"traceutil/trace.go:171","msg":"trace[2055809207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5435; }","duration":"158.918568ms","start":"2026-04-02T22:41:46.532625Z","end":"2026-04-02T22:41:46.691543Z","steps":["trace[2055809207] 'agreement among raft nodes before linearized reading' (duration: 158.718253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:46.691606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.434278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-04-02T22:41:46.691685Z","caller":"traceutil/trace.go:171","msg":"trace[695310089] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5435; }","duration":"135.527011ms","start":"2026-04-02T22:41:46.556137Z","end":"2026-04-02T22:41:46.691664Z","steps":["trace[695310089] 'agreement among raft nodes before linearized reading' (duration: 135.273215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:46.691827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.05641ms","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-02T22:41:46.691932Z","caller":"traceutil/trace.go:171","msg":"trace[1170060009] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5435; }","duration":"126.679994ms","start":"2026-04-02T22:41:46.565227Z","end":"2026-04-02T22:41:46.691907Z","steps":["trace[1170060009] 'agreement among raft nodes before linearized reading' (duration: 125.913886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:41:46.692219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.322771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4pd8j\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-02T22:41:46.692271Z","caller":"traceutil/trace.go:171","msg":"trace[596211629] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4pd8j; range_end:; response_count:1; response_revision:5435; }","duration":"154.391492ms","start":"2026-04-02T22:41:46.537866Z","end":"2026-04-02T22:41:46.692258Z","steps":["trace[596211629] 'agreement among raft nodes before linearized reading' (duration: 154.239309ms)"],"step_count":1}