{"level":"warn","ts":"2026-06-09T22:17:13.550181Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T22:17:13.550275Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.186: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.19.213.186:2380","--initial-cluster=instance=https://199.19.213.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.186: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-06-09T22:17:13.550383Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T22:17:13.550397Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-06-09T22:17:13.550412Z","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-06-09T22:17:13.551003Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"]} {"level":"info","ts":"2026-06-09T22:17:13.551071Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.186: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-06-09T22:17:13.555055Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.78423ms"} {"level":"info","ts":"2026-06-09T22:17:13.561265Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22478416d949d47d","cluster-id":"568bb29601528c07"} {"level":"info","ts":"2026-06-09T22:17:13.561339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T22:17:13.561423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 0"} {"level":"info","ts":"2026-06-09T22:17:13.561453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22478416d949d47d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T22:17:13.561462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 1"} {"level":"info","ts":"2026-06-09T22:17:13.561683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"warn","ts":"2026-06-09T22:17:13.569778Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T22:17:13.571149Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T22:17:13.573737Z","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-06-09T22:17:13.576127Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22478416d949d47d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T22:17:13.576255Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22478416d949d47d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T22:17:13.57631Z","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-06-09T22:17:13.576338Z","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-06-09T22:17:13.576347Z","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-06-09T22:17:13.579509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"info","ts":"2026-06-09T22:17:13.579804Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","added-peer-id":"22478416d949d47d","added-peer-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-06-09T22:17:13.581078Z","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-06-09T22:17:13.581296Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22478416d949d47d","initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T22:17:13.581335Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T22:17:13.581344Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-06-09T22:17:13.581492Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-06-09T22:17:13.962001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T22:17:13.962113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T22:17:13.962143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgPreVoteResp from 22478416d949d47d at term 1"} {"level":"info","ts":"2026-06-09T22:17:13.962166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became candidate at term 2"} {"level":"info","ts":"2026-06-09T22:17:13.96218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgVoteResp from 22478416d949d47d at term 2"} {"level":"info","ts":"2026-06-09T22:17:13.962197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became leader at term 2"} {"level":"info","ts":"2026-06-09T22:17:13.962218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22478416d949d47d elected leader 22478416d949d47d at term 2"} {"level":"info","ts":"2026-06-09T22:17:13.96374Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22478416d949d47d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.186:2379]}","request-path":"/0/members/22478416d949d47d/attributes","cluster-id":"568bb29601528c07","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T22:17:13.96391Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:17:13.963921Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:17:13.964103Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:17:13.964997Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:17:13.965143Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:17:13.965176Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:17:13.966489Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T22:17:13.96655Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T22:17:13.967661Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T22:17:13.967888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.186:2379"} {"level":"info","ts":"2026-06-09T22:17:29.604323Z","caller":"traceutil/trace.go:171","msg":"trace[1465547515] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"280.645125ms","start":"2026-06-09T22:17:29.323655Z","end":"2026-06-09T22:17:29.6043Z","steps":["trace[1465547515] 'process raft request' (duration: 280.462535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:30.429143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.681782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-09T22:17:30.429363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.645027ms","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-06-09T22:17:30.429642Z","caller":"traceutil/trace.go:171","msg":"trace[387717575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"425.938705ms","start":"2026-06-09T22:17:30.003672Z","end":"2026-06-09T22:17:30.429611Z","steps":["trace[387717575] 'range keys from in-memory index tree' (duration: 425.578743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:30.42972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:30.003658Z","time spent":"426.034662ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T22:17:30.429776Z","caller":"traceutil/trace.go:171","msg":"trace[2099130795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"201.843349ms","start":"2026-06-09T22:17:30.227688Z","end":"2026-06-09T22:17:30.429531Z","steps":["trace[2099130795] 'range keys from in-memory index tree' (duration: 199.576976ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:31.23327Z","caller":"traceutil/trace.go:171","msg":"trace[45893541] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"798.719861ms","start":"2026-06-09T22:17:30.434526Z","end":"2026-06-09T22:17:31.233246Z","steps":["trace[45893541] 'process raft request' (duration: 798.432894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:31.238054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:30.434509Z","time spent":"798.828148ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:17:31.570065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.571322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:17:31.570177Z","caller":"traceutil/trace.go:171","msg":"trace[1698893118] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"741.703902ms","start":"2026-06-09T22:17:30.828457Z","end":"2026-06-09T22:17:31.57016Z","steps":["trace[1698893118] 'read index received' (duration: 405.045184ms)","trace[1698893118] 'applied index is now lower than readState.Index' (duration: 336.657698ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:17:31.570231Z","caller":"traceutil/trace.go:171","msg":"trace[519977066] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.135564919s","start":"2026-06-09T22:17:30.434625Z","end":"2026-06-09T22:17:31.57019Z","steps":["trace[519977066] 'process raft request' (duration: 1.017632655s)","trace[519977066] 'compare' (duration: 117.415893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:17:31.570388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.265714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-06-09T22:17:31.570423Z","caller":"traceutil/trace.go:171","msg":"trace[1684661488] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:411; }","duration":"234.330628ms","start":"2026-06-09T22:17:31.336078Z","end":"2026-06-09T22:17:31.570409Z","steps":["trace[1684661488] 'agreement among raft nodes before linearized reading' (duration: 234.282555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:31.570487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:30.434607Z","time spent":"1.135798093s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:17:31.570286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.822289ms","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-06-09T22:17:31.570528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.597426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:17:31.570603Z","caller":"traceutil/trace.go:171","msg":"trace[527005085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"742.123237ms","start":"2026-06-09T22:17:30.828431Z","end":"2026-06-09T22:17:31.570554Z","steps":["trace[527005085] 'agreement among raft nodes before linearized reading' (duration: 741.814009ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:31.570697Z","caller":"traceutil/trace.go:171","msg":"trace[26739425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"728.850322ms","start":"2026-06-09T22:17:30.841821Z","end":"2026-06-09T22:17:31.570671Z","steps":["trace[26739425] 'agreement among raft nodes before linearized reading' (duration: 728.545764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:31.570745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:30.828417Z","time spent":"742.290197ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:17:31.571091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:30.841706Z","time spent":"729.076234ms","remote":"127.0.0.1:60820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T22:17:31.690937Z","caller":"traceutil/trace.go:171","msg":"trace[1630879842] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"111.167853ms","start":"2026-06-09T22:17:31.579202Z","end":"2026-06-09T22:17:31.690369Z","steps":["trace[1630879842] 'process raft request' (duration: 110.724266ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:53.252651Z","caller":"traceutil/trace.go:171","msg":"trace[1265671390] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"383.728514ms","start":"2026-06-09T22:17:52.868886Z","end":"2026-06-09T22:17:53.252615Z","steps":["trace[1265671390] 'process raft request' (duration: 381.40076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:53.252807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:52.868856Z","time spent":"383.876092ms","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:17:53.252976Z","caller":"traceutil/trace.go:171","msg":"trace[126395710] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"372.360173ms","start":"2026-06-09T22:17:52.880582Z","end":"2026-06-09T22:17:53.252942Z","steps":["trace[126395710] 'process raft request' (duration: 371.929807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:53.25345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:52.88056Z","time spent":"372.818192ms","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T22:17:53.259761Z","caller":"traceutil/trace.go:171","msg":"trace[1883623949] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"323.270517ms","start":"2026-06-09T22:17:52.936473Z","end":"2026-06-09T22:17:53.259744Z","steps":["trace[1883623949] 'process raft request' (duration: 323.144289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:53.259866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:52.936444Z","time spent":"323.364972ms","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:17:59.224873Z","caller":"traceutil/trace.go:171","msg":"trace[262016209] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"109.023215ms","start":"2026-06-09T22:17:59.115799Z","end":"2026-06-09T22:17:59.224822Z","steps":["trace[262016209] 'process raft request' (duration: 103.765317ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:59.962697Z","caller":"traceutil/trace.go:171","msg":"trace[1688197601] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"243.701282ms","start":"2026-06-09T22:17:59.718973Z","end":"2026-06-09T22:17:59.962674Z","steps":["trace[1688197601] 'read index received' (duration: 242.760944ms)","trace[1688197601] 'applied index is now lower than readState.Index' (duration: 939.088µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:17:59.962717Z","caller":"traceutil/trace.go:171","msg":"trace[480365484] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"261.359921ms","start":"2026-06-09T22:17:59.701341Z","end":"2026-06-09T22:17:59.962701Z","steps":["trace[480365484] 'process raft request' (duration: 260.716732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:59.962939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.730605ms","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-06-09T22:17:59.96298Z","caller":"traceutil/trace.go:171","msg":"trace[1705507943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"240.794943ms","start":"2026-06-09T22:17:59.722171Z","end":"2026-06-09T22:17:59.962966Z","steps":["trace[1705507943] 'agreement among raft nodes before linearized reading' (duration: 240.653008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:59.962997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.003723ms","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-06-09T22:17:59.963077Z","caller":"traceutil/trace.go:171","msg":"trace[233024499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"244.145277ms","start":"2026-06-09T22:17:59.718911Z","end":"2026-06-09T22:17:59.963056Z","steps":["trace[233024499] 'agreement among raft nodes before linearized reading' (duration: 243.892936ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:00.357735Z","caller":"traceutil/trace.go:171","msg":"trace[919256080] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"389.136247ms","start":"2026-06-09T22:17:59.968566Z","end":"2026-06-09T22:18:00.357702Z","steps":["trace[919256080] 'process raft request' (duration: 388.936552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:00.357922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:59.968558Z","time spent":"389.277922ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:18:00.357926Z","caller":"traceutil/trace.go:171","msg":"trace[1065462554] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"382.572425ms","start":"2026-06-09T22:17:59.975279Z","end":"2026-06-09T22:18:00.357852Z","steps":["trace[1065462554] 'process raft request' (duration: 382.488947ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:00.358138Z","caller":"traceutil/trace.go:171","msg":"trace[1672370566] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"389.479655ms","start":"2026-06-09T22:17:59.968623Z","end":"2026-06-09T22:18:00.358102Z","steps":["trace[1672370566] 'process raft request' (duration: 389.016081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:00.358136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:59.975269Z","time spent":"382.761119ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:18:00.358446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:59.968597Z","time spent":"389.650079ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:18:00.370499Z","caller":"traceutil/trace.go:171","msg":"trace[943324460] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"356.935069ms","start":"2026-06-09T22:18:00.013545Z","end":"2026-06-09T22:18:00.37048Z","steps":["trace[943324460] 'process raft request' (duration: 356.848052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:00.3706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:00.01352Z","time spent":"357.034406ms","remote":"127.0.0.1:32848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T22:18:00.632287Z","caller":"traceutil/trace.go:171","msg":"trace[1322564041] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"160.149044ms","start":"2026-06-09T22:18:00.472114Z","end":"2026-06-09T22:18:00.632263Z","steps":["trace[1322564041] 'process raft request' (duration: 160.003408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:00.632389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.796796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:18:00.632419Z","caller":"traceutil/trace.go:171","msg":"trace[1394258918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"116.900492ms","start":"2026-06-09T22:18:00.515511Z","end":"2026-06-09T22:18:00.632412Z","steps":["trace[1394258918] 'agreement among raft nodes before linearized reading' (duration: 116.827125ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:00.632299Z","caller":"traceutil/trace.go:171","msg":"trace[2121409162] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:666; }","duration":"116.69719ms","start":"2026-06-09T22:18:00.515588Z","end":"2026-06-09T22:18:00.632285Z","steps":["trace[2121409162] 'read index received' (duration: 116.520015ms)","trace[2121409162] 'applied index is now lower than readState.Index' (duration: 176.335µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:18:00.632627Z","caller":"traceutil/trace.go:171","msg":"trace[2072461071] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"159.684329ms","start":"2026-06-09T22:18:00.472936Z","end":"2026-06-09T22:18:00.63262Z","steps":["trace[2072461071] 'process raft request' (duration: 159.272433ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:00.632764Z","caller":"traceutil/trace.go:171","msg":"trace[1341795930] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"159.631581ms","start":"2026-06-09T22:18:00.473124Z","end":"2026-06-09T22:18:00.632756Z","steps":["trace[1341795930] 'process raft request' (duration: 159.127038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:02.213493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311568780271836760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:18:02.590497Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.191324274s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T22:18:02.591438Z","caller":"traceutil/trace.go:171","msg":"trace[896801048] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.192391551s","start":"2026-06-09T22:18:01.399023Z","end":"2026-06-09T22:18:02.591415Z","steps":["trace[896801048] 'process raft request' (duration: 1.192255365s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:02.591591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:01.399006Z","time spent":"1.192517617s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:18:02.979223Z","caller":"traceutil/trace.go:171","msg":"trace[342053796] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"1.266204659s","start":"2026-06-09T22:18:01.712998Z","end":"2026-06-09T22:18:02.979202Z","steps":["trace[342053796] 'read index received' (duration: 877.985163ms)","trace[342053796] 'applied index is now lower than readState.Index' (duration: 388.218326ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:18:02.979357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266342524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:18:02.979441Z","caller":"traceutil/trace.go:171","msg":"trace[1553245186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.266600026s","start":"2026-06-09T22:18:01.712819Z","end":"2026-06-09T22:18:02.979419Z","steps":["trace[1553245186] 'agreement among raft nodes before linearized reading' (duration: 1.266450501s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:02.979486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:01.712757Z","time spent":"1.266720132s","remote":"127.0.0.1:60820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:18:02.979473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.254982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:18:02.979545Z","caller":"traceutil/trace.go:171","msg":"trace[879693590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"463.349678ms","start":"2026-06-09T22:18:02.516188Z","end":"2026-06-09T22:18:02.979537Z","steps":["trace[879693590] 'agreement among raft nodes before linearized reading' (duration: 463.251241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:02.979569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:02.516177Z","time spent":"463.386238ms","remote":"127.0.0.1:60836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:18:02.979655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.134641ms","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-06-09T22:18:02.979387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.692608ms","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-06-09T22:18:02.979739Z","caller":"traceutil/trace.go:171","msg":"trace[1151787072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"611.259977ms","start":"2026-06-09T22:18:02.36845Z","end":"2026-06-09T22:18:02.97971Z","steps":["trace[1151787072] 'agreement among raft nodes before linearized reading' (duration: 611.000834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:02.979761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.736513ms","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-06-09T22:18:02.979782Z","caller":"traceutil/trace.go:171","msg":"trace[852206884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"611.794681ms","start":"2026-06-09T22:18:02.367982Z","end":"2026-06-09T22:18:02.979776Z","steps":["trace[852206884] 'agreement among raft nodes before linearized reading' (duration: 611.754122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:02.979798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:02.367961Z","time spent":"611.834089ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T22:18:02.979789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:02.368432Z","time spent":"611.345965ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T22:18:02.979784Z","caller":"traceutil/trace.go:171","msg":"trace[1292130936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"996.137404ms","start":"2026-06-09T22:18:01.983629Z","end":"2026-06-09T22:18:02.979767Z","steps":["trace[1292130936] 'agreement among raft nodes before linearized reading' (duration: 995.694237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:02.979941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:01.983601Z","time spent":"996.318359ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T22:18:08.044358Z","caller":"traceutil/trace.go:171","msg":"trace[217863657] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"115.776599ms","start":"2026-06-09T22:18:07.92853Z","end":"2026-06-09T22:18:08.044307Z","steps":["trace[217863657] 'process raft request' (duration: 115.600032ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:08.044541Z","caller":"traceutil/trace.go:171","msg":"trace[1818195623] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"104.539152ms","start":"2026-06-09T22:18:07.939989Z","end":"2026-06-09T22:18:08.044528Z","steps":["trace[1818195623] 'process raft request' (duration: 104.195709ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:08.523509Z","caller":"traceutil/trace.go:171","msg":"trace[1850530788] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"258.002217ms","start":"2026-06-09T22:18:08.265478Z","end":"2026-06-09T22:18:08.52348Z","steps":["trace[1850530788] 'process raft request' (duration: 257.625014ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:08.535699Z","caller":"traceutil/trace.go:171","msg":"trace[117488230] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"267.583765ms","start":"2026-06-09T22:18:08.26809Z","end":"2026-06-09T22:18:08.535674Z","steps":["trace[117488230] 'process raft request' (duration: 267.36471ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:08.535719Z","caller":"traceutil/trace.go:171","msg":"trace[683694290] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"238.582982ms","start":"2026-06-09T22:18:08.297079Z","end":"2026-06-09T22:18:08.535662Z","steps":["trace[683694290] 'read index received' (duration: 225.919074ms)","trace[683694290] 'applied index is now lower than readState.Index' (duration: 12.662948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:18:08.535929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.832137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-06-09T22:18:08.536155Z","caller":"traceutil/trace.go:171","msg":"trace[282546684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:726; }","duration":"239.10559ms","start":"2026-06-09T22:18:08.297028Z","end":"2026-06-09T22:18:08.536134Z","steps":["trace[282546684] 'agreement among raft nodes before linearized reading' (duration: 238.729089ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:13.869101Z","caller":"traceutil/trace.go:171","msg":"trace[486530706] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"115.375786ms","start":"2026-06-09T22:18:13.753698Z","end":"2026-06-09T22:18:13.869074Z","steps":["trace[486530706] 'process raft request' (duration: 80.695825ms)","trace[486530706] 'compare' (duration: 34.556743ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:18:17.150234Z","caller":"traceutil/trace.go:171","msg":"trace[1508861051] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"161.102271ms","start":"2026-06-09T22:18:16.989072Z","end":"2026-06-09T22:18:17.150174Z","steps":["trace[1508861051] 'process raft request' (duration: 160.919263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:20.496768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311568780271837079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:18:20.997051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311568780271837079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:18:21.143574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.16905291s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T22:18:21.143996Z","caller":"traceutil/trace.go:171","msg":"trace[895785056] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.169635295s","start":"2026-06-09T22:18:19.974335Z","end":"2026-06-09T22:18:21.14397Z","steps":["trace[895785056] 'process raft request' (duration: 1.169402867s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:21.144132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:19.974266Z","time spent":"1.169782223s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:18:21.168339Z","caller":"traceutil/trace.go:171","msg":"trace[606143732] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"1.17173623s","start":"2026-06-09T22:18:19.996555Z","end":"2026-06-09T22:18:21.168291Z","steps":["trace[606143732] 'read index received' (duration: 1.147573776s)","trace[606143732] 'applied index is now lower than readState.Index' (duration: 24.161064ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:18:21.168481Z","caller":"traceutil/trace.go:171","msg":"trace[827181968] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"623.091393ms","start":"2026-06-09T22:18:20.545378Z","end":"2026-06-09T22:18:21.168469Z","steps":["trace[827181968] 'process raft request' (duration: 622.719326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:21.168713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.250263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:18:21.168785Z","caller":"traceutil/trace.go:171","msg":"trace[843176681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"652.389462ms","start":"2026-06-09T22:18:20.516376Z","end":"2026-06-09T22:18:21.168765Z","steps":["trace[843176681] 'agreement among raft nodes before linearized reading' (duration: 652.256123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:21.16883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:20.516348Z","time spent":"652.475021ms","remote":"127.0.0.1:60820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:18:21.168866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:20.545357Z","time spent":"623.229981ms","remote":"127.0.0.1:32770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:18:21.168918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.779008ms","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-06-09T22:18:21.169044Z","caller":"traceutil/trace.go:171","msg":"trace[715004163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"467.939097ms","start":"2026-06-09T22:18:20.701084Z","end":"2026-06-09T22:18:21.169024Z","steps":["trace[715004163] 'agreement among raft nodes before linearized reading' (duration: 467.60243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:21.169112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:20.701073Z","time spent":"468.025386ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:18:21.169237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.843959ms","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-06-09T22:18:21.169284Z","caller":"traceutil/trace.go:171","msg":"trace[972016787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:811; }","duration":"501.918169ms","start":"2026-06-09T22:18:20.667356Z","end":"2026-06-09T22:18:21.169274Z","steps":["trace[972016787] 'agreement among raft nodes before linearized reading' (duration: 501.77678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:21.169384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:20.667344Z","time spent":"501.968879ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:18:21.16953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.345806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:18:21.169812Z","caller":"traceutil/trace.go:171","msg":"trace[103655584] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:811; }","duration":"518.635673ms","start":"2026-06-09T22:18:20.651161Z","end":"2026-06-09T22:18:21.169797Z","steps":["trace[103655584] 'agreement among raft nodes before linearized reading' (duration: 518.315676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:21.169924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:20.651105Z","time spent":"518.801931ms","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:18:21.169654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173103947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4274l\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-09T22:18:21.170417Z","caller":"traceutil/trace.go:171","msg":"trace[1161279638] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4274l; range_end:; response_count:1; response_revision:811; }","duration":"1.173932859s","start":"2026-06-09T22:18:19.996465Z","end":"2026-06-09T22:18:21.170398Z","steps":["trace[1161279638] 'agreement among raft nodes before linearized reading' (duration: 1.172923499s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:21.170475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:19.996451Z","time spent":"1.174007999s","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4274l\" "} {"level":"info","ts":"2026-06-09T22:18:35.420877Z","caller":"traceutil/trace.go:171","msg":"trace[321403667] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"114.186577ms","start":"2026-06-09T22:18:35.306663Z","end":"2026-06-09T22:18:35.420849Z","steps":["trace[321403667] 'process raft request' (duration: 106.318732ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:35.421272Z","caller":"traceutil/trace.go:171","msg":"trace[1315951091] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"112.692873ms","start":"2026-06-09T22:18:35.308538Z","end":"2026-06-09T22:18:35.421231Z","steps":["trace[1315951091] 'process raft request' (duration: 112.235301ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:35.421288Z","caller":"traceutil/trace.go:171","msg":"trace[1024754725] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"111.698089ms","start":"2026-06-09T22:18:35.30956Z","end":"2026-06-09T22:18:35.421258Z","steps":["trace[1024754725] 'process raft request' (duration: 111.649409ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:35.421344Z","caller":"traceutil/trace.go:171","msg":"trace[231665218] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"111.970721ms","start":"2026-06-09T22:18:35.309344Z","end":"2026-06-09T22:18:35.421315Z","steps":["trace[231665218] 'process raft request' (duration: 111.751979ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:51.835406Z","caller":"traceutil/trace.go:171","msg":"trace[888796660] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"381.233821ms","start":"2026-06-09T22:18:51.454147Z","end":"2026-06-09T22:18:51.835381Z","steps":["trace[888796660] 'read index received' (duration: 380.962907ms)","trace[888796660] 'applied index is now lower than readState.Index' (duration: 269.864µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:18:51.835569Z","caller":"traceutil/trace.go:171","msg":"trace[788165321] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"406.384864ms","start":"2026-06-09T22:18:51.429161Z","end":"2026-06-09T22:18:51.835546Z","steps":["trace[788165321] 'process raft request' (duration: 406.055219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:51.835696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:51.429138Z","time spent":"406.473214ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:18:51.835897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.917059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:18:51.836083Z","caller":"traceutil/trace.go:171","msg":"trace[1467996572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"320.134373ms","start":"2026-06-09T22:18:51.515908Z","end":"2026-06-09T22:18:51.836042Z","steps":["trace[1467996572] 'agreement among raft nodes before linearized reading' (duration: 319.903809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:51.836149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:51.515892Z","time spent":"320.245824ms","remote":"127.0.0.1:60820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:18:51.836214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.073242ms","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-06-09T22:18:51.83626Z","caller":"traceutil/trace.go:171","msg":"trace[1316972724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"320.128352ms","start":"2026-06-09T22:18:51.516118Z","end":"2026-06-09T22:18:51.836246Z","steps":["trace[1316972724] 'agreement among raft nodes before linearized reading' (duration: 319.98342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:51.836293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:51.516109Z","time spent":"320.177293ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:18:51.836476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.332687ms","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-06-09T22:18:51.836504Z","caller":"traceutil/trace.go:171","msg":"trace[731307241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"382.449399ms","start":"2026-06-09T22:18:51.454046Z","end":"2026-06-09T22:18:51.836496Z","steps":["trace[731307241] 'agreement among raft nodes before linearized reading' (duration: 382.362518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:51.836528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:51.454021Z","time spent":"382.502459ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:18:51.836542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.476017ms","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-06-09T22:18:51.836589Z","caller":"traceutil/trace.go:171","msg":"trace[2042638442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"320.572839ms","start":"2026-06-09T22:18:51.516004Z","end":"2026-06-09T22:18:51.836577Z","steps":["trace[2042638442] 'agreement among raft nodes before linearized reading' (duration: 320.384566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:51.836627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:51.515977Z","time spent":"320.64356ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T22:18:51.8367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.393156ms","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-06-09T22:18:51.83673Z","caller":"traceutil/trace.go:171","msg":"trace[1096295626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"320.443937ms","start":"2026-06-09T22:18:51.516278Z","end":"2026-06-09T22:18:51.836722Z","steps":["trace[1096295626] 'agreement among raft nodes before linearized reading' (duration: 320.362716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:51.836756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:51.516273Z","time spent":"320.477198ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:18:51.83679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.547139ms","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-06-09T22:18:51.837003Z","caller":"traceutil/trace.go:171","msg":"trace[714895508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"320.916735ms","start":"2026-06-09T22:18:51.516064Z","end":"2026-06-09T22:18:51.83698Z","steps":["trace[714895508] 'agreement among raft nodes before linearized reading' (duration: 320.203464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:51.83719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:51.516055Z","time spent":"321.122928ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T22:19:08.52208Z","caller":"traceutil/trace.go:171","msg":"trace[956430721] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"102.26559ms","start":"2026-06-09T22:19:08.419776Z","end":"2026-06-09T22:19:08.522042Z","steps":["trace[956430721] 'process raft request' (duration: 101.996293ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:12.357375Z","caller":"traceutil/trace.go:171","msg":"trace[1015440126] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"101.642078ms","start":"2026-06-09T22:19:12.255713Z","end":"2026-06-09T22:19:12.357355Z","steps":["trace[1015440126] 'read index received' (duration: 16.078017ms)","trace[1015440126] 'applied index is now lower than readState.Index' (duration: 85.562941ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:19:12.357549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.816613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-cnr7z\" ","response":"range_response_count:1 size:4523"} {"level":"info","ts":"2026-06-09T22:19:12.357765Z","caller":"traceutil/trace.go:171","msg":"trace[1359632065] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-cnr7z; range_end:; response_count:1; response_revision:1368; }","duration":"102.075049ms","start":"2026-06-09T22:19:12.255668Z","end":"2026-06-09T22:19:12.357743Z","steps":["trace[1359632065] 'agreement among raft nodes before linearized reading' (duration: 101.778952ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:18.343093Z","caller":"traceutil/trace.go:171","msg":"trace[1705815853] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"214.764433ms","start":"2026-06-09T22:19:18.128306Z","end":"2026-06-09T22:19:18.34307Z","steps":["trace[1705815853] 'process raft request' (duration: 214.479176ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:18.34462Z","caller":"traceutil/trace.go:171","msg":"trace[76441055] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"215.946437ms","start":"2026-06-09T22:19:18.128658Z","end":"2026-06-09T22:19:18.344604Z","steps":["trace[76441055] 'process raft request' (duration: 215.68127ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:18.346292Z","caller":"traceutil/trace.go:171","msg":"trace[1196949440] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"216.632265ms","start":"2026-06-09T22:19:18.129647Z","end":"2026-06-09T22:19:18.346279Z","steps":["trace[1196949440] 'process raft request' (duration: 216.037369ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:18.346613Z","caller":"traceutil/trace.go:171","msg":"trace[156880696] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"215.862864ms","start":"2026-06-09T22:19:18.13072Z","end":"2026-06-09T22:19:18.346583Z","steps":["trace[156880696] 'process raft request' (duration: 215.785412ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:18.346633Z","caller":"traceutil/trace.go:171","msg":"trace[1414443321] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"216.253146ms","start":"2026-06-09T22:19:18.130357Z","end":"2026-06-09T22:19:18.34661Z","steps":["trace[1414443321] 'process raft request' (duration: 215.445183ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:23.061742Z","caller":"traceutil/trace.go:171","msg":"trace[356877582] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"199.707085ms","start":"2026-06-09T22:19:22.861988Z","end":"2026-06-09T22:19:23.061695Z","steps":["trace[356877582] 'process raft request' (duration: 151.192574ms)","trace[356877582] 'compare' (duration: 48.224332ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:23.242444Z","caller":"traceutil/trace.go:171","msg":"trace[163083729] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"105.46133ms","start":"2026-06-09T22:19:23.136941Z","end":"2026-06-09T22:19:23.242402Z","steps":["trace[163083729] 'process raft request' (duration: 62.454264ms)","trace[163083729] 'compare' (duration: 42.774369ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:23.557901Z","caller":"traceutil/trace.go:171","msg":"trace[602703312] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"228.677738ms","start":"2026-06-09T22:19:23.32919Z","end":"2026-06-09T22:19:23.557868Z","steps":["trace[602703312] 'process raft request' (duration: 133.474142ms)","trace[602703312] 'compare' (duration: 94.840975ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:36.091554Z","caller":"traceutil/trace.go:171","msg":"trace[2070258718] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"191.142618ms","start":"2026-06-09T22:19:35.900376Z","end":"2026-06-09T22:19:36.091519Z","steps":["trace[2070258718] 'process raft request' (duration: 190.474875ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:36.09234Z","caller":"traceutil/trace.go:171","msg":"trace[1126317467] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"165.39716ms","start":"2026-06-09T22:19:35.926922Z","end":"2026-06-09T22:19:36.092319Z","steps":["trace[1126317467] 'read index received' (duration: 163.60629ms)","trace[1126317467] 'applied index is now lower than readState.Index' (duration: 1.78821ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:19:36.092531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.560986ms","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-06-09T22:19:36.092592Z","caller":"traceutil/trace.go:171","msg":"trace[439173844] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1782; }","duration":"165.68499ms","start":"2026-06-09T22:19:35.92689Z","end":"2026-06-09T22:19:36.092575Z","steps":["trace[439173844] 'agreement among raft nodes before linearized reading' (duration: 165.542575ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:36.093676Z","caller":"traceutil/trace.go:171","msg":"trace[1763051416] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"156.071837ms","start":"2026-06-09T22:19:35.937586Z","end":"2026-06-09T22:19:36.093658Z","steps":["trace[1763051416] 'process raft request' (duration: 155.623662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:36.094622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.673192ms","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-06-09T22:19:36.094658Z","caller":"traceutil/trace.go:171","msg":"trace[1969836701] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1783; }","duration":"103.752924ms","start":"2026-06-09T22:19:35.990895Z","end":"2026-06-09T22:19:36.094648Z","steps":["trace[1969836701] 'agreement among raft nodes before linearized reading' (duration: 103.695872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:36.094898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.837657ms","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-06-09T22:19:36.094934Z","caller":"traceutil/trace.go:171","msg":"trace[763391872] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1783; }","duration":"103.914569ms","start":"2026-06-09T22:19:35.991008Z","end":"2026-06-09T22:19:36.094923Z","steps":["trace[763391872] 'agreement among raft nodes before linearized reading' (duration: 103.860517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:36.095255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.160783ms","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-06-09T22:19:36.095286Z","caller":"traceutil/trace.go:171","msg":"trace[1489998624] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1783; }","duration":"138.194854ms","start":"2026-06-09T22:19:35.957083Z","end":"2026-06-09T22:19:36.095278Z","steps":["trace[1489998624] 'agreement among raft nodes before linearized reading' (duration: 138.144593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:36.095522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.468804ms","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-06-09T22:19:36.095556Z","caller":"traceutil/trace.go:171","msg":"trace[844683755] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1783; }","duration":"138.534816ms","start":"2026-06-09T22:19:35.957013Z","end":"2026-06-09T22:19:36.095548Z","steps":["trace[844683755] 'agreement among raft nodes before linearized reading' (duration: 138.475564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:36.095835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.678662ms","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-06-09T22:19:36.095891Z","caller":"traceutil/trace.go:171","msg":"trace[1242065079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1783; }","duration":"168.780366ms","start":"2026-06-09T22:19:35.927102Z","end":"2026-06-09T22:19:36.095882Z","steps":["trace[1242065079] 'agreement among raft nodes before linearized reading' (duration: 166.215749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:43.707382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.025274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:19:43.708841Z","caller":"traceutil/trace.go:171","msg":"trace[1046991951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"192.518949ms","start":"2026-06-09T22:19:43.516304Z","end":"2026-06-09T22:19:43.708822Z","steps":["trace[1046991951] 'range keys from in-memory index tree' (duration: 190.937391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:43.707686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.615067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e8d45081-d878-432a-9ecb-c771db114c29\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-09T22:19:43.707932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.721711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e8d45081-d878-432a-9ecb-c771db114c29\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-09T22:19:43.70797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.148085ms","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-06-09T22:19:43.708246Z","caller":"traceutil/trace.go:171","msg":"trace[1815691511] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"109.597204ms","start":"2026-06-09T22:19:43.598613Z","end":"2026-06-09T22:19:43.70821Z","steps":["trace[1815691511] 'process raft request' (duration: 92.25978ms)","trace[1815691511] 'compare' (duration: 16.4035ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:43.709825Z","caller":"traceutil/trace.go:171","msg":"trace[1311267990] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e8d45081-d878-432a-9ecb-c771db114c29; range_end:; response_count:1; response_revision:1896; }","duration":"280.676572ms","start":"2026-06-09T22:19:43.429134Z","end":"2026-06-09T22:19:43.70981Z","steps":["trace[1311267990] 'range keys from in-memory index tree' (duration: 278.509984ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:43.709974Z","caller":"traceutil/trace.go:171","msg":"trace[2033398394] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e8d45081-d878-432a-9ecb-c771db114c29; range_end:; response_count:1; response_revision:1896; }","duration":"622.952369ms","start":"2026-06-09T22:19:43.087012Z","end":"2026-06-09T22:19:43.709964Z","steps":["trace[2033398394] 'range keys from in-memory index tree' (duration: 620.479652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:43.710017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:43.086989Z","time spent":"623.014292ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e8d45081-d878-432a-9ecb-c771db114c29\" "} {"level":"info","ts":"2026-06-09T22:19:43.71046Z","caller":"traceutil/trace.go:171","msg":"trace[1770385782] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1896; }","duration":"214.671687ms","start":"2026-06-09T22:19:43.495756Z","end":"2026-06-09T22:19:43.710428Z","steps":["trace[1770385782] 'range keys from in-memory index tree' (duration: 211.983929ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:44.15084Z","caller":"traceutil/trace.go:171","msg":"trace[1068199424] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1972; }","duration":"424.239828ms","start":"2026-06-09T22:19:43.726564Z","end":"2026-06-09T22:19:44.150804Z","steps":["trace[1068199424] 'read index received' (duration: 2.174539ms)","trace[1068199424] 'applied index is now lower than readState.Index' (duration: 422.063829ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:44.151078Z","caller":"traceutil/trace.go:171","msg":"trace[1478657842] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"431.906523ms","start":"2026-06-09T22:19:43.719011Z","end":"2026-06-09T22:19:44.150918Z","steps":["trace[1478657842] 'process raft request' (duration: 431.569611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.151224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.612921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T22:19:44.151346Z","caller":"traceutil/trace.go:171","msg":"trace[1485184583] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"424.812798ms","start":"2026-06-09T22:19:43.7265Z","end":"2026-06-09T22:19:44.151313Z","steps":["trace[1485184583] 'agreement among raft nodes before linearized reading' (duration: 424.436265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.151426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:43.726464Z","time spent":"424.937982ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T22:19:44.151403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:43.718988Z","time spent":"432.220694ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:19:44.932889Z","caller":"traceutil/trace.go:171","msg":"trace[1579572340] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"807.477716ms","start":"2026-06-09T22:19:44.125377Z","end":"2026-06-09T22:19:44.932855Z","steps":["trace[1579572340] 'process raft request' (duration: 763.709089ms)","trace[1579572340] 'compare' (duration: 42.918567ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:19:44.933149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.125334Z","time spent":"807.704484ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:19:44.937798Z","caller":"traceutil/trace.go:171","msg":"trace[1908139161] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1974; }","duration":"442.95806ms","start":"2026-06-09T22:19:44.494804Z","end":"2026-06-09T22:19:44.937762Z","steps":["trace[1908139161] 'read index received' (duration: 394.279004ms)","trace[1908139161] 'applied index is now lower than readState.Index' (duration: 48.677496ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:44.937994Z","caller":"traceutil/trace.go:171","msg":"trace[819194831] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"292.637861ms","start":"2026-06-09T22:19:44.64534Z","end":"2026-06-09T22:19:44.937977Z","steps":["trace[819194831] 'process raft request' (duration: 292.369911ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:44.937826Z","caller":"traceutil/trace.go:171","msg":"trace[9237360] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"562.422802ms","start":"2026-06-09T22:19:44.375356Z","end":"2026-06-09T22:19:44.937779Z","steps":["trace[9237360] 'process raft request' (duration: 562.141442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.93846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.375323Z","time spent":"563.069675ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:19:44.938577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.943724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-09T22:19:44.938637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.811151ms","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-06-09T22:19:44.938684Z","caller":"traceutil/trace.go:171","msg":"trace[952331358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1902; }","duration":"443.913774ms","start":"2026-06-09T22:19:44.494755Z","end":"2026-06-09T22:19:44.938669Z","steps":["trace[952331358] 'agreement among raft nodes before linearized reading' (duration: 443.713327ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.938731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.494741Z","time spent":"443.980437ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T22:19:44.938767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.116044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-06-09T22:19:44.938778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.762559ms","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-06-09T22:19:44.938819Z","caller":"traceutil/trace.go:171","msg":"trace[1441254698] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1902; }","duration":"326.240258ms","start":"2026-06-09T22:19:44.612566Z","end":"2026-06-09T22:19:44.938807Z","steps":["trace[1441254698] 'agreement among raft nodes before linearized reading' (duration: 326.031751ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:44.938819Z","caller":"traceutil/trace.go:171","msg":"trace[860815554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1902; }","duration":"438.870202ms","start":"2026-06-09T22:19:44.499941Z","end":"2026-06-09T22:19:44.938811Z","steps":["trace[860815554] 'agreement among raft nodes before linearized reading' (duration: 438.768179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.938851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.612535Z","time spent":"326.309961ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-09T22:19:44.938863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.499929Z","time spent":"438.924525ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T22:19:44.938679Z","caller":"traceutil/trace.go:171","msg":"trace[1380038320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1902; }","duration":"442.11585ms","start":"2026-06-09T22:19:44.496541Z","end":"2026-06-09T22:19:44.938657Z","steps":["trace[1380038320] 'agreement among raft nodes before linearized reading' (duration: 441.869771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.938938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.496505Z","time spent":"442.421631ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:19:44.939028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.173806ms","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-06-09T22:19:44.939027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.152115ms","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-06-09T22:19:44.939101Z","caller":"traceutil/trace.go:171","msg":"trace[1793647946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1902; }","duration":"393.255148ms","start":"2026-06-09T22:19:44.545837Z","end":"2026-06-09T22:19:44.939092Z","steps":["trace[1793647946] 'agreement among raft nodes before linearized reading' (duration: 392.794591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.939147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.545819Z","time spent":"393.31747ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T22:19:44.939068Z","caller":"traceutil/trace.go:171","msg":"trace[820571398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1902; }","duration":"390.233758ms","start":"2026-06-09T22:19:44.548826Z","end":"2026-06-09T22:19:44.93906Z","steps":["trace[820571398] 'agreement among raft nodes before linearized reading' (duration: 390.121114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:44.93944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.231083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e8d45081-d878-432a-9ecb-c771db114c29\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-06-09T22:19:44.939363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:44.548817Z","time spent":"390.475807ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T22:19:44.939485Z","caller":"traceutil/trace.go:171","msg":"trace[1910511474] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e8d45081-d878-432a-9ecb-c771db114c29; range_end:; response_count:1; response_revision:1902; }","duration":"223.399589ms","start":"2026-06-09T22:19:44.716072Z","end":"2026-06-09T22:19:44.939472Z","steps":["trace[1910511474] 'agreement among raft nodes before linearized reading' (duration: 222.972644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:58.168888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.984418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-5qvpv\" ","response":"range_response_count:1 size:5815"} {"level":"info","ts":"2026-06-09T22:19:58.168988Z","caller":"traceutil/trace.go:171","msg":"trace[317339042] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-5qvpv; range_end:; response_count:1; response_revision:2010; }","duration":"124.141074ms","start":"2026-06-09T22:19:58.044825Z","end":"2026-06-09T22:19:58.168966Z","steps":["trace[317339042] 'range keys from in-memory index tree' (duration: 123.811771ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:58.701029Z","caller":"traceutil/trace.go:171","msg":"trace[81017654] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"435.900391ms","start":"2026-06-09T22:19:58.265109Z","end":"2026-06-09T22:19:58.701009Z","steps":["trace[81017654] 'read index received' (duration: 435.708164ms)","trace[81017654] 'applied index is now lower than readState.Index' (duration: 191.427µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:58.701144Z","caller":"traceutil/trace.go:171","msg":"trace[889800269] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"522.218618ms","start":"2026-06-09T22:19:58.178895Z","end":"2026-06-09T22:19:58.701114Z","steps":["trace[889800269] 'process raft request' (duration: 521.980218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:58.701303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:58.178872Z","time spent":"522.346173ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5586,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:19:58.701345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.211173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T22:19:58.701516Z","caller":"traceutil/trace.go:171","msg":"trace[1087950223] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2011; }","duration":"436.353529ms","start":"2026-06-09T22:19:58.265055Z","end":"2026-06-09T22:19:58.701408Z","steps":["trace[1087950223] 'agreement among raft nodes before linearized reading' (duration: 436.096219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:58.701616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:19:58.265035Z","time spent":"436.563778ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-09T22:19:58.914615Z","caller":"traceutil/trace.go:171","msg":"trace[1169272488] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"204.057416ms","start":"2026-06-09T22:19:58.710527Z","end":"2026-06-09T22:19:58.914585Z","steps":["trace[1169272488] 'process raft request' (duration: 203.893619ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:58.943316Z","caller":"traceutil/trace.go:171","msg":"trace[1257326134] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2089; }","duration":"230.750562ms","start":"2026-06-09T22:19:58.712546Z","end":"2026-06-09T22:19:58.943296Z","steps":["trace[1257326134] 'read index received' (duration: 201.827327ms)","trace[1257326134] 'applied index is now lower than readState.Index' (duration: 28.922044ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:58.94344Z","caller":"traceutil/trace.go:171","msg":"trace[930486067] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"230.885297ms","start":"2026-06-09T22:19:58.712526Z","end":"2026-06-09T22:19:58.943411Z","steps":["trace[930486067] 'process raft request' (duration: 230.596866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:58.943469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.893407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:19:58.943635Z","caller":"traceutil/trace.go:171","msg":"trace[1351237003] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2014; }","duration":"231.060064ms","start":"2026-06-09T22:19:58.712508Z","end":"2026-06-09T22:19:58.943568Z","steps":["trace[1351237003] 'agreement among raft nodes before linearized reading' (duration: 230.861266ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:58.94358Z","caller":"traceutil/trace.go:171","msg":"trace[1787483794] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"220.83833ms","start":"2026-06-09T22:19:58.722722Z","end":"2026-06-09T22:19:58.94356Z","steps":["trace[1787483794] 'process raft request' (duration: 220.526578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:59.212198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.049338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:19:59.21258Z","caller":"traceutil/trace.go:171","msg":"trace[1360121603] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"226.591111ms","start":"2026-06-09T22:19:58.985955Z","end":"2026-06-09T22:19:59.212546Z","steps":["trace[1360121603] 'process raft request' (duration: 116.049464ms)","trace[1360121603] 'compare' (duration: 109.441924ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:59.212689Z","caller":"traceutil/trace.go:171","msg":"trace[43996999] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"217.180259ms","start":"2026-06-09T22:19:58.995482Z","end":"2026-06-09T22:19:59.212662Z","steps":["trace[43996999] 'process raft request' (duration: 217.059374ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:59.212899Z","caller":"traceutil/trace.go:171","msg":"trace[976111408] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"219.054573ms","start":"2026-06-09T22:19:58.993833Z","end":"2026-06-09T22:19:59.212887Z","steps":["trace[976111408] 'process raft request' (duration: 218.528663ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:59.213205Z","caller":"traceutil/trace.go:171","msg":"trace[1642105161] linearizableReadLoop","detail":"{readStateIndex:2101; appliedIndex:2096; }","duration":"170.002133ms","start":"2026-06-09T22:19:59.043172Z","end":"2026-06-09T22:19:59.213174Z","steps":["trace[1642105161] 'read index received' (duration: 58.845341ms)","trace[1642105161] 'applied index is now lower than readState.Index' (duration: 111.155562ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:59.213627Z","caller":"traceutil/trace.go:171","msg":"trace[726132056] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"203.959885ms","start":"2026-06-09T22:19:59.009647Z","end":"2026-06-09T22:19:59.213607Z","steps":["trace[726132056] 'process raft request' (duration: 203.384351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:59.213798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.718463ms","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-06-09T22:19:59.21389Z","caller":"traceutil/trace.go:171","msg":"trace[1799179353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2023; }","duration":"165.842168ms","start":"2026-06-09T22:19:59.048028Z","end":"2026-06-09T22:19:59.21387Z","steps":["trace[1799179353] 'agreement among raft nodes before linearized reading' (duration: 165.587848ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:59.214562Z","caller":"traceutil/trace.go:171","msg":"trace[627665802] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"216.634858ms","start":"2026-06-09T22:19:58.997909Z","end":"2026-06-09T22:19:59.214544Z","steps":["trace[627665802] 'process raft request' (duration: 214.672119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:59.214697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.361839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T22:19:59.214792Z","caller":"traceutil/trace.go:171","msg":"trace[580146288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2023; }","duration":"166.504544ms","start":"2026-06-09T22:19:59.048271Z","end":"2026-06-09T22:19:59.214775Z","steps":["trace[580146288] 'agreement among raft nodes before linearized reading' (duration: 166.238733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:59.215026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.753494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T22:19:59.215076Z","caller":"traceutil/trace.go:171","msg":"trace[367237637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2023; }","duration":"166.829576ms","start":"2026-06-09T22:19:59.048235Z","end":"2026-06-09T22:19:59.215065Z","steps":["trace[367237637] 'agreement among raft nodes before linearized reading' (duration: 166.67892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:59.215285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.108107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-09T22:19:59.215323Z","caller":"traceutil/trace.go:171","msg":"trace[618375633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2023; }","duration":"172.18671ms","start":"2026-06-09T22:19:59.043128Z","end":"2026-06-09T22:19:59.215314Z","steps":["trace[618375633] 'agreement among raft nodes before linearized reading' (duration: 172.090716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:59.215949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.944661ms","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-06-09T22:19:59.216017Z","caller":"traceutil/trace.go:171","msg":"trace[676929521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2023; }","duration":"172.034624ms","start":"2026-06-09T22:19:59.043967Z","end":"2026-06-09T22:19:59.216002Z","steps":["trace[676929521] 'agreement among raft nodes before linearized reading' (duration: 171.835867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:02.779333Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311568780271839554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:20:02.97603Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039228198s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T22:20:02.976338Z","caller":"traceutil/trace.go:171","msg":"trace[1896968657] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"1.039625594s","start":"2026-06-09T22:20:01.936695Z","end":"2026-06-09T22:20:02.976321Z","steps":["trace[1896968657] 'process raft request' (duration: 1.03951167s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:02.976447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:01.936662Z","time spent":"1.039725238s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:03.031015Z","caller":"traceutil/trace.go:171","msg":"trace[1013496953] linearizableReadLoop","detail":"{readStateIndex:2120; appliedIndex:2118; }","duration":"752.046056ms","start":"2026-06-09T22:20:02.278943Z","end":"2026-06-09T22:20:03.03099Z","steps":["trace[1013496953] 'read index received' (duration: 697.262468ms)","trace[1013496953] 'applied index is now lower than readState.Index' (duration: 54.782088ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:20:03.03117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.205712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:20:03.031195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.695435ms","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-06-09T22:20:03.031235Z","caller":"traceutil/trace.go:171","msg":"trace[828849931] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2041; }","duration":"532.780069ms","start":"2026-06-09T22:20:02.498445Z","end":"2026-06-09T22:20:03.031226Z","steps":["trace[828849931] 'agreement among raft nodes before linearized reading' (duration: 532.685745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:03.031268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.269849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:20:03.03127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:02.498428Z","time spent":"532.833101ms","remote":"127.0.0.1:60920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-06-09T22:20:03.031295Z","caller":"traceutil/trace.go:171","msg":"trace[1957014213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2041; }","duration":"512.323851ms","start":"2026-06-09T22:20:02.518966Z","end":"2026-06-09T22:20:03.031289Z","steps":["trace[1957014213] 'agreement among raft nodes before linearized reading' (duration: 512.282079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:03.031332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:02.518953Z","time spent":"512.373014ms","remote":"127.0.0.1:60836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:20:03.031537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.075607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T22:20:03.031635Z","caller":"traceutil/trace.go:171","msg":"trace[132434067] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2041; }","duration":"301.213234ms","start":"2026-06-09T22:20:02.730406Z","end":"2026-06-09T22:20:03.031619Z","steps":["trace[132434067] 'agreement among raft nodes before linearized reading' (duration: 300.862389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:03.031744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:02.730386Z","time spent":"301.308728ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-09T22:20:03.031217Z","caller":"traceutil/trace.go:171","msg":"trace[1801799851] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2041; }","duration":"752.297236ms","start":"2026-06-09T22:20:02.278907Z","end":"2026-06-09T22:20:03.031204Z","steps":["trace[1801799851] 'agreement among raft nodes before linearized reading' (duration: 752.200402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:03.032017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:02.278889Z","time spent":"753.10808ms","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:20:25.84013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.297928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:20:25.840293Z","caller":"traceutil/trace.go:171","msg":"trace[1361972347] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"530.249575ms","start":"2026-06-09T22:20:25.310019Z","end":"2026-06-09T22:20:25.840268Z","steps":["trace[1361972347] 'process raft request' (duration: 254.728828ms)","trace[1361972347] 'compare' (duration: 275.12299ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:20:25.840361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.309981Z","time spent":"530.34786ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:25.949894Z","caller":"traceutil/trace.go:171","msg":"trace[2045859563] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"582.474495ms","start":"2026-06-09T22:20:25.367388Z","end":"2026-06-09T22:20:25.949863Z","steps":["trace[2045859563] 'process raft request' (duration: 572.132948ms)","trace[2045859563] 'compare' (duration: 10.117037ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:20:25.950296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.367342Z","time spent":"582.663634ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:25.95031Z","caller":"traceutil/trace.go:171","msg":"trace[1181556282] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2249; }","duration":"577.688115ms","start":"2026-06-09T22:20:25.372568Z","end":"2026-06-09T22:20:25.950256Z","steps":["trace[1181556282] 'read index received' (duration: 192.198152ms)","trace[1181556282] 'applied index is now lower than readState.Index' (duration: 385.486543ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:20:25.951002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.589171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:20:25.951062Z","caller":"traceutil/trace.go:171","msg":"trace[1059474904] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2168; }","duration":"490.683265ms","start":"2026-06-09T22:20:25.460365Z","end":"2026-06-09T22:20:25.951048Z","steps":["trace[1059474904] 'agreement among raft nodes before linearized reading' (duration: 490.588931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.951111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.460351Z","time spent":"490.747098ms","remote":"127.0.0.1:32770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:20:25.951114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.796478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:20:25.951205Z","caller":"traceutil/trace.go:171","msg":"trace[852859595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2168; }","duration":"435.940314ms","start":"2026-06-09T22:20:25.515247Z","end":"2026-06-09T22:20:25.951187Z","steps":["trace[852859595] 'agreement among raft nodes before linearized reading' (duration: 435.772567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.951258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.515227Z","time spent":"436.021948ms","remote":"127.0.0.1:60820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:20:25.951123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.422168ms","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-06-09T22:20:25.95132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.998434ms","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-06-09T22:20:25.951408Z","caller":"traceutil/trace.go:171","msg":"trace[479067418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2168; }","duration":"544.155251ms","start":"2026-06-09T22:20:25.407236Z","end":"2026-06-09T22:20:25.951391Z","steps":["trace[479067418] 'agreement among raft nodes before linearized reading' (duration: 543.810856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.951484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.407218Z","time spent":"544.253335ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T22:20:25.951481Z","caller":"traceutil/trace.go:171","msg":"trace[771453838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2168; }","duration":"569.807735ms","start":"2026-06-09T22:20:25.381563Z","end":"2026-06-09T22:20:25.951371Z","steps":["trace[771453838] 'agreement among raft nodes before linearized reading' (duration: 569.326884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.951581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.35241ms","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-06-09T22:20:25.951648Z","caller":"traceutil/trace.go:171","msg":"trace[1082893172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2168; }","duration":"544.423862ms","start":"2026-06-09T22:20:25.407209Z","end":"2026-06-09T22:20:25.951632Z","steps":["trace[1082893172] 'agreement among raft nodes before linearized reading' (duration: 544.253955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.951686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.407201Z","time spent":"544.478654ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T22:20:25.950202Z","caller":"traceutil/trace.go:171","msg":"trace[1788078324] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"582.718587ms","start":"2026-06-09T22:20:25.367411Z","end":"2026-06-09T22:20:25.95013Z","steps":["trace[1788078324] 'process raft request' (duration: 582.395111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.951762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.300997ms","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-06-09T22:20:25.951804Z","caller":"traceutil/trace.go:171","msg":"trace[2121639778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2168; }","duration":"544.37306ms","start":"2026-06-09T22:20:25.407417Z","end":"2026-06-09T22:20:25.95179Z","steps":["trace[2121639778] 'agreement among raft nodes before linearized reading' (duration: 544.191962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.951834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.407372Z","time spent":"544.455034ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:20:25.951742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.381536Z","time spent":"570.131959ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T22:20:25.950512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.916655ms","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-06-09T22:20:25.951877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.367379Z","time spent":"584.419342ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:25.952054Z","caller":"traceutil/trace.go:171","msg":"trace[125632425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2168; }","duration":"579.439942ms","start":"2026-06-09T22:20:25.37252Z","end":"2026-06-09T22:20:25.95196Z","steps":["trace[125632425] 'agreement among raft nodes before linearized reading' (duration: 577.716656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:25.952169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.372495Z","time spent":"579.660681ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T22:20:26.311628Z","caller":"traceutil/trace.go:171","msg":"trace[2031092037] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2254; }","duration":"116.339533ms","start":"2026-06-09T22:20:26.195248Z","end":"2026-06-09T22:20:26.311588Z","steps":["trace[2031092037] 'read index received' (duration: 60.85127ms)","trace[2031092037] 'applied index is now lower than readState.Index' (duration: 55.486682ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:20:26.311817Z","caller":"traceutil/trace.go:171","msg":"trace[962761810] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"352.402841ms","start":"2026-06-09T22:20:25.95938Z","end":"2026-06-09T22:20:26.311782Z","steps":["trace[962761810] 'process raft request' (duration: 351.959081ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:20:26.311856Z","caller":"traceutil/trace.go:171","msg":"trace[2106168086] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"352.019015ms","start":"2026-06-09T22:20:25.959799Z","end":"2026-06-09T22:20:26.311818Z","steps":["trace[2106168086] 'process raft request' (duration: 351.705411ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:20:26.311874Z","caller":"traceutil/trace.go:171","msg":"trace[1695520641] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"352.464224ms","start":"2026-06-09T22:20:25.959366Z","end":"2026-06-09T22:20:26.31183Z","steps":["trace[1695520641] 'process raft request' (duration: 296.71799ms)","trace[1695520641] 'compare' (duration: 54.690537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:20:26.311991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.959365Z","time spent":"352.526717ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:20:26.311993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.73567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-09T22:20:26.31204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.959355Z","time spent":"352.59781ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:26.312066Z","caller":"traceutil/trace.go:171","msg":"trace[1827139600] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"116.883007ms","start":"2026-06-09T22:20:26.195165Z","end":"2026-06-09T22:20:26.312048Z","steps":["trace[1827139600] 'agreement among raft nodes before linearized reading' (duration: 116.627846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:26.31209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:25.959788Z","time spent":"352.16096ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:27.32091Z","caller":"traceutil/trace.go:171","msg":"trace[1829500590] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2259; }","duration":"125.231105ms","start":"2026-06-09T22:20:27.195647Z","end":"2026-06-09T22:20:27.320878Z","steps":["trace[1829500590] 'read index received' (duration: 5.411151ms)","trace[1829500590] 'applied index is now lower than readState.Index' (duration: 119.818684ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:20:27.321252Z","caller":"traceutil/trace.go:171","msg":"trace[1717318004] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"136.443113ms","start":"2026-06-09T22:20:27.184675Z","end":"2026-06-09T22:20:27.321118Z","steps":["trace[1717318004] 'process raft request' (duration: 129.18242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:27.32127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.58318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:20:27.321509Z","caller":"traceutil/trace.go:171","msg":"trace[1546792055] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2176; }","duration":"125.855902ms","start":"2026-06-09T22:20:27.195632Z","end":"2026-06-09T22:20:27.321488Z","steps":["trace[1546792055] 'agreement among raft nodes before linearized reading' (duration: 125.565259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:27.321548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.088799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:20:27.321597Z","caller":"traceutil/trace.go:171","msg":"trace[1619369381] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2176; }","duration":"103.203374ms","start":"2026-06-09T22:20:27.218377Z","end":"2026-06-09T22:20:27.321581Z","steps":["trace[1619369381] 'agreement among raft nodes before linearized reading' (duration: 103.076388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:27.321552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.287575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-09T22:20:27.321687Z","caller":"traceutil/trace.go:171","msg":"trace[834223143] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2176; }","duration":"118.470853ms","start":"2026-06-09T22:20:27.203201Z","end":"2026-06-09T22:20:27.321672Z","steps":["trace[834223143] 'agreement among raft nodes before linearized reading' (duration: 118.138378ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:20:27.847896Z","caller":"traceutil/trace.go:171","msg":"trace[671906386] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"274.604792ms","start":"2026-06-09T22:20:27.573263Z","end":"2026-06-09T22:20:27.847868Z","steps":["trace[671906386] 'process raft request' (duration: 274.423274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:28.46342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311568780271839892,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T22:20:28.70986Z","caller":"traceutil/trace.go:171","msg":"trace[359255301] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"751.650249ms","start":"2026-06-09T22:20:27.958173Z","end":"2026-06-09T22:20:28.709823Z","steps":["trace[359255301] 'process raft request' (duration: 751.419959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:28.710115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:27.958146Z","time spent":"751.822457ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:20:29.327888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.121011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:20:29.328485Z","caller":"traceutil/trace.go:171","msg":"trace[1299707623] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"1.370211044s","start":"2026-06-09T22:20:27.95824Z","end":"2026-06-09T22:20:29.328451Z","steps":["trace[1299707623] 'process raft request' (duration: 931.383412ms)","trace[1299707623] 'compare' (duration: 437.713553ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:20:29.328563Z","caller":"traceutil/trace.go:171","msg":"trace[1684909132] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2263; }","duration":"1.365767417s","start":"2026-06-09T22:20:27.962755Z","end":"2026-06-09T22:20:29.328523Z","steps":["trace[1684909132] 'read index received' (duration: 746.877347ms)","trace[1684909132] 'applied index is now lower than readState.Index' (duration: 618.88756ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:20:29.328602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:27.958194Z","time spent":"1.370358011s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:20:29.32879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366025919s","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-06-09T22:20:29.328875Z","caller":"traceutil/trace.go:171","msg":"trace[2107708582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2180; }","duration":"1.366176075s","start":"2026-06-09T22:20:27.962677Z","end":"2026-06-09T22:20:29.328853Z","steps":["trace[2107708582] 'agreement among raft nodes before linearized reading' (duration: 1.365939295s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.328925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:27.962648Z","time spent":"1.36626814s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T22:20:29.329357Z","caller":"traceutil/trace.go:171","msg":"trace[1242450610] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"357.326015ms","start":"2026-06-09T22:20:28.972012Z","end":"2026-06-09T22:20:29.329338Z","steps":["trace[1242450610] 'process raft request' (duration: 356.039087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.329389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01336716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-09T22:20:29.329409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359210315s","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-06-09T22:20:29.329439Z","caller":"traceutil/trace.go:171","msg":"trace[399153648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2181; }","duration":"1.013472026s","start":"2026-06-09T22:20:28.315953Z","end":"2026-06-09T22:20:29.329425Z","steps":["trace[399153648] 'agreement among raft nodes before linearized reading' (duration: 1.013287108s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.330184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:28.31593Z","time spent":"1.01423709s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T22:20:29.329461Z","caller":"traceutil/trace.go:171","msg":"trace[87013429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2181; }","duration":"1.35932443s","start":"2026-06-09T22:20:27.970122Z","end":"2026-06-09T22:20:29.329446Z","steps":["trace[87013429] 'agreement among raft nodes before linearized reading' (duration: 1.359183503s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.330794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:27.970099Z","time spent":"1.36067726s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:20:29.329495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0082073s","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-06-09T22:20:29.330954Z","caller":"traceutil/trace.go:171","msg":"trace[1912676287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2181; }","duration":"1.009677326s","start":"2026-06-09T22:20:28.32126Z","end":"2026-06-09T22:20:29.330937Z","steps":["trace[1912676287] 'agreement among raft nodes before linearized reading' (duration: 1.008158819s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.3295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:28.971989Z","time spent":"357.425509ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:20:29.32936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.549719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:20:29.331153Z","caller":"traceutil/trace.go:171","msg":"trace[2088978131] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2181; }","duration":"711.3645ms","start":"2026-06-09T22:20:28.619766Z","end":"2026-06-09T22:20:29.331131Z","steps":["trace[2088978131] 'agreement among raft nodes before linearized reading' (duration: 709.402892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.329547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.23409ms","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":"warn","ts":"2026-06-09T22:20:29.331222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:28.619756Z","time spent":"711.446663ms","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-06-09T22:20:29.331258Z","caller":"traceutil/trace.go:171","msg":"trace[727154959] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2181; }","duration":"135.981747ms","start":"2026-06-09T22:20:29.195259Z","end":"2026-06-09T22:20:29.331241Z","steps":["trace[727154959] 'agreement among raft nodes before linearized reading' (duration: 134.24648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.329592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001196888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T22:20:29.331335Z","caller":"traceutil/trace.go:171","msg":"trace[1602584596] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2181; }","duration":"1.002944206s","start":"2026-06-09T22:20:28.328377Z","end":"2026-06-09T22:20:29.331321Z","steps":["trace[1602584596] 'agreement among raft nodes before linearized reading' (duration: 1.001148536s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.33138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:28.328369Z","time spent":"1.002999778s","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T22:20:29.329635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013007375s","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-06-09T22:20:29.331541Z","caller":"traceutil/trace.go:171","msg":"trace[1419154987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2181; }","duration":"1.0149111s","start":"2026-06-09T22:20:28.316619Z","end":"2026-06-09T22:20:29.33153Z","steps":["trace[1419154987] 'agreement among raft nodes before linearized reading' (duration: 1.012930911s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.331586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:28.316548Z","time spent":"1.015025595s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:20:29.329751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.720775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:20:29.331729Z","caller":"traceutil/trace.go:171","msg":"trace[991560719] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2181; }","duration":"743.740615ms","start":"2026-06-09T22:20:28.58798Z","end":"2026-06-09T22:20:29.33172Z","steps":["trace[991560719] 'agreement among raft nodes before linearized reading' (duration: 741.210771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.331765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:28.587958Z","time spent":"743.794027ms","remote":"127.0.0.1:60902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:20:29.331011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:28.321253Z","time spent":"1.009743789s","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T22:20:29.770059Z","caller":"traceutil/trace.go:171","msg":"trace[1782186188] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"432.996617ms","start":"2026-06-09T22:20:29.336949Z","end":"2026-06-09T22:20:29.769946Z","steps":["trace[1782186188] 'process raft request' (duration: 382.81877ms)","trace[1782186188] 'compare' (duration: 49.660434ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:20:29.770372Z","caller":"traceutil/trace.go:171","msg":"trace[743254286] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"433.30271ms","start":"2026-06-09T22:20:29.337005Z","end":"2026-06-09T22:20:29.770308Z","steps":["trace[743254286] 'process raft request' (duration: 432.904402ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:20:29.770444Z","caller":"traceutil/trace.go:171","msg":"trace[369568198] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"308.603557ms","start":"2026-06-09T22:20:29.461798Z","end":"2026-06-09T22:20:29.770402Z","steps":["trace[369568198] 'process raft request' (duration: 308.542514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.770527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:29.336978Z","time spent":"433.459747ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:29.770583Z","caller":"traceutil/trace.go:171","msg":"trace[2027320113] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"433.432945ms","start":"2026-06-09T22:20:29.337107Z","end":"2026-06-09T22:20:29.77054Z","steps":["trace[2027320113] 'process raft request' (duration: 433.144012ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:20:29.770681Z","caller":"traceutil/trace.go:171","msg":"trace[1206597174] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"433.567421ms","start":"2026-06-09T22:20:29.337107Z","end":"2026-06-09T22:20:29.770674Z","steps":["trace[1206597174] 'process raft request' (duration: 433.174073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.770782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:29.337086Z","time spent":"433.618093ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:20:29.77085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:29.336613Z","time spent":"434.079965ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:20:29.770824Z","caller":"traceutil/trace.go:171","msg":"trace[1751864799] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"433.632324ms","start":"2026-06-09T22:20:29.337064Z","end":"2026-06-09T22:20:29.770697Z","steps":["trace[1751864799] 'process raft request' (duration: 433.08206ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:20:29.770914Z","caller":"traceutil/trace.go:171","msg":"trace[1743250131] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2267; }","duration":"365.373528ms","start":"2026-06-09T22:20:29.405531Z","end":"2026-06-09T22:20:29.770904Z","steps":["trace[1743250131] 'read index received' (duration: 314.2539ms)","trace[1743250131] 'applied index is now lower than readState.Index' (duration: 51.118138ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:20:29.771074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.538305ms","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-06-09T22:20:29.771129Z","caller":"traceutil/trace.go:171","msg":"trace[361495038] 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:2188; }","duration":"365.619669ms","start":"2026-06-09T22:20:29.405481Z","end":"2026-06-09T22:20:29.771101Z","steps":["trace[361495038] 'agreement among raft nodes before linearized reading' (duration: 365.511394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.771128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.932754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:20:29.771114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:29.337034Z","time spent":"433.923847ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:20:29.771157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:29.405461Z","time spent":"365.689102ms","remote":"127.0.0.1:42044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-06-09T22:20:29.771161Z","caller":"traceutil/trace.go:171","msg":"trace[1233880164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"255.005587ms","start":"2026-06-09T22:20:29.516148Z","end":"2026-06-09T22:20:29.771153Z","steps":["trace[1233880164] 'agreement among raft nodes before linearized reading' (duration: 254.948535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:20:29.77064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:29.461773Z","time spent":"308.762064ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:20:29.771513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:20:29.336925Z","time spent":"434.558007ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:21:54.290302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311568780271841196,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T22:21:54.462642Z","caller":"traceutil/trace.go:171","msg":"trace[2019130410] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"719.574023ms","start":"2026-06-09T22:21:53.743046Z","end":"2026-06-09T22:21:54.46262Z","steps":["trace[2019130410] 'process raft request' (duration: 719.350431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.462786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:21:53.743023Z","time spent":"719.681528ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:21:54.463268Z","caller":"traceutil/trace.go:171","msg":"trace[1557949057] linearizableReadLoop","detail":"{readStateIndex:2835; appliedIndex:2833; }","duration":"673.147702ms","start":"2026-06-09T22:21:53.790103Z","end":"2026-06-09T22:21:54.46325Z","steps":["trace[1557949057] 'read index received' (duration: 672.29878ms)","trace[1557949057] 'applied index is now lower than readState.Index' (duration: 848.112µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:21:54.463624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.473045ms","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-06-09T22:21:54.463651Z","caller":"traceutil/trace.go:171","msg":"trace[521276409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2731; }","duration":"198.530407ms","start":"2026-06-09T22:21:54.265115Z","end":"2026-06-09T22:21:54.463645Z","steps":["trace[521276409] 'agreement among raft nodes before linearized reading' (duration: 198.407391ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:54.463732Z","caller":"traceutil/trace.go:171","msg":"trace[212900511] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"241.649382ms","start":"2026-06-09T22:21:54.222078Z","end":"2026-06-09T22:21:54.463727Z","steps":["trace[212900511] 'process raft request' (duration: 241.066102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.463723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.373633ms","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-06-09T22:21:54.46383Z","caller":"traceutil/trace.go:171","msg":"trace[2041728030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2731; }","duration":"612.52179ms","start":"2026-06-09T22:21:53.851282Z","end":"2026-06-09T22:21:54.463804Z","steps":["trace[2041728030] 'agreement among raft nodes before linearized reading' (duration: 612.208935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.46388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:21:53.851272Z","time spent":"612.598464ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:21:54.463905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.810712ms","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-06-09T22:21:54.463955Z","caller":"traceutil/trace.go:171","msg":"trace[334691366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2731; }","duration":"198.892396ms","start":"2026-06-09T22:21:54.265051Z","end":"2026-06-09T22:21:54.463943Z","steps":["trace[334691366] 'agreement among raft nodes before linearized reading' (duration: 198.726878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.464089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.981744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T22:21:54.464126Z","caller":"traceutil/trace.go:171","msg":"trace[654052291] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2731; }","duration":"674.037967ms","start":"2026-06-09T22:21:53.790068Z","end":"2026-06-09T22:21:54.464106Z","steps":["trace[654052291] 'agreement among raft nodes before linearized reading' (duration: 673.748222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.464151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:21:53.790053Z","time spent":"674.086169ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":293,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:21:54.464178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.003872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-09T22:21:54.464194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.507654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:21:54.464214Z","caller":"traceutil/trace.go:171","msg":"trace[1181415004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2731; }","duration":"199.101567ms","start":"2026-06-09T22:21:54.265101Z","end":"2026-06-09T22:21:54.464203Z","steps":["trace[1181415004] 'agreement among raft nodes before linearized reading' (duration: 198.953709ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:54.464252Z","caller":"traceutil/trace.go:171","msg":"trace[169247810] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2731; }","duration":"534.589248ms","start":"2026-06-09T22:21:53.929651Z","end":"2026-06-09T22:21:54.464241Z","steps":["trace[169247810] 'agreement among raft nodes before linearized reading' (duration: 534.500844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.464287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:21:53.929642Z","time spent":"534.637481ms","remote":"127.0.0.1:32920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:21:54.468495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.519313ms","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-06-09T22:21:54.468551Z","caller":"traceutil/trace.go:171","msg":"trace[1478438491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2731; }","duration":"180.575286ms","start":"2026-06-09T22:21:54.287947Z","end":"2026-06-09T22:21:54.468522Z","steps":["trace[1478438491] 'agreement among raft nodes before linearized reading' (duration: 176.818235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.468693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.130147ms","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-06-09T22:21:54.468714Z","caller":"traceutil/trace.go:171","msg":"trace[1643306282] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2731; }","duration":"547.181179ms","start":"2026-06-09T22:21:53.921528Z","end":"2026-06-09T22:21:54.468709Z","steps":["trace[1643306282] 'agreement among raft nodes before linearized reading' (duration: 542.950994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:54.46873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:21:53.921514Z","time spent":"547.211511ms","remote":"127.0.0.1:32794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-09T22:21:54.468835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.833377ms","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-06-09T22:21:54.468858Z","caller":"traceutil/trace.go:171","msg":"trace[1440507848] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2731; }","duration":"167.874109ms","start":"2026-06-09T22:21:54.300977Z","end":"2026-06-09T22:21:54.468851Z","steps":["trace[1440507848] 'agreement among raft nodes before linearized reading' (duration: 163.760141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:22:31.796961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.562109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:22:31.797049Z","caller":"traceutil/trace.go:171","msg":"trace[1462097205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2940; }","duration":"282.739886ms","start":"2026-06-09T22:22:31.514291Z","end":"2026-06-09T22:22:31.797031Z","steps":["trace[1462097205] 'range keys from in-memory index tree' (duration: 282.48674ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:08.576328Z","caller":"traceutil/trace.go:171","msg":"trace[1384643795] linearizableReadLoop","detail":"{readStateIndex:4445; appliedIndex:4444; }","duration":"132.977303ms","start":"2026-06-09T22:26:08.443325Z","end":"2026-06-09T22:26:08.576302Z","steps":["trace[1384643795] 'read index received' (duration: 132.782987ms)","trace[1384643795] 'applied index is now lower than readState.Index' (duration: 193.236µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:08.576416Z","caller":"traceutil/trace.go:171","msg":"trace[1997406409] transaction","detail":"{read_only:false; response_revision:4272; number_of_response:1; }","duration":"178.817909ms","start":"2026-06-09T22:26:08.397539Z","end":"2026-06-09T22:26:08.576357Z","steps":["trace[1997406409] 'process raft request' (duration: 178.595472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:08.576499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.154559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:08.576715Z","caller":"traceutil/trace.go:171","msg":"trace[273687392] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4272; }","duration":"133.420488ms","start":"2026-06-09T22:26:08.443281Z","end":"2026-06-09T22:26:08.576702Z","steps":["trace[273687392] 'agreement among raft nodes before linearized reading' (duration: 133.158069ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:16.647022Z","caller":"traceutil/trace.go:171","msg":"trace[1028600790] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"152.471621ms","start":"2026-06-09T22:26:16.494506Z","end":"2026-06-09T22:26:16.646978Z","steps":["trace[1028600790] 'process raft request' (duration: 93.593619ms)","trace[1028600790] 'compare' (duration: 58.707586ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:18.811325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.992531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:18.811423Z","caller":"traceutil/trace.go:171","msg":"trace[1503017231] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:0; response_revision:4709; }","duration":"236.106325ms","start":"2026-06-09T22:26:18.575291Z","end":"2026-06-09T22:26:18.811397Z","steps":["trace[1503017231] 'range keys from in-memory index tree' (duration: 235.92675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:18.811602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.664379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-09T22:26:18.811727Z","caller":"traceutil/trace.go:171","msg":"trace[138428002] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4709; }","duration":"111.874446ms","start":"2026-06-09T22:26:18.699837Z","end":"2026-06-09T22:26:18.811711Z","steps":["trace[138428002] 'range keys from in-memory index tree' (duration: 111.465922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:18.812194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.749948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:18.812249Z","caller":"traceutil/trace.go:171","msg":"trace[258254854] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4709; }","duration":"112.81332ms","start":"2026-06-09T22:26:18.699427Z","end":"2026-06-09T22:26:18.81224Z","steps":["trace[258254854] 'count revisions from in-memory index tree' (duration: 112.668734ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:19.661837Z","caller":"traceutil/trace.go:171","msg":"trace[1384087920] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"142.737411ms","start":"2026-06-09T22:26:19.519076Z","end":"2026-06-09T22:26:19.661814Z","steps":["trace[1384087920] 'process raft request' (duration: 142.572005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.464007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311568780271846471,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T22:26:24.523467Z","caller":"traceutil/trace.go:171","msg":"trace[1364244423] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"581.573748ms","start":"2026-06-09T22:26:23.941857Z","end":"2026-06-09T22:26:24.523431Z","steps":["trace[1364244423] 'process raft request' (duration: 581.320779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.52368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:23.941827Z","time spent":"581.743954ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14482,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:24.563884Z","caller":"traceutil/trace.go:171","msg":"trace[617799310] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5034; }","duration":"600.181534ms","start":"2026-06-09T22:26:23.963674Z","end":"2026-06-09T22:26:24.563855Z","steps":["trace[617799310] 'read index received' (duration: 559.651279ms)","trace[617799310] 'applied index is now lower than readState.Index' (duration: 40.528635ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:24.564003Z","caller":"traceutil/trace.go:171","msg":"trace[1796283731] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"445.84436ms","start":"2026-06-09T22:26:24.118124Z","end":"2026-06-09T22:26:24.563968Z","steps":["trace[1796283731] 'process raft request' (duration: 445.420645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.564181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.11806Z","time spent":"446.011155ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:24.564282Z","caller":"traceutil/trace.go:171","msg":"trace[1700578106] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"405.297286ms","start":"2026-06-09T22:26:24.158935Z","end":"2026-06-09T22:26:24.564232Z","steps":["trace[1700578106] 'process raft request' (duration: 404.819659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.564466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.158912Z","time spent":"405.457822ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:26:24.564515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.849048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-09T22:26:24.564553Z","caller":"traceutil/trace.go:171","msg":"trace[547900696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4850; }","duration":"600.93185ms","start":"2026-06-09T22:26:23.96361Z","end":"2026-06-09T22:26:24.564542Z","steps":["trace[547900696] 'agreement among raft nodes before linearized reading' (duration: 600.746563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.56459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:23.963583Z","time spent":"601.001372ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T22:26:24.565051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.576576ms","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-06-09T22:26:24.565123Z","caller":"traceutil/trace.go:171","msg":"trace[1147186364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4851; }","duration":"496.701231ms","start":"2026-06-09T22:26:24.068407Z","end":"2026-06-09T22:26:24.565108Z","steps":["trace[1147186364] 'agreement among raft nodes before linearized reading' (duration: 496.486503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.565169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.06838Z","time spent":"496.780903ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T22:26:24.565103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.442187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-09T22:26:24.565398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.10385ms","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-06-09T22:26:24.565448Z","caller":"traceutil/trace.go:171","msg":"trace[1019268858] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4851; }","duration":"441.183503ms","start":"2026-06-09T22:26:24.124246Z","end":"2026-06-09T22:26:24.56543Z","steps":["trace[1019268858] 'agreement among raft nodes before linearized reading' (duration: 441.075669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.565485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.124227Z","time spent":"441.247966ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-09T22:26:24.565665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.724531ms","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-06-09T22:26:24.565651Z","caller":"traceutil/trace.go:171","msg":"trace[291668116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4851; }","duration":"441.015347ms","start":"2026-06-09T22:26:24.124618Z","end":"2026-06-09T22:26:24.565633Z","steps":["trace[291668116] 'agreement among raft nodes before linearized reading' (duration: 440.356144ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:24.565714Z","caller":"traceutil/trace.go:171","msg":"trace[725881543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4851; }","duration":"462.827364ms","start":"2026-06-09T22:26:24.102876Z","end":"2026-06-09T22:26:24.565703Z","steps":["trace[725881543] 'agreement among raft nodes before linearized reading' (duration: 462.71728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.565751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.102848Z","time spent":"462.896556ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T22:26:24.565887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.717842ms","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-06-09T22:26:24.565918Z","caller":"traceutil/trace.go:171","msg":"trace[833070386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4851; }","duration":"441.769224ms","start":"2026-06-09T22:26:24.124141Z","end":"2026-06-09T22:26:24.56591Z","steps":["trace[833070386] 'agreement among raft nodes before linearized reading' (duration: 441.680841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.565947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.12413Z","time spent":"441.810155ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:26:24.566047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.950863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:26:24.566078Z","caller":"traceutil/trace.go:171","msg":"trace[168985844] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4851; }","duration":"446.041397ms","start":"2026-06-09T22:26:24.120029Z","end":"2026-06-09T22:26:24.56607Z","steps":["trace[168985844] 'agreement among raft nodes before linearized reading' (duration: 445.986935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.566108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.120003Z","time spent":"446.094048ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:26:24.56614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.378109ms","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-06-09T22:26:24.566231Z","caller":"traceutil/trace.go:171","msg":"trace[1555880365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4851; }","duration":"441.549906ms","start":"2026-06-09T22:26:24.124659Z","end":"2026-06-09T22:26:24.566209Z","steps":["trace[1555880365] 'agreement among raft nodes before linearized reading' (duration: 441.213034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:24.566288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.124646Z","time spent":"441.63276ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:26:24.566547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:24.124611Z","time spent":"441.294637ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:26:30.741456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.170169ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:30.74163Z","caller":"traceutil/trace.go:171","msg":"trace[1918784944] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4905; }","duration":"401.382797ms","start":"2026-06-09T22:26:30.340222Z","end":"2026-06-09T22:26:30.741605Z","steps":["trace[1918784944] 'range keys from in-memory index tree' (duration: 401.154139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.741782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.286612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:30.741893Z","caller":"traceutil/trace.go:171","msg":"trace[84924149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4905; }","duration":"227.418987ms","start":"2026-06-09T22:26:30.51443Z","end":"2026-06-09T22:26:30.741849Z","steps":["trace[84924149] 'range keys from in-memory index tree' (duration: 227.140296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.742117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.081077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-06-09T22:26:30.742141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.123075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:30.742152Z","caller":"traceutil/trace.go:171","msg":"trace[901453980] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4905; }","duration":"492.157629ms","start":"2026-06-09T22:26:30.249983Z","end":"2026-06-09T22:26:30.742141Z","steps":["trace[901453980] 'range keys from in-memory index tree' (duration: 491.924261ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:30.742175Z","caller":"traceutil/trace.go:171","msg":"trace[971914322] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4905; }","duration":"331.178877ms","start":"2026-06-09T22:26:30.410983Z","end":"2026-06-09T22:26:30.742162Z","steps":["trace[971914322] 'range keys from in-memory index tree' (duration: 331.051152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.742207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:30.410948Z","time spent":"331.251449ms","remote":"127.0.0.1:59814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-06-09T22:26:30.742177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:30.249924Z","time spent":"492.246563ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-09T22:26:30.742716Z","caller":"traceutil/trace.go:171","msg":"trace[1087588421] linearizableReadLoop","detail":"{readStateIndex:5094; appliedIndex:5093; }","duration":"128.090896ms","start":"2026-06-09T22:26:30.614614Z","end":"2026-06-09T22:26:30.742704Z","steps":["trace[1087588421] 'read index received' (duration: 107.264426ms)","trace[1087588421] 'applied index is now lower than readState.Index' (duration: 20.82554ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:30.742916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.787981ms","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-06-09T22:26:30.742947Z","caller":"traceutil/trace.go:171","msg":"trace[1674306869] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4906; }","duration":"123.836923ms","start":"2026-06-09T22:26:30.619102Z","end":"2026-06-09T22:26:30.742939Z","steps":["trace[1674306869] 'agreement among raft nodes before linearized reading' (duration: 123.74798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.743021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.378836ms","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-06-09T22:26:30.743105Z","caller":"traceutil/trace.go:171","msg":"trace[1222767665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4906; }","duration":"128.497391ms","start":"2026-06-09T22:26:30.614586Z","end":"2026-06-09T22:26:30.743083Z","steps":["trace[1222767665] 'agreement among raft nodes before linearized reading' (duration: 128.21482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.743146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.188649ms","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-06-09T22:26:30.743169Z","caller":"traceutil/trace.go:171","msg":"trace[454153082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4906; }","duration":"108.22152ms","start":"2026-06-09T22:26:30.63494Z","end":"2026-06-09T22:26:30.743162Z","steps":["trace[454153082] 'agreement among raft nodes before linearized reading' (duration: 108.152568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.743173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.037403ms","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-06-09T22:26:30.743219Z","caller":"traceutil/trace.go:171","msg":"trace[321743363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4906; }","duration":"108.144587ms","start":"2026-06-09T22:26:30.635061Z","end":"2026-06-09T22:26:30.743206Z","steps":["trace[321743363] 'agreement among raft nodes before linearized reading' (duration: 107.992442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.743271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.665956ms","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-06-09T22:26:30.743292Z","caller":"traceutil/trace.go:171","msg":"trace[2068234545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4906; }","duration":"108.733279ms","start":"2026-06-09T22:26:30.634552Z","end":"2026-06-09T22:26:30.743285Z","steps":["trace[2068234545] 'agreement among raft nodes before linearized reading' (duration: 108.672006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.743554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.707228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T22:26:30.743579Z","caller":"traceutil/trace.go:171","msg":"trace[16391966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4906; }","duration":"113.729899ms","start":"2026-06-09T22:26:30.629843Z","end":"2026-06-09T22:26:30.743573Z","steps":["trace[16391966] 'agreement among raft nodes before linearized reading' (duration: 113.675507ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:30.743592Z","caller":"traceutil/trace.go:171","msg":"trace[222856886] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"148.824885ms","start":"2026-06-09T22:26:30.594754Z","end":"2026-06-09T22:26:30.743579Z","steps":["trace[222856886] 'process raft request' (duration: 127.091112ms)","trace[222856886] 'compare' (duration: 19.42612ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:30.746569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.057358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-b5db64dbd-4hf4k.18b78a127b35ae45\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-06-09T22:26:30.74663Z","caller":"traceutil/trace.go:171","msg":"trace[783391672] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-b5db64dbd-4hf4k.18b78a127b35ae45; range_end:; response_count:1; response_revision:4906; }","duration":"102.126521ms","start":"2026-06-09T22:26:30.644488Z","end":"2026-06-09T22:26:30.746614Z","steps":["trace[783391672] 'agreement among raft nodes before linearized reading' (duration: 99.237667ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:31.753377Z","caller":"traceutil/trace.go:171","msg":"trace[421194895] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"386.185587ms","start":"2026-06-09T22:26:31.367165Z","end":"2026-06-09T22:26:31.753351Z","steps":["trace[421194895] 'read index received' (duration: 385.881786ms)","trace[421194895] 'applied index is now lower than readState.Index' (duration: 302.88µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:31.753688Z","caller":"traceutil/trace.go:171","msg":"trace[1695542662] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"650.345965ms","start":"2026-06-09T22:26:31.103332Z","end":"2026-06-09T22:26:31.753678Z","steps":["trace[1695542662] 'process raft request' (duration: 649.808986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:31.753812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:31.1033Z","time spent":"650.429238ms","remote":"127.0.0.1:59814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2724,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:26:31.754064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.941976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:31.754164Z","caller":"traceutil/trace.go:171","msg":"trace[911242705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4923; }","duration":"237.128723ms","start":"2026-06-09T22:26:31.517014Z","end":"2026-06-09T22:26:31.754143Z","steps":["trace[911242705] 'agreement among raft nodes before linearized reading' (duration: 236.925836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:31.754302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.113589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:31.754435Z","caller":"traceutil/trace.go:171","msg":"trace[1605225760] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4923; }","duration":"387.313417ms","start":"2026-06-09T22:26:31.367102Z","end":"2026-06-09T22:26:31.754415Z","steps":["trace[1605225760] 'agreement among raft nodes before linearized reading' (duration: 387.093989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:31.754496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:31.367078Z","time spent":"387.40699ms","remote":"127.0.0.1:59862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:26:31.754538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.132641ms","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-06-09T22:26:31.754587Z","caller":"traceutil/trace.go:171","msg":"trace[2058843044] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4923; }","duration":"114.239325ms","start":"2026-06-09T22:26:31.640335Z","end":"2026-06-09T22:26:31.754575Z","steps":["trace[2058843044] 'agreement among raft nodes before linearized reading' (duration: 114.153852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:32.005878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.764603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:26:32.006269Z","caller":"traceutil/trace.go:171","msg":"trace[1904248441] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5112; }","duration":"155.241345ms","start":"2026-06-09T22:26:31.851008Z","end":"2026-06-09T22:26:32.006249Z","steps":["trace[1904248441] 'read index received' (duration: 42.633499ms)","trace[1904248441] 'applied index is now lower than readState.Index' (duration: 112.606746ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:32.006242Z","caller":"traceutil/trace.go:171","msg":"trace[1686351049] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"225.414259ms","start":"2026-06-09T22:26:31.780778Z","end":"2026-06-09T22:26:32.006192Z","steps":["trace[1686351049] 'process raft request' (duration: 35.205721ms)","trace[1686351049] 'compare' (duration: 189.628388ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:32.006306Z","caller":"traceutil/trace.go:171","msg":"trace[1000289998] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"225.093928ms","start":"2026-06-09T22:26:31.781185Z","end":"2026-06-09T22:26:32.006278Z","steps":["trace[1000289998] 'process raft request' (duration: 224.822919ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:32.006296Z","caller":"traceutil/trace.go:171","msg":"trace[766491509] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"224.886801ms","start":"2026-06-09T22:26:31.781371Z","end":"2026-06-09T22:26:32.006258Z","steps":["trace[766491509] 'process raft request' (duration: 224.729605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:32.006502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.499825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-09T22:26:32.006551Z","caller":"traceutil/trace.go:171","msg":"trace[8648475] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4928; }","duration":"155.586598ms","start":"2026-06-09T22:26:31.850947Z","end":"2026-06-09T22:26:32.006533Z","steps":["trace[8648475] 'agreement among raft nodes before linearized reading' (duration: 155.403991ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:32.006642Z","caller":"traceutil/trace.go:171","msg":"trace[658617987] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"204.210884ms","start":"2026-06-09T22:26:31.802418Z","end":"2026-06-09T22:26:32.006629Z","steps":["trace[658617987] 'process raft request' (duration: 203.783579ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:33.983816Z","caller":"traceutil/trace.go:171","msg":"trace[1655199693] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"166.824006ms","start":"2026-06-09T22:26:33.816958Z","end":"2026-06-09T22:26:33.983782Z","steps":["trace[1655199693] 'read index received' (duration: 166.490004ms)","trace[1655199693] 'applied index is now lower than readState.Index' (duration: 332.482µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:33.983902Z","caller":"traceutil/trace.go:171","msg":"trace[1347792537] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"179.124143ms","start":"2026-06-09T22:26:33.804734Z","end":"2026-06-09T22:26:33.983859Z","steps":["trace[1347792537] 'process raft request' (duration: 178.836553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:33.984157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.151378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-933d2e76-d803-45d8-8a4e-0f4ed1ef1747\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-09T22:26:33.984285Z","caller":"traceutil/trace.go:171","msg":"trace[191095541] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-933d2e76-d803-45d8-8a4e-0f4ed1ef1747; range_end:; response_count:1; response_revision:5006; }","duration":"167.344185ms","start":"2026-06-09T22:26:33.816916Z","end":"2026-06-09T22:26:33.98426Z","steps":["trace[191095541] 'agreement among raft nodes before linearized reading' (duration: 166.995563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:35.215995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.973737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:26:35.216086Z","caller":"traceutil/trace.go:171","msg":"trace[1809516391] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5018; }","duration":"205.095672ms","start":"2026-06-09T22:26:35.010972Z","end":"2026-06-09T22:26:35.216068Z","steps":["trace[1809516391] 'count revisions from in-memory index tree' (duration: 204.841463ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:37.198509Z","caller":"traceutil/trace.go:171","msg":"trace[445358497] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"109.464697ms","start":"2026-06-09T22:26:37.089004Z","end":"2026-06-09T22:26:37.198469Z","steps":["trace[445358497] 'process raft request' (duration: 74.639315ms)","trace[445358497] 'compare' (duration: 34.700378ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:41.048422Z","caller":"traceutil/trace.go:171","msg":"trace[2106310977] linearizableReadLoop","detail":"{readStateIndex:5301; appliedIndex:5300; }","duration":"158.664717ms","start":"2026-06-09T22:26:40.88973Z","end":"2026-06-09T22:26:41.048395Z","steps":["trace[2106310977] 'read index received' (duration: 157.147682ms)","trace[2106310977] 'applied index is now lower than readState.Index' (duration: 1.515525ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:41.048773Z","caller":"traceutil/trace.go:171","msg":"trace[1852707457] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"160.753033ms","start":"2026-06-09T22:26:40.888006Z","end":"2026-06-09T22:26:41.048759Z","steps":["trace[1852707457] 'process raft request' (duration: 158.77105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.049101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.356123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T22:26:41.04915Z","caller":"traceutil/trace.go:171","msg":"trace[2109533330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5107; }","duration":"159.462936ms","start":"2026-06-09T22:26:40.889676Z","end":"2026-06-09T22:26:41.049139Z","steps":["trace[2109533330] 'agreement among raft nodes before linearized reading' (duration: 159.30589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.049342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.31875ms","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-06-09T22:26:41.049381Z","caller":"traceutil/trace.go:171","msg":"trace[144038492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5107; }","duration":"153.404554ms","start":"2026-06-09T22:26:40.895968Z","end":"2026-06-09T22:26:41.049373Z","steps":["trace[144038492] 'agreement among raft nodes before linearized reading' (duration: 153.30446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.049802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.207249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-06-09T22:26:41.049847Z","caller":"traceutil/trace.go:171","msg":"trace[126624181] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5107; }","duration":"116.278212ms","start":"2026-06-09T22:26:40.933557Z","end":"2026-06-09T22:26:41.049836Z","steps":["trace[126624181] 'agreement among raft nodes before linearized reading' (duration: 116.179228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.050067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.584841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17235"} {"level":"info","ts":"2026-06-09T22:26:41.050109Z","caller":"traceutil/trace.go:171","msg":"trace[1151551947] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5107; }","duration":"138.683164ms","start":"2026-06-09T22:26:40.911417Z","end":"2026-06-09T22:26:41.0501Z","steps":["trace[1151551947] 'agreement among raft nodes before linearized reading' (duration: 138.5757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.05035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.383004ms","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-06-09T22:26:41.050389Z","caller":"traceutil/trace.go:171","msg":"trace[1519118775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5107; }","duration":"150.471956ms","start":"2026-06-09T22:26:40.899909Z","end":"2026-06-09T22:26:41.050381Z","steps":["trace[1519118775] 'agreement among raft nodes before linearized reading' (duration: 150.348942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.050547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.370519ms","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-06-09T22:26:41.050609Z","caller":"traceutil/trace.go:171","msg":"trace[58872684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5107; }","duration":"151.448112ms","start":"2026-06-09T22:26:40.899148Z","end":"2026-06-09T22:26:41.050596Z","steps":["trace[58872684] 'agreement among raft nodes before linearized reading' (duration: 151.311427ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.287769Z","caller":"traceutil/trace.go:171","msg":"trace[1859127222] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5332; }","duration":"370.521621ms","start":"2026-06-09T22:26:44.917221Z","end":"2026-06-09T22:26:45.287743Z","steps":["trace[1859127222] 'read index received' (duration: 370.380026ms)","trace[1859127222] 'applied index is now lower than readState.Index' (duration: 140.135µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:45.287812Z","caller":"traceutil/trace.go:171","msg":"trace[1530539672] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"376.839054ms","start":"2026-06-09T22:26:44.910943Z","end":"2026-06-09T22:26:45.287782Z","steps":["trace[1530539672] 'process raft request' (duration: 376.608115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.288056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.818031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-09T22:26:45.288107Z","caller":"traceutil/trace.go:171","msg":"trace[854265246] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5138; }","duration":"370.911905ms","start":"2026-06-09T22:26:44.917181Z","end":"2026-06-09T22:26:45.288093Z","steps":["trace[854265246] 'agreement among raft nodes before linearized reading' (duration: 370.706717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.288116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:44.910922Z","time spent":"376.966288ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:26:45.288146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:44.917161Z","time spent":"370.975218ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-06-09T22:26:45.288324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.454956ms","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-06-09T22:26:45.288469Z","caller":"traceutil/trace.go:171","msg":"trace[80722248] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5138; }","duration":"175.582231ms","start":"2026-06-09T22:26:45.11283Z","end":"2026-06-09T22:26:45.288412Z","steps":["trace[80722248] 'agreement among raft nodes before linearized reading' (duration: 175.444196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.288577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.718305ms","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-06-09T22:26:45.288595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.135971ms","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-06-09T22:26:45.288674Z","caller":"traceutil/trace.go:171","msg":"trace[1921667057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5138; }","duration":"223.296297ms","start":"2026-06-09T22:26:45.065363Z","end":"2026-06-09T22:26:45.288659Z","steps":["trace[1921667057] 'agreement among raft nodes before linearized reading' (duration: 223.12342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.28864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.947352ms","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-06-09T22:26:45.288753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.97204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-09T22:26:45.288777Z","caller":"traceutil/trace.go:171","msg":"trace[1551949307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5138; }","duration":"207.139489ms","start":"2026-06-09T22:26:45.081609Z","end":"2026-06-09T22:26:45.288748Z","steps":["trace[1551949307] 'agreement among raft nodes before linearized reading' (duration: 206.764765ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.288806Z","caller":"traceutil/trace.go:171","msg":"trace[1570596635] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5138; }","duration":"344.038173ms","start":"2026-06-09T22:26:44.944755Z","end":"2026-06-09T22:26:45.288793Z","steps":["trace[1570596635] 'agreement among raft nodes before linearized reading' (duration: 343.888457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.288854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:44.944745Z","time spent":"344.101485ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T22:26:45.288358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.321921ms","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-06-09T22:26:45.288729Z","caller":"traceutil/trace.go:171","msg":"trace[2041767163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5138; }","duration":"212.822979ms","start":"2026-06-09T22:26:45.075823Z","end":"2026-06-09T22:26:45.288646Z","steps":["trace[2041767163] 'agreement among raft nodes before linearized reading' (duration: 212.56861ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.288961Z","caller":"traceutil/trace.go:171","msg":"trace[238775175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5138; }","duration":"215.972225ms","start":"2026-06-09T22:26:45.072973Z","end":"2026-06-09T22:26:45.288945Z","steps":["trace[238775175] 'agreement among raft nodes before linearized reading' (duration: 215.244619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.289082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.861184ms","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-06-09T22:26:45.289187Z","caller":"traceutil/trace.go:171","msg":"trace[1153174649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5138; }","duration":"212.02711ms","start":"2026-06-09T22:26:45.077137Z","end":"2026-06-09T22:26:45.289164Z","steps":["trace[1153174649] 'agreement among raft nodes before linearized reading' (duration: 210.914248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.288211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.19314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-06-09T22:26:45.290255Z","caller":"traceutil/trace.go:171","msg":"trace[1093586532] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5138; }","duration":"352.318138ms","start":"2026-06-09T22:26:44.937917Z","end":"2026-06-09T22:26:45.290235Z","steps":["trace[1093586532] 'agreement among raft nodes before linearized reading' (duration: 350.057175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.2903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:44.937897Z","time spent":"352.386441ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-09T22:26:45.459454Z","caller":"traceutil/trace.go:171","msg":"trace[814993535] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"164.326173ms","start":"2026-06-09T22:26:45.295098Z","end":"2026-06-09T22:26:45.459424Z","steps":["trace[814993535] 'process raft request' (duration: 164.134156ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.648415Z","caller":"traceutil/trace.go:171","msg":"trace[938843240] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"353.116622ms","start":"2026-06-09T22:26:45.29525Z","end":"2026-06-09T22:26:45.648367Z","steps":["trace[938843240] 'process raft request' (duration: 302.285861ms)","trace[938843240] 'compare' (duration: 50.397516ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:45.648753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:45.295236Z","time spent":"353.375291ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:45.650941Z","caller":"traceutil/trace.go:171","msg":"trace[1617688154] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"355.223321ms","start":"2026-06-09T22:26:45.295681Z","end":"2026-06-09T22:26:45.650905Z","steps":["trace[1617688154] 'process raft request' (duration: 352.576912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.651259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:45.295672Z","time spent":"355.367515ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:45.652201Z","caller":"traceutil/trace.go:171","msg":"trace[372508362] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"356.31851ms","start":"2026-06-09T22:26:45.295869Z","end":"2026-06-09T22:26:45.652188Z","steps":["trace[372508362] 'process raft request' (duration: 354.94125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.652415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:45.295861Z","time spent":"356.381162ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:45.653173Z","caller":"traceutil/trace.go:171","msg":"trace[1331176530] linearizableReadLoop","detail":"{readStateIndex:5338; appliedIndex:5333; }","duration":"356.106763ms","start":"2026-06-09T22:26:45.29705Z","end":"2026-06-09T22:26:45.653157Z","steps":["trace[1331176530] 'read index received' (duration: 162.199325ms)","trace[1331176530] 'applied index is now lower than readState.Index' (duration: 193.906258ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:45.653507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.438485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-09T22:26:45.653573Z","caller":"traceutil/trace.go:171","msg":"trace[770622564] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"351.508493ms","start":"2026-06-09T22:26:45.302054Z","end":"2026-06-09T22:26:45.653562Z","steps":["trace[770622564] 'process raft request' (duration: 350.854029ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.653579Z","caller":"traceutil/trace.go:171","msg":"trace[45945874] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5144; }","duration":"356.522318ms","start":"2026-06-09T22:26:45.297035Z","end":"2026-06-09T22:26:45.653557Z","steps":["trace[45945874] 'agreement among raft nodes before linearized reading' (duration: 356.268858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.653697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:45.302019Z","time spent":"351.597766ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:45.653163Z","caller":"traceutil/trace.go:171","msg":"trace[1146604465] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"356.614872ms","start":"2026-06-09T22:26:45.296513Z","end":"2026-06-09T22:26:45.653128Z","steps":["trace[1146604465] 'process raft request' (duration: 355.622445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.65572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:45.296505Z","time spent":"359.141376ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:26:45.655948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.990713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:45.655994Z","caller":"traceutil/trace.go:171","msg":"trace[537146764] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5144; }","duration":"294.081037ms","start":"2026-06-09T22:26:45.3619Z","end":"2026-06-09T22:26:45.655981Z","steps":["trace[537146764] 'agreement among raft nodes before linearized reading' (duration: 294.008094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.65612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.839336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:45.656157Z","caller":"traceutil/trace.go:171","msg":"trace[39842379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5144; }","duration":"139.92085ms","start":"2026-06-09T22:26:45.516227Z","end":"2026-06-09T22:26:45.656148Z","steps":["trace[39842379] 'agreement among raft nodes before linearized reading' (duration: 139.857377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.656267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.137716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:45.656365Z","caller":"traceutil/trace.go:171","msg":"trace[1009042504] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5144; }","duration":"275.315282ms","start":"2026-06-09T22:26:45.381032Z","end":"2026-06-09T22:26:45.656348Z","steps":["trace[1009042504] 'agreement among raft nodes before linearized reading' (duration: 275.097104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.656398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.811093ms","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-06-09T22:26:45.656444Z","caller":"traceutil/trace.go:171","msg":"trace[1123831198] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5144; }","duration":"150.970469ms","start":"2026-06-09T22:26:45.505463Z","end":"2026-06-09T22:26:45.656433Z","steps":["trace[1123831198] 'agreement among raft nodes before linearized reading' (duration: 150.891726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.653695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:45.297029Z","time spent":"356.649543ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-09T22:26:45.850181Z","caller":"traceutil/trace.go:171","msg":"trace[85470616] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5339; }","duration":"186.524334ms","start":"2026-06-09T22:26:45.663604Z","end":"2026-06-09T22:26:45.850129Z","steps":["trace[85470616] 'read index received' (duration: 131.711996ms)","trace[85470616] 'applied index is now lower than readState.Index' (duration: 54.808728ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:45.850545Z","caller":"traceutil/trace.go:171","msg":"trace[684725929] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"187.170419ms","start":"2026-06-09T22:26:45.663299Z","end":"2026-06-09T22:26:45.85047Z","steps":["trace[684725929] 'process raft request' (duration: 131.994827ms)","trace[684725929] 'compare' (duration: 54.30462ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:45.850746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.085815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-09T22:26:45.850813Z","caller":"traceutil/trace.go:171","msg":"trace[321821367] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5145; }","duration":"187.215181ms","start":"2026-06-09T22:26:45.663579Z","end":"2026-06-09T22:26:45.850794Z","steps":["trace[321821367] 'agreement among raft nodes before linearized reading' (duration: 186.815506ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.947181Z","caller":"traceutil/trace.go:171","msg":"trace[2057414588] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"281.232181ms","start":"2026-06-09T22:26:45.665923Z","end":"2026-06-09T22:26:45.947155Z","steps":["trace[2057414588] 'process raft request' (duration: 280.91464ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.94728Z","caller":"traceutil/trace.go:171","msg":"trace[472297682] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"279.59091ms","start":"2026-06-09T22:26:45.667663Z","end":"2026-06-09T22:26:45.947254Z","steps":["trace[472297682] 'process raft request' (duration: 279.485446ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.947298Z","caller":"traceutil/trace.go:171","msg":"trace[253858438] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"280.196032ms","start":"2026-06-09T22:26:45.66706Z","end":"2026-06-09T22:26:45.947256Z","steps":["trace[253858438] 'process raft request' (duration: 279.987545ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:45.947534Z","caller":"traceutil/trace.go:171","msg":"trace[317576014] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"279.724125ms","start":"2026-06-09T22:26:45.667795Z","end":"2026-06-09T22:26:45.947519Z","steps":["trace[317576014] 'process raft request' (duration: 279.400423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.947711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.191907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-09T22:26:45.947806Z","caller":"traceutil/trace.go:171","msg":"trace[757317766] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5149; }","duration":"107.354413ms","start":"2026-06-09T22:26:45.840428Z","end":"2026-06-09T22:26:45.947782Z","steps":["trace[757317766] 'agreement among raft nodes before linearized reading' (duration: 107.099044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:45.948384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.697575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-933d2e76-d803-45d8-8a4e-0f4ed1ef1747\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-09T22:26:45.948432Z","caller":"traceutil/trace.go:171","msg":"trace[35337292] range","detail":"{range_begin:/registry/persistentvolumes/pvc-933d2e76-d803-45d8-8a4e-0f4ed1ef1747; range_end:; response_count:1; response_revision:5149; }","duration":"282.800589ms","start":"2026-06-09T22:26:45.665614Z","end":"2026-06-09T22:26:45.948414Z","steps":["trace[35337292] 'agreement among raft nodes before linearized reading' (duration: 282.497218ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:46.064345Z","caller":"traceutil/trace.go:171","msg":"trace[82179149] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"106.889952ms","start":"2026-06-09T22:26:45.957403Z","end":"2026-06-09T22:26:46.064293Z","steps":["trace[82179149] 'process raft request' (duration: 84.868105ms)","trace[82179149] 'compare' (duration: 21.702064ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:46.074684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.761667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13291"} {"level":"info","ts":"2026-06-09T22:26:46.074741Z","caller":"traceutil/trace.go:171","msg":"trace[1738003265] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5151; }","duration":"106.85185ms","start":"2026-06-09T22:26:45.967873Z","end":"2026-06-09T22:26:46.074725Z","steps":["trace[1738003265] 'agreement among raft nodes before linearized reading' (duration: 106.599371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:46.428092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.212225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:46.428217Z","caller":"traceutil/trace.go:171","msg":"trace[1987106793] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:5153; }","duration":"179.440724ms","start":"2026-06-09T22:26:46.248747Z","end":"2026-06-09T22:26:46.428188Z","steps":["trace[1987106793] 'count revisions from in-memory index tree' (duration: 179.039399ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:14.096563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2844} {"level":"info","ts":"2026-06-09T22:27:14.187906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2844,"took":"89.664492ms","hash":3158248138,"current-db-size-bytes":50733056,"current-db-size":"51 MB","current-db-size-in-use-bytes":27357184,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-09T22:27:14.187966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3158248138,"revision":2844,"compact-revision":-1} {"level":"info","ts":"2026-06-09T22:27:26.469183Z","caller":"traceutil/trace.go:171","msg":"trace[242619158] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"215.851536ms","start":"2026-06-09T22:27:26.25329Z","end":"2026-06-09T22:27:26.469141Z","steps":["trace[242619158] 'process raft request' (duration: 215.631356ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:26.496044Z","caller":"traceutil/trace.go:171","msg":"trace[633446072] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"234.429483ms","start":"2026-06-09T22:27:26.261593Z","end":"2026-06-09T22:27:26.496023Z","steps":["trace[633446072] 'process raft request' (duration: 234.301868ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:44.721804Z","caller":"traceutil/trace.go:171","msg":"trace[895289159] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"155.688095ms","start":"2026-06-09T22:27:44.566081Z","end":"2026-06-09T22:27:44.721769Z","steps":["trace[895289159] 'process raft request' (duration: 155.175265ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:30:33.183625Z","caller":"traceutil/trace.go:171","msg":"trace[325611029] transaction","detail":"{read_only:false; response_revision:6782; number_of_response:1; }","duration":"121.627245ms","start":"2026-06-09T22:30:33.061964Z","end":"2026-06-09T22:30:33.183592Z","steps":["trace[325611029] 'process raft request' (duration: 121.326972ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:30:33.183908Z","caller":"traceutil/trace.go:171","msg":"trace[1042532843] linearizableReadLoop","detail":"{readStateIndex:7034; appliedIndex:7031; }","duration":"107.848685ms","start":"2026-06-09T22:30:33.07602Z","end":"2026-06-09T22:30:33.183869Z","steps":["trace[1042532843] 'read index received' (duration: 49.820439ms)","trace[1042532843] 'applied index is now lower than readState.Index' (duration: 58.022165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:30:33.184067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.99986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:30:33.184139Z","caller":"traceutil/trace.go:171","msg":"trace[1202650434] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:6783; }","duration":"108.168009ms","start":"2026-06-09T22:30:33.075959Z","end":"2026-06-09T22:30:33.184127Z","steps":["trace[1202650434] 'agreement among raft nodes before linearized reading' (duration: 107.99426ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:30:33.183837Z","caller":"traceutil/trace.go:171","msg":"trace[1804660606] transaction","detail":"{read_only:false; response_revision:6783; number_of_response:1; }","duration":"118.53575ms","start":"2026-06-09T22:30:33.065254Z","end":"2026-06-09T22:30:33.18379Z","steps":["trace[1804660606] 'process raft request' (duration: 118.270429ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:30:55.073676Z","caller":"traceutil/trace.go:171","msg":"trace[827570922] linearizableReadLoop","detail":"{readStateIndex:7204; appliedIndex:7203; }","duration":"116.1518ms","start":"2026-06-09T22:30:54.957503Z","end":"2026-06-09T22:30:55.073655Z","steps":["trace[827570922] 'read index received' (duration: 116.04558ms)","trace[827570922] 'applied index is now lower than readState.Index' (duration: 105.4µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:30:55.074109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.575628ms","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-06-09T22:30:55.075592Z","caller":"traceutil/trace.go:171","msg":"trace[930625507] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6946; }","duration":"118.084021ms","start":"2026-06-09T22:30:54.957489Z","end":"2026-06-09T22:30:55.075573Z","steps":["trace[930625507] 'agreement among raft nodes before linearized reading' (duration: 116.519568ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:30:55.074273Z","caller":"traceutil/trace.go:171","msg":"trace[1906787740] transaction","detail":"{read_only:false; response_revision:6946; number_of_response:1; }","duration":"117.762272ms","start":"2026-06-09T22:30:54.956498Z","end":"2026-06-09T22:30:55.07426Z","steps":["trace[1906787740] 'process raft request' (duration: 116.960716ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:31:01.552619Z","caller":"traceutil/trace.go:171","msg":"trace[163052522] linearizableReadLoop","detail":"{readStateIndex:7239; appliedIndex:7238; }","duration":"309.219551ms","start":"2026-06-09T22:31:01.243371Z","end":"2026-06-09T22:31:01.552591Z","steps":["trace[163052522] 'read index received' (duration: 308.904903ms)","trace[163052522] 'applied index is now lower than readState.Index' (duration: 313.578µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:31:01.5534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.998698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107251"} {"level":"info","ts":"2026-06-09T22:31:01.553539Z","caller":"traceutil/trace.go:171","msg":"trace[1834467284] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6980; }","duration":"310.159459ms","start":"2026-06-09T22:31:01.24336Z","end":"2026-06-09T22:31:01.55352Z","steps":["trace[1834467284] 'agreement among raft nodes before linearized reading' (duration: 309.56476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:01.553607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:31:01.243291Z","time spent":"310.304669ms","remote":"127.0.0.1:60908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":107273,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"info","ts":"2026-06-09T22:31:01.552877Z","caller":"traceutil/trace.go:171","msg":"trace[1980538679] transaction","detail":"{read_only:false; response_revision:6980; number_of_response:1; }","duration":"337.992816ms","start":"2026-06-09T22:31:01.214865Z","end":"2026-06-09T22:31:01.552858Z","steps":["trace[1980538679] 'process raft request' (duration: 337.540188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:01.554139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:31:01.214848Z","time spent":"339.141283ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:31:01.667934Z","caller":"traceutil/trace.go:171","msg":"trace[1425441574] linearizableReadLoop","detail":"{readStateIndex:7240; appliedIndex:7239; }","duration":"115.18436ms","start":"2026-06-09T22:31:01.552699Z","end":"2026-06-09T22:31:01.667883Z","steps":["trace[1425441574] 'read index received' (duration: 84.597811ms)","trace[1425441574] 'applied index is now lower than readState.Index' (duration: 30.585249ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:31:01.668111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.840863ms","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-06-09T22:31:01.668225Z","caller":"traceutil/trace.go:171","msg":"trace[1404961844] transaction","detail":"{read_only:false; response_revision:6981; number_of_response:1; }","duration":"115.898586ms","start":"2026-06-09T22:31:01.552305Z","end":"2026-06-09T22:31:01.668203Z","steps":["trace[1404961844] 'process raft request' (duration: 84.928869ms)","trace[1404961844] 'compare' (duration: 30.445469ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:31:01.668268Z","caller":"traceutil/trace.go:171","msg":"trace[1891482934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6981; }","duration":"287.010992ms","start":"2026-06-09T22:31:01.381218Z","end":"2026-06-09T22:31:01.668229Z","steps":["trace[1891482934] 'agreement among raft nodes before linearized reading' (duration: 286.770123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:01.668249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.387018ms","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-06-09T22:31:01.66833Z","caller":"traceutil/trace.go:171","msg":"trace[1701269399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6981; }","duration":"307.513438ms","start":"2026-06-09T22:31:01.360798Z","end":"2026-06-09T22:31:01.668312Z","steps":["trace[1701269399] 'agreement among raft nodes before linearized reading' (duration: 307.233859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:01.668386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:31:01.360787Z","time spent":"307.590979ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:31:01.668479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.248378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:31:01.668597Z","caller":"traceutil/trace.go:171","msg":"trace[969408847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6981; }","duration":"151.402288ms","start":"2026-06-09T22:31:01.51715Z","end":"2026-06-09T22:31:01.668553Z","steps":["trace[969408847] 'agreement among raft nodes before linearized reading' (duration: 151.229429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:01.668665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.566171ms","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-06-09T22:31:01.668706Z","caller":"traceutil/trace.go:171","msg":"trace[1750684063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6981; }","duration":"320.68261ms","start":"2026-06-09T22:31:01.348013Z","end":"2026-06-09T22:31:01.668696Z","steps":["trace[1750684063] 'agreement among raft nodes before linearized reading' (duration: 320.105202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:01.668735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:31:01.347957Z","time spent":"320.76673ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:31:01.669152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.052869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-09T22:31:01.669221Z","caller":"traceutil/trace.go:171","msg":"trace[722409111] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6981; }","duration":"406.120348ms","start":"2026-06-09T22:31:01.26309Z","end":"2026-06-09T22:31:01.66921Z","steps":["trace[722409111] 'agreement among raft nodes before linearized reading' (duration: 404.911492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:01.669297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:31:01.263082Z","time spent":"406.179158ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-09T22:32:14.104017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5338} {"level":"info","ts":"2026-06-09T22:32:14.190236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5338,"took":"84.28324ms","hash":2758306833,"current-db-size-bytes":50733056,"current-db-size":"51 MB","current-db-size-in-use-bytes":18509824,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-09T22:32:14.19033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2758306833,"revision":5338,"compact-revision":2844}