{"level":"warn","ts":"2026-04-22T06:04:45.307337Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:04:45.307457Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T06:04:45.307546Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:04:45.307555Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-22T06:04:45.307575Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:04:45.308352Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-04-22T06:04:45.308432Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T06:04:45.313029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.226124ms"} {"level":"info","ts":"2026-04-22T06:04:45.320085Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-04-22T06:04:45.320238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T06:04:45.320338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-04-22T06:04:45.320368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T06:04:45.320392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-04-22T06:04:45.320462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-04-22T06:04:45.328022Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T06:04:45.330938Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T06:04:45.33251Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T06:04:45.335969Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T06:04:45.336113Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T06:04:45.336081Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:04:45.336225Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:04:45.336242Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:04:45.339737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-04-22T06:04:45.339914Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-22T06:04:45.341867Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:04:45.342017Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-22T06:04:45.342107Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-22T06:04:45.342308Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T06:04:45.342351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T06:04:45.521577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T06:04:45.521704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T06:04:45.521753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-04-22T06:04:45.521782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-04-22T06:04:45.5218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-22T06:04:45.521825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-04-22T06:04:45.521851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-22T06:04:45.523845Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:45.525654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:04:45.525698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:04:45.525655Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T06:04:45.526089Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T06:04:45.526157Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T06:04:45.526289Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:45.526466Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:45.52653Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:45.529898Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-04-22T06:04:45.530304Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T06:05:33.112454Z","caller":"traceutil/trace.go:171","msg":"trace[713841923] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"149.932559ms","start":"2026-04-22T06:05:32.96247Z","end":"2026-04-22T06:05:33.112402Z","steps":["trace[713841923] 'process raft request' (duration: 147.405476ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:35.929042Z","caller":"traceutil/trace.go:171","msg":"trace[253082728] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"461.005307ms","start":"2026-04-22T06:05:35.468001Z","end":"2026-04-22T06:05:35.929006Z","steps":["trace[253082728] 'read index received' (duration: 460.812054ms)","trace[253082728] 'applied index is now lower than readState.Index' (duration: 191.573µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:35.929328Z","caller":"traceutil/trace.go:171","msg":"trace[112651144] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"493.609841ms","start":"2026-04-22T06:05:35.435697Z","end":"2026-04-22T06:05:35.929307Z","steps":["trace[112651144] 'process raft request' (duration: 493.062722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:35.929599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.908554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T06:05:35.929529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.381073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j5ccx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T06:05:35.929648Z","caller":"traceutil/trace.go:171","msg":"trace[487716987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"275.975455ms","start":"2026-04-22T06:05:35.653655Z","end":"2026-04-22T06:05:35.92963Z","steps":["trace[487716987] 'agreement among raft nodes before linearized reading' (duration: 275.814213ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:35.929672Z","caller":"traceutil/trace.go:171","msg":"trace[266745519] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j5ccx; range_end:; response_count:1; response_revision:654; }","duration":"362.561175ms","start":"2026-04-22T06:05:35.567084Z","end":"2026-04-22T06:05:35.929645Z","steps":["trace[266745519] 'agreement among raft nodes before linearized reading' (duration: 362.285181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:35.929669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.635528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T06:05:35.929798Z","caller":"traceutil/trace.go:171","msg":"trace[656175525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"461.79055ms","start":"2026-04-22T06:05:35.467968Z","end":"2026-04-22T06:05:35.929759Z","steps":["trace[656175525] 'agreement among raft nodes before linearized reading' (duration: 461.457855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:35.929851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:35.467957Z","time spent":"461.883143ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:05:35.929754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:35.567075Z","time spent":"362.666587ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-j5ccx\" "} {"level":"warn","ts":"2026-04-22T06:05:35.930725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:35.435677Z","time spent":"493.709492ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:36.492336Z","caller":"traceutil/trace.go:171","msg":"trace[431464141] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"557.303599ms","start":"2026-04-22T06:05:35.935006Z","end":"2026-04-22T06:05:36.492309Z","steps":["trace[431464141] 'process raft request' (duration: 515.687258ms)","trace[431464141] 'compare' (duration: 41.385847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:05:36.492497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:35.934982Z","time spent":"557.426222ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:36.497574Z","caller":"traceutil/trace.go:171","msg":"trace[822329609] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"560.408741ms","start":"2026-04-22T06:05:35.937135Z","end":"2026-04-22T06:05:36.497543Z","steps":["trace[822329609] 'process raft request' (duration: 560.152647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:36.49785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:35.93711Z","time spent":"560.553623ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:36.498019Z","caller":"traceutil/trace.go:171","msg":"trace[1425619375] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"182.860831ms","start":"2026-04-22T06:05:36.315139Z","end":"2026-04-22T06:05:36.498Z","steps":["trace[1425619375] 'read index received' (duration: 135.584644ms)","trace[1425619375] 'applied index is now lower than readState.Index' (duration: 47.274877ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:36.498081Z","caller":"traceutil/trace.go:171","msg":"trace[479411975] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"552.521447ms","start":"2026-04-22T06:05:35.945539Z","end":"2026-04-22T06:05:36.49806Z","steps":["trace[479411975] 'process raft request' (duration: 551.904637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:36.498098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.969302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T06:05:36.498139Z","caller":"traceutil/trace.go:171","msg":"trace[1061944515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"183.047814ms","start":"2026-04-22T06:05:36.31508Z","end":"2026-04-22T06:05:36.498128Z","steps":["trace[1061944515] 'agreement among raft nodes before linearized reading' (duration: 182.981163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:36.498216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:35.94551Z","time spent":"552.620668ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:38.835406Z","caller":"traceutil/trace.go:171","msg":"trace[14229683] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"117.327128ms","start":"2026-04-22T06:05:38.718052Z","end":"2026-04-22T06:05:38.835379Z","steps":["trace[14229683] 'process raft request' (duration: 116.582025ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:38.872856Z","caller":"traceutil/trace.go:171","msg":"trace[2034859505] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"103.953396ms","start":"2026-04-22T06:05:38.768884Z","end":"2026-04-22T06:05:38.872837Z","steps":["trace[2034859505] 'process raft request' (duration: 103.784313ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:48.704345Z","caller":"traceutil/trace.go:171","msg":"trace[997562623] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"148.730069ms","start":"2026-04-22T06:05:48.555595Z","end":"2026-04-22T06:05:48.704325Z","steps":["trace[997562623] 'read index received' (duration: 148.614808ms)","trace[997562623] 'applied index is now lower than readState.Index' (duration: 113.911µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:48.704435Z","caller":"traceutil/trace.go:171","msg":"trace[1040930799] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"150.428155ms","start":"2026-04-22T06:05:48.553964Z","end":"2026-04-22T06:05:48.704392Z","steps":["trace[1040930799] 'process raft request' (duration: 150.230103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:48.704515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.909382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T06:05:48.704551Z","caller":"traceutil/trace.go:171","msg":"trace[2017773246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"148.989314ms","start":"2026-04-22T06:05:48.555552Z","end":"2026-04-22T06:05:48.704541Z","steps":["trace[2017773246] 'agreement among raft nodes before linearized reading' (duration: 148.866862ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:48.708212Z","caller":"traceutil/trace.go:171","msg":"trace[106688364] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"150.503718ms","start":"2026-04-22T06:05:48.557688Z","end":"2026-04-22T06:05:48.708192Z","steps":["trace[106688364] 'process raft request' (duration: 150.270484ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:48.70831Z","caller":"traceutil/trace.go:171","msg":"trace[2137498553] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"144.901371ms","start":"2026-04-22T06:05:48.563393Z","end":"2026-04-22T06:05:48.708295Z","steps":["trace[2137498553] 'process raft request' (duration: 144.681738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.662788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.676326ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:05:52.663035Z","caller":"traceutil/trace.go:171","msg":"trace[1508808869] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:807; }","duration":"268.945831ms","start":"2026-04-22T06:05:52.394063Z","end":"2026-04-22T06:05:52.663009Z","steps":["trace[1508808869] 'range keys from in-memory index tree' (duration: 268.656155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.663633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.416702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:05:52.663957Z","caller":"traceutil/trace.go:171","msg":"trace[1634625265] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.310859831s","start":"2026-04-22T06:05:51.35308Z","end":"2026-04-22T06:05:52.66394Z","steps":["trace[1634625265] 'process raft request' (duration: 959.556522ms)","trace[1634625265] 'compare' (duration: 350.573219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:05:52.664091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:51.353051Z","time spent":"1.310985463s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:52.664874Z","caller":"traceutil/trace.go:171","msg":"trace[1516747727] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:838; }","duration":"676.340855ms","start":"2026-04-22T06:05:51.98848Z","end":"2026-04-22T06:05:52.664821Z","steps":["trace[1516747727] 'read index received' (duration: 323.32382ms)","trace[1516747727] 'applied index is now lower than readState.Index' (duration: 353.014885ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:52.665155Z","caller":"traceutil/trace.go:171","msg":"trace[538179184] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"969.159413ms","start":"2026-04-22T06:05:51.695964Z","end":"2026-04-22T06:05:52.665124Z","steps":["trace[538179184] 'process raft request' (duration: 968.524193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.66531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:51.695937Z","time spent":"969.309084ms","remote":"127.0.0.1:51820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:05:52.665614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.115056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:05:52.665977Z","caller":"traceutil/trace.go:171","msg":"trace[1236992859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"677.241547ms","start":"2026-04-22T06:05:51.98842Z","end":"2026-04-22T06:05:52.665661Z","steps":["trace[1236992859] 'agreement among raft nodes before linearized reading' (duration: 677.118615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.666125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:51.988371Z","time spent":"677.738466ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T06:05:52.677498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.755715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-22T06:05:52.677514Z","caller":"traceutil/trace.go:171","msg":"trace[1842118740] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"325.853267ms","start":"2026-04-22T06:05:52.351633Z","end":"2026-04-22T06:05:52.677486Z","steps":["trace[1842118740] 'process raft request' (duration: 325.614603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.677486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.113823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:05:52.677559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.815081ms","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-04-22T06:05:52.677567Z","caller":"traceutil/trace.go:171","msg":"trace[385960582] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:810; }","duration":"366.223925ms","start":"2026-04-22T06:05:52.311335Z","end":"2026-04-22T06:05:52.677559Z","steps":["trace[385960582] 'agreement among raft nodes before linearized reading' (duration: 366.020622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.677595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:52.3113Z","time spent":"366.286606ms","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:05:52.677596Z","caller":"traceutil/trace.go:171","msg":"trace[1801303450] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:810; }","duration":"136.880551ms","start":"2026-04-22T06:05:52.540701Z","end":"2026-04-22T06:05:52.677581Z","steps":["trace[1801303450] 'agreement among raft nodes before linearized reading' (duration: 136.83073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.6776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:52.351606Z","time spent":"325.957268ms","remote":"127.0.0.1:51748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:52.677542Z","caller":"traceutil/trace.go:171","msg":"trace[1205788722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"661.848335ms","start":"2026-04-22T06:05:52.015683Z","end":"2026-04-22T06:05:52.677532Z","steps":["trace[1205788722] 'agreement among raft nodes before linearized reading' (duration: 661.697223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.677693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:52.015672Z","time spent":"662.014209ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:05:52.677816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.93311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T06:05:52.677857Z","caller":"traceutil/trace.go:171","msg":"trace[1381681638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"634.972041ms","start":"2026-04-22T06:05:52.042872Z","end":"2026-04-22T06:05:52.677844Z","steps":["trace[1381681638] 'agreement among raft nodes before linearized reading' (duration: 634.750318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.677879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:52.042846Z","time spent":"635.028421ms","remote":"127.0.0.1:51850","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-04-22T06:05:56.037048Z","caller":"traceutil/trace.go:171","msg":"trace[1002381486] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"143.218382ms","start":"2026-04-22T06:05:55.8938Z","end":"2026-04-22T06:05:56.037019Z","steps":["trace[1002381486] 'process raft request' (duration: 142.582713ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:17.092766Z","caller":"traceutil/trace.go:171","msg":"trace[1360870477] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"217.757755ms","start":"2026-04-22T06:06:16.874982Z","end":"2026-04-22T06:06:17.09274Z","steps":["trace[1360870477] 'process raft request' (duration: 213.003062ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:17.123051Z","caller":"traceutil/trace.go:171","msg":"trace[308302668] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1222; }","duration":"241.32515ms","start":"2026-04-22T06:06:16.881704Z","end":"2026-04-22T06:06:17.123029Z","steps":["trace[308302668] 'read index received' (duration: 206.192863ms)","trace[308302668] 'applied index is now lower than readState.Index' (duration: 35.131247ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:17.123267Z","caller":"traceutil/trace.go:171","msg":"trace[1433533690] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"242.366893ms","start":"2026-04-22T06:06:16.880765Z","end":"2026-04-22T06:06:17.123132Z","steps":["trace[1433533690] 'process raft request' (duration: 242.140501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:17.123401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.360297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T06:06:17.123452Z","caller":"traceutil/trace.go:171","msg":"trace[2133564816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1188; }","duration":"220.456037ms","start":"2026-04-22T06:06:16.902984Z","end":"2026-04-22T06:06:17.12344Z","steps":["trace[2133564816] 'agreement among raft nodes before linearized reading' (duration: 220.297035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:17.123431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.712026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T06:06:17.1236Z","caller":"traceutil/trace.go:171","msg":"trace[300980396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1188; }","duration":"241.932268ms","start":"2026-04-22T06:06:16.881655Z","end":"2026-04-22T06:06:17.123587Z","steps":["trace[300980396] 'agreement among raft nodes before linearized reading' (duration: 241.563294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:17.123679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.702002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T06:06:17.123714Z","caller":"traceutil/trace.go:171","msg":"trace[1925037858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1188; }","duration":"188.803844ms","start":"2026-04-22T06:06:16.934902Z","end":"2026-04-22T06:06:17.123706Z","steps":["trace[1925037858] 'agreement among raft nodes before linearized reading' (duration: 188.722623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:17.123606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.219015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:17.123808Z","caller":"traceutil/trace.go:171","msg":"trace[72586523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"136.448209ms","start":"2026-04-22T06:06:16.987335Z","end":"2026-04-22T06:06:17.123783Z","steps":["trace[72586523] 'agreement among raft nodes before linearized reading' (duration: 136.239316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:17.124474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.14254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T06:06:17.124566Z","caller":"traceutil/trace.go:171","msg":"trace[1231293800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"196.279561ms","start":"2026-04-22T06:06:16.928268Z","end":"2026-04-22T06:06:17.124547Z","steps":["trace[1231293800] 'agreement among raft nodes before linearized reading' (duration: 195.699524ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:23.367534Z","caller":"traceutil/trace.go:171","msg":"trace[1419443204] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"210.142146ms","start":"2026-04-22T06:06:23.157369Z","end":"2026-04-22T06:06:23.367511Z","steps":["trace[1419443204] 'process raft request' (duration: 210.011845ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:23.367568Z","caller":"traceutil/trace.go:171","msg":"trace[1220778776] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1252; }","duration":"209.257595ms","start":"2026-04-22T06:06:23.158274Z","end":"2026-04-22T06:06:23.367532Z","steps":["trace[1220778776] 'read index received' (duration: 209.245414ms)","trace[1220778776] 'applied index is now lower than readState.Index' (duration: 9.721µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:23.367927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.62845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-22T06:06:23.367976Z","caller":"traceutil/trace.go:171","msg":"trace[1145946884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"209.712531ms","start":"2026-04-22T06:06:23.158251Z","end":"2026-04-22T06:06:23.367963Z","steps":["trace[1145946884] 'agreement among raft nodes before linearized reading' (duration: 209.412396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:23.385164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.962235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-22T06:06:23.385264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.948965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T06:06:23.385278Z","caller":"traceutil/trace.go:171","msg":"trace[670133238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"203.121017ms","start":"2026-04-22T06:06:23.182131Z","end":"2026-04-22T06:06:23.385252Z","steps":["trace[670133238] 'agreement among raft nodes before linearized reading' (duration: 202.855394ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:23.385315Z","caller":"traceutil/trace.go:171","msg":"trace[826521680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"199.024135ms","start":"2026-04-22T06:06:23.186277Z","end":"2026-04-22T06:06:23.385302Z","steps":["trace[826521680] 'agreement among raft nodes before linearized reading' (duration: 198.838753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:23.385364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.579401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-22T06:06:23.385371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.622465ms","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-04-22T06:06:23.385439Z","caller":"traceutil/trace.go:171","msg":"trace[90176256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"202.686162ms","start":"2026-04-22T06:06:23.182736Z","end":"2026-04-22T06:06:23.385422Z","steps":["trace[90176256] 'agreement among raft nodes before linearized reading' (duration: 202.50334ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:23.38545Z","caller":"traceutil/trace.go:171","msg":"trace[1687257756] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1217; }","duration":"196.733146ms","start":"2026-04-22T06:06:23.1887Z","end":"2026-04-22T06:06:23.385433Z","steps":["trace[1687257756] 'agreement among raft nodes before linearized reading' (duration: 196.516373ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:25.608125Z","caller":"traceutil/trace.go:171","msg":"trace[1776498583] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"216.687863ms","start":"2026-04-22T06:06:25.391418Z","end":"2026-04-22T06:06:25.608105Z","steps":["trace[1776498583] 'read index received' (duration: 216.48405ms)","trace[1776498583] 'applied index is now lower than readState.Index' (duration: 202.793µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:25.608219Z","caller":"traceutil/trace.go:171","msg":"trace[2146104862] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"230.900931ms","start":"2026-04-22T06:06:25.377295Z","end":"2026-04-22T06:06:25.608196Z","steps":["trace[2146104862] 'process raft request' (duration: 230.675429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:25.608485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.054338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-22T06:06:25.608542Z","caller":"traceutil/trace.go:171","msg":"trace[1126631350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1230; }","duration":"217.133868ms","start":"2026-04-22T06:06:25.391393Z","end":"2026-04-22T06:06:25.608527Z","steps":["trace[1126631350] 'agreement among raft nodes before linearized reading' (duration: 216.851365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:25.608514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.131401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-22T06:06:25.608584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.550065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T06:06:25.608679Z","caller":"traceutil/trace.go:171","msg":"trace[1648274864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"214.310553ms","start":"2026-04-22T06:06:25.394288Z","end":"2026-04-22T06:06:25.608598Z","steps":["trace[1648274864] 'agreement among raft nodes before linearized reading' (duration: 214.02777ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:25.608805Z","caller":"traceutil/trace.go:171","msg":"trace[1793365737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"213.683896ms","start":"2026-04-22T06:06:25.394966Z","end":"2026-04-22T06:06:25.608649Z","steps":["trace[1793365737] 'agreement among raft nodes before linearized reading' (duration: 213.397402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:25.608485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.872895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:06:25.608893Z","caller":"traceutil/trace.go:171","msg":"trace[1679958355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"213.31057ms","start":"2026-04-22T06:06:25.39557Z","end":"2026-04-22T06:06:25.608881Z","steps":["trace[1679958355] 'agreement among raft nodes before linearized reading' (duration: 212.753164ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:27.119045Z","caller":"traceutil/trace.go:171","msg":"trace[24463391] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"132.450804ms","start":"2026-04-22T06:06:26.986571Z","end":"2026-04-22T06:06:27.119022Z","steps":["trace[24463391] 'read index received' (duration: 132.301472ms)","trace[24463391] 'applied index is now lower than readState.Index' (duration: 148.322µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:27.119214Z","caller":"traceutil/trace.go:171","msg":"trace[405024678] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"189.291432ms","start":"2026-04-22T06:06:26.929806Z","end":"2026-04-22T06:06:27.119097Z","steps":["trace[405024678] 'process raft request' (duration: 189.037909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:27.119247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.651517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:27.119331Z","caller":"traceutil/trace.go:171","msg":"trace[1965612655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"132.798948ms","start":"2026-04-22T06:06:26.986512Z","end":"2026-04-22T06:06:27.119311Z","steps":["trace[1965612655] 'agreement among raft nodes before linearized reading' (duration: 132.613806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:27.709668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.327125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:06:27.709993Z","caller":"traceutil/trace.go:171","msg":"trace[633707305] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"582.894401ms","start":"2026-04-22T06:06:27.127075Z","end":"2026-04-22T06:06:27.70997Z","steps":["trace[633707305] 'process raft request' (duration: 407.184701ms)","trace[633707305] 'compare' (duration: 175.133182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:27.710069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:27.127052Z","time spent":"582.984433ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4050,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:06:27.953081Z","caller":"traceutil/trace.go:171","msg":"trace[2044871691] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"238.718771ms","start":"2026-04-22T06:06:27.714332Z","end":"2026-04-22T06:06:27.953051Z","steps":["trace[2044871691] 'process raft request' (duration: 167.715521ms)","trace[2044871691] 'compare' (duration: 70.879539ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:27.963027Z","caller":"traceutil/trace.go:171","msg":"trace[1538299630] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"248.175229ms","start":"2026-04-22T06:06:27.714828Z","end":"2026-04-22T06:06:27.963004Z","steps":["trace[1538299630] 'process raft request' (duration: 248.004296ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:27.963263Z","caller":"traceutil/trace.go:171","msg":"trace[1407085] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"248.276431ms","start":"2026-04-22T06:06:27.714962Z","end":"2026-04-22T06:06:27.963238Z","steps":["trace[1407085] 'process raft request' (duration: 248.006008ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:27.963482Z","caller":"traceutil/trace.go:171","msg":"trace[205212481] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"247.844195ms","start":"2026-04-22T06:06:27.715619Z","end":"2026-04-22T06:06:27.963464Z","steps":["trace[205212481] 'process raft request' (duration: 247.561991ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:27.963521Z","caller":"traceutil/trace.go:171","msg":"trace[2112022113] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"247.629072ms","start":"2026-04-22T06:06:27.715858Z","end":"2026-04-22T06:06:27.963488Z","steps":["trace[2112022113] 'process raft request' (duration: 247.48126ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:27.963553Z","caller":"traceutil/trace.go:171","msg":"trace[2089287760] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"163.55649ms","start":"2026-04-22T06:06:27.799981Z","end":"2026-04-22T06:06:27.963537Z","steps":["trace[2089287760] 'process raft request' (duration: 163.503009ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:27.96374Z","caller":"traceutil/trace.go:171","msg":"trace[344938816] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"247.236258ms","start":"2026-04-22T06:06:27.716491Z","end":"2026-04-22T06:06:27.963727Z","steps":["trace[344938816] 'process raft request' (duration: 246.900804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:28.185305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.526812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert\" ","response":"range_response_count:1 size:1126"} {"level":"info","ts":"2026-04-22T06:06:28.185402Z","caller":"traceutil/trace.go:171","msg":"trace[508201349] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert; range_end:; response_count:1; response_revision:1247; }","duration":"130.654873ms","start":"2026-04-22T06:06:28.054721Z","end":"2026-04-22T06:06:28.185376Z","steps":["trace[508201349] 'range keys from in-memory index tree' (duration: 130.351279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:29.686155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.28976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:06:29.686387Z","caller":"traceutil/trace.go:171","msg":"trace[1450496599] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"186.018736ms","start":"2026-04-22T06:06:29.500346Z","end":"2026-04-22T06:06:29.686364Z","steps":["trace[1450496599] 'process raft request' (duration: 29.432982ms)","trace[1450496599] 'compare' (duration: 156.074898ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:29.686946Z","caller":"traceutil/trace.go:171","msg":"trace[886383240] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"137.223986ms","start":"2026-04-22T06:06:29.54969Z","end":"2026-04-22T06:06:29.686914Z","steps":["trace[886383240] 'process raft request' (duration: 137.060914ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:40.238931Z","caller":"traceutil/trace.go:171","msg":"trace[666176487] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"138.352735ms","start":"2026-04-22T06:06:40.100548Z","end":"2026-04-22T06:06:40.2389Z","steps":["trace[666176487] 'process raft request' (duration: 80.563717ms)","trace[666176487] 'compare' (duration: 57.498755ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:42.201528Z","caller":"traceutil/trace.go:171","msg":"trace[2129878602] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"177.985763ms","start":"2026-04-22T06:06:42.023516Z","end":"2026-04-22T06:06:42.201502Z","steps":["trace[2129878602] 'process raft request' (duration: 177.842531ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:42.312593Z","caller":"traceutil/trace.go:171","msg":"trace[2021227317] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1399; }","duration":"221.187266ms","start":"2026-04-22T06:06:42.091372Z","end":"2026-04-22T06:06:42.312559Z","steps":["trace[2021227317] 'read index received' (duration: 109.966691ms)","trace[2021227317] 'applied index is now lower than readState.Index' (duration: 111.219255ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:42.312673Z","caller":"traceutil/trace.go:171","msg":"trace[538826722] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"284.345323ms","start":"2026-04-22T06:06:42.02827Z","end":"2026-04-22T06:06:42.312615Z","steps":["trace[538826722] 'process raft request' (duration: 224.678628ms)","trace[538826722] 'compare' (duration: 59.479953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:42.312815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.431549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-22T06:06:42.31285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.128574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T06:06:42.312899Z","caller":"traceutil/trace.go:171","msg":"trace[1840417723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"221.542661ms","start":"2026-04-22T06:06:42.091337Z","end":"2026-04-22T06:06:42.312879Z","steps":["trace[1840417723] 'agreement among raft nodes before linearized reading' (duration: 221.359888ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:42.312919Z","caller":"traceutil/trace.go:171","msg":"trace[66306676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"214.215145ms","start":"2026-04-22T06:06:42.098683Z","end":"2026-04-22T06:06:42.312898Z","steps":["trace[66306676] 'agreement among raft nodes before linearized reading' (duration: 214.060832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:42.312988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.335188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-22T06:06:42.313118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.841823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:06:42.313201Z","caller":"traceutil/trace.go:171","msg":"trace[923246234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"214.897774ms","start":"2026-04-22T06:06:42.098266Z","end":"2026-04-22T06:06:42.313163Z","steps":["trace[923246234] 'agreement among raft nodes before linearized reading' (duration: 214.777013ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:42.313121Z","caller":"traceutil/trace.go:171","msg":"trace[584631068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"215.448179ms","start":"2026-04-22T06:06:42.097578Z","end":"2026-04-22T06:06:42.313026Z","steps":["trace[584631068] 'agreement among raft nodes before linearized reading' (duration: 215.158895ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:46.097914Z","caller":"traceutil/trace.go:171","msg":"trace[374862202] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"110.743551ms","start":"2026-04-22T06:06:45.98715Z","end":"2026-04-22T06:06:46.097894Z","steps":["trace[374862202] 'read index received' (duration: 110.56845ms)","trace[374862202] 'applied index is now lower than readState.Index' (duration: 174.361µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:46.097986Z","caller":"traceutil/trace.go:171","msg":"trace[1968512872] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"146.957808ms","start":"2026-04-22T06:06:45.950996Z","end":"2026-04-22T06:06:46.097954Z","steps":["trace[1968512872] 'process raft request' (duration: 146.770616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:46.098093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.866813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:46.098163Z","caller":"traceutil/trace.go:171","msg":"trace[1379821511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1378; }","duration":"111.055114ms","start":"2026-04-22T06:06:45.987096Z","end":"2026-04-22T06:06:46.098151Z","steps":["trace[1379821511] 'agreement among raft nodes before linearized reading' (duration: 110.885432ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:47.804078Z","caller":"traceutil/trace.go:171","msg":"trace[913031712] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1502; }","duration":"182.323653ms","start":"2026-04-22T06:06:47.621728Z","end":"2026-04-22T06:06:47.804052Z","steps":["trace[913031712] 'read index received' (duration: 113.075322ms)","trace[913031712] 'applied index is now lower than readState.Index' (duration: 69.247041ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:47.80425Z","caller":"traceutil/trace.go:171","msg":"trace[606878178] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"183.65857ms","start":"2026-04-22T06:06:47.620519Z","end":"2026-04-22T06:06:47.804178Z","steps":["trace[606878178] 'process raft request' (duration: 114.500128ms)","trace[606878178] 'compare' (duration: 68.776617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:47.804415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.658569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1020"} {"level":"info","ts":"2026-04-22T06:06:47.804508Z","caller":"traceutil/trace.go:171","msg":"trace[2063089278] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:1458; }","duration":"182.83315ms","start":"2026-04-22T06:06:47.621652Z","end":"2026-04-22T06:06:47.804485Z","steps":["trace[2063089278] 'agreement among raft nodes before linearized reading' (duration: 182.539197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:47.80512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.874619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-04-22T06:06:47.805235Z","caller":"traceutil/trace.go:171","msg":"trace[621880074] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:1458; }","duration":"182.076341ms","start":"2026-04-22T06:06:47.623141Z","end":"2026-04-22T06:06:47.805218Z","steps":["trace[621880074] 'agreement among raft nodes before linearized reading' (duration: 181.503455ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:48.270276Z","caller":"traceutil/trace.go:171","msg":"trace[1959268470] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1504; }","duration":"282.978733ms","start":"2026-04-22T06:06:47.987267Z","end":"2026-04-22T06:06:48.270246Z","steps":["trace[1959268470] 'read index received' (duration: 253.714771ms)","trace[1959268470] 'applied index is now lower than readState.Index' (duration: 29.262602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:48.270483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.205166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:48.270419Z","caller":"traceutil/trace.go:171","msg":"trace[15017845] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"385.761458ms","start":"2026-04-22T06:06:47.884599Z","end":"2026-04-22T06:06:48.27036Z","steps":["trace[15017845] 'process raft request' (duration: 356.454875ms)","trace[15017845] 'compare' (duration: 28.834507ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:48.270538Z","caller":"traceutil/trace.go:171","msg":"trace[1249352547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1460; }","duration":"283.311828ms","start":"2026-04-22T06:06:47.98721Z","end":"2026-04-22T06:06:48.270522Z","steps":["trace[1249352547] 'agreement among raft nodes before linearized reading' (duration: 283.201777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:48.270709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:47.884559Z","time spent":"385.997561ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105431,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:06:48.575297Z","caller":"traceutil/trace.go:171","msg":"trace[1217206209] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"120.934848ms","start":"2026-04-22T06:06:48.454338Z","end":"2026-04-22T06:06:48.575273Z","steps":["trace[1217206209] 'process raft request' (duration: 120.793286ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:48.644936Z","caller":"traceutil/trace.go:171","msg":"trace[1058628325] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"171.711286ms","start":"2026-04-22T06:06:48.473201Z","end":"2026-04-22T06:06:48.644912Z","steps":["trace[1058628325] 'process raft request' (duration: 171.574245ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:49.048633Z","caller":"traceutil/trace.go:171","msg":"trace[773235877] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1524; }","duration":"118.027902ms","start":"2026-04-22T06:06:48.93058Z","end":"2026-04-22T06:06:49.048608Z","steps":["trace[773235877] 'read index received' (duration: 46.390248ms)","trace[773235877] 'applied index is now lower than readState.Index' (duration: 71.636404ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:49.048689Z","caller":"traceutil/trace.go:171","msg":"trace[2064819915] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"174.804849ms","start":"2026-04-22T06:06:48.873818Z","end":"2026-04-22T06:06:49.048623Z","steps":["trace[2064819915] 'process raft request' (duration: 103.082163ms)","trace[2064819915] 'compare' (duration: 71.495483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:49.048898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.302126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1071"} {"level":"info","ts":"2026-04-22T06:06:49.048948Z","caller":"traceutil/trace.go:171","msg":"trace[1876153228] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1479; }","duration":"118.413568ms","start":"2026-04-22T06:06:48.93052Z","end":"2026-04-22T06:06:49.048933Z","steps":["trace[1876153228] 'agreement among raft nodes before linearized reading' (duration: 118.192354ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:52.50151Z","caller":"traceutil/trace.go:171","msg":"trace[540911919] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"179.01738ms","start":"2026-04-22T06:06:52.322471Z","end":"2026-04-22T06:06:52.501489Z","steps":["trace[540911919] 'process raft request' (duration: 178.892999ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:52.516226Z","caller":"traceutil/trace.go:171","msg":"trace[1388031338] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"142.783084ms","start":"2026-04-22T06:06:52.373427Z","end":"2026-04-22T06:06:52.51621Z","steps":["trace[1388031338] 'process raft request' (duration: 142.554752ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:52.683938Z","caller":"traceutil/trace.go:171","msg":"trace[2006361230] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"163.322594ms","start":"2026-04-22T06:06:52.520577Z","end":"2026-04-22T06:06:52.683899Z","steps":["trace[2006361230] 'process raft request' (duration: 163.052331ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:52.684258Z","caller":"traceutil/trace.go:171","msg":"trace[1337910696] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"163.604258ms","start":"2026-04-22T06:06:52.520621Z","end":"2026-04-22T06:06:52.684226Z","steps":["trace[1337910696] 'process raft request' (duration: 163.222324ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:52.695935Z","caller":"traceutil/trace.go:171","msg":"trace[75772400] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"174.60263ms","start":"2026-04-22T06:06:52.521305Z","end":"2026-04-22T06:06:52.695907Z","steps":["trace[75772400] 'process raft request' (duration: 174.389528ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:52.696008Z","caller":"traceutil/trace.go:171","msg":"trace[433823032] linearizableReadLoop","detail":"{readStateIndex:1546; appliedIndex:1542; }","duration":"123.942091ms","start":"2026-04-22T06:06:52.572052Z","end":"2026-04-22T06:06:52.695994Z","steps":["trace[433823032] 'read index received' (duration: 111.551183ms)","trace[433823032] 'applied index is now lower than readState.Index' (duration: 12.390188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:52.696149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.075213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:06:52.696191Z","caller":"traceutil/trace.go:171","msg":"trace[2002864607] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1500; }","duration":"124.167604ms","start":"2026-04-22T06:06:52.572014Z","end":"2026-04-22T06:06:52.696182Z","steps":["trace[2002864607] 'agreement among raft nodes before linearized reading' (duration: 124.031572ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:52.696475Z","caller":"traceutil/trace.go:171","msg":"trace[741363165] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"175.011295ms","start":"2026-04-22T06:06:52.521422Z","end":"2026-04-22T06:06:52.696433Z","steps":["trace[741363165] 'process raft request' (duration: 174.427838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:18.167611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.753863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:18.167697Z","caller":"traceutil/trace.go:171","msg":"trace[1524245988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1913; }","duration":"179.895655ms","start":"2026-04-22T06:07:17.987783Z","end":"2026-04-22T06:07:18.167679Z","steps":["trace[1524245988] 'range keys from in-memory index tree' (duration: 179.662553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:18.544688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339356965,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:07:19.045133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339356965,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:07:19.13647Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104213736s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T06:07:19.136938Z","caller":"traceutil/trace.go:171","msg":"trace[1664927144] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"1.104757571s","start":"2026-04-22T06:07:18.032154Z","end":"2026-04-22T06:07:19.136912Z","steps":["trace[1664927144] 'process raft request' (duration: 1.10457492s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.137114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.032137Z","time spent":"1.104882392s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:19.637593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.065696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:07:19.637822Z","caller":"traceutil/trace.go:171","msg":"trace[2014939048] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1985; }","duration":"1.593200216s","start":"2026-04-22T06:07:18.04459Z","end":"2026-04-22T06:07:19.63779Z","steps":["trace[2014939048] 'read index received' (duration: 1.09214785s)","trace[2014939048] 'applied index is now lower than readState.Index' (duration: 501.051316ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:19.637898Z","caller":"traceutil/trace.go:171","msg":"trace[77804923] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"682.713066ms","start":"2026-04-22T06:07:18.955174Z","end":"2026-04-22T06:07:19.637887Z","steps":["trace[77804923] 'process raft request' (duration: 682.515544ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:19.637896Z","caller":"traceutil/trace.go:171","msg":"trace[2119201802] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"1.029547436s","start":"2026-04-22T06:07:18.608318Z","end":"2026-04-22T06:07:19.637865Z","steps":["trace[2119201802] 'process raft request' (duration: 709.121127ms)","trace[2119201802] 'compare' (duration: 319.935865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:19.637955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.955156Z","time spent":"682.767656ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:19.638041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.608261Z","time spent":"1.029696177s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:19.638231Z","caller":"traceutil/trace.go:171","msg":"trace[1892698332] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"491.964142ms","start":"2026-04-22T06:07:19.146229Z","end":"2026-04-22T06:07:19.638193Z","steps":["trace[1892698332] 'process raft request' (duration: 491.526667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.638305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593720722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-476708d9-857b-48d8-8d3e-5348b24e5d94\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-22T06:07:19.638368Z","caller":"traceutil/trace.go:171","msg":"trace[437440660] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-476708d9-857b-48d8-8d3e-5348b24e5d94; range_end:; response_count:1; response_revision:1917; }","duration":"1.593821982s","start":"2026-04-22T06:07:18.044534Z","end":"2026-04-22T06:07:19.638356Z","steps":["trace[437440660] 'agreement among raft nodes before linearized reading' (duration: 1.593529329s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.638403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.044505Z","time spent":"1.593890622s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-476708d9-857b-48d8-8d3e-5348b24e5d94\" "} {"level":"warn","ts":"2026-04-22T06:07:19.638414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.146207Z","time spent":"492.089843ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:19.665432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.093305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:19.665493Z","caller":"traceutil/trace.go:171","msg":"trace[1947572894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1918; }","duration":"599.156325ms","start":"2026-04-22T06:07:19.066321Z","end":"2026-04-22T06:07:19.665478Z","steps":["trace[1947572894] 'agreement among raft nodes before linearized reading' (duration: 599.039124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.665529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.066253Z","time spent":"599.268746ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T06:07:19.665529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031202654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T06:07:19.665573Z","caller":"traceutil/trace.go:171","msg":"trace[1294308749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1918; }","duration":"1.031271094s","start":"2026-04-22T06:07:18.634288Z","end":"2026-04-22T06:07:19.665559Z","steps":["trace[1294308749] 'agreement among raft nodes before linearized reading' (duration: 1.031185663s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.665575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.189061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T06:07:19.66559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.085125ms","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-04-22T06:07:19.665649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496563569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:19.665648Z","caller":"traceutil/trace.go:171","msg":"trace[1032807677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1918; }","duration":"831.309072ms","start":"2026-04-22T06:07:18.83432Z","end":"2026-04-22T06:07:19.665629Z","steps":["trace[1032807677] 'agreement among raft nodes before linearized reading' (duration: 831.08898ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:19.665654Z","caller":"traceutil/trace.go:171","msg":"trace[1624359848] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1918; }","duration":"798.174727ms","start":"2026-04-22T06:07:18.867465Z","end":"2026-04-22T06:07:19.66564Z","steps":["trace[1624359848] 'agreement among raft nodes before linearized reading' (duration: 798.011435ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:19.665676Z","caller":"traceutil/trace.go:171","msg":"trace[817361545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1918; }","duration":"1.496626259s","start":"2026-04-22T06:07:18.169042Z","end":"2026-04-22T06:07:19.665668Z","steps":["trace[817361545] 'agreement among raft nodes before linearized reading' (duration: 1.496587809s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.665695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.834304Z","time spent":"831.382832ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T06:07:19.66573Z","caller":"traceutil/trace.go:171","msg":"trace[1077822421] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"306.119941ms","start":"2026-04-22T06:07:19.359594Z","end":"2026-04-22T06:07:19.665714Z","steps":["trace[1077822421] 'process raft request' (duration: 305.556974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.665743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.16902Z","time spent":"1.4967149s","remote":"127.0.0.1:51582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T06:07:19.66577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.203489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T06:07:19.665798Z","caller":"traceutil/trace.go:171","msg":"trace[185738516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1918; }","duration":"827.268369ms","start":"2026-04-22T06:07:18.838521Z","end":"2026-04-22T06:07:19.66579Z","steps":["trace[185738516] 'agreement among raft nodes before linearized reading' (duration: 827.190649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.6658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.359564Z","time spent":"306.192361ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:19.665823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.838494Z","time spent":"827.323701ms","remote":"127.0.0.1:51850","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-04-22T06:07:19.665438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539057562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-04-22T06:07:19.665902Z","caller":"traceutil/trace.go:171","msg":"trace[1685523684] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1918; }","duration":"1.539556986s","start":"2026-04-22T06:07:18.126336Z","end":"2026-04-22T06:07:19.665893Z","steps":["trace[1685523684] 'agreement among raft nodes before linearized reading' (duration: 1.538955351s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.66561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.634274Z","time spent":"1.031325805s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:07:19.665943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.126284Z","time spent":"1.539651198s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-04-22T06:07:19.665975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.890142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-22T06:07:19.665691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.867452Z","time spent":"798.230567ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-22T06:07:19.666077Z","caller":"traceutil/trace.go:171","msg":"trace[1924322809] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1918; }","duration":"818.027663ms","start":"2026-04-22T06:07:18.848025Z","end":"2026-04-22T06:07:19.666052Z","steps":["trace[1924322809] 'agreement among raft nodes before linearized reading' (duration: 817.72726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.666145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.848013Z","time spent":"818.107084ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-04-22T06:07:19.666018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.861166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T06:07:19.66623Z","caller":"traceutil/trace.go:171","msg":"trace[1297385258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1918; }","duration":"828.095217ms","start":"2026-04-22T06:07:18.838104Z","end":"2026-04-22T06:07:19.6662Z","steps":["trace[1297385258] 'agreement among raft nodes before linearized reading' (duration: 827.831455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.666281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.838087Z","time spent":"828.179079ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:07:19.666499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.684372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T06:07:19.666553Z","caller":"traceutil/trace.go:171","msg":"trace[728963579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1918; }","duration":"825.761434ms","start":"2026-04-22T06:07:18.840781Z","end":"2026-04-22T06:07:19.666542Z","steps":["trace[728963579] 'agreement among raft nodes before linearized reading' (duration: 825.583242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:19.666581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:18.840766Z","time spent":"825.808774ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T06:07:20.176147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339356972,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:07:20.67698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339356972,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:07:20.691166Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022534935s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T06:07:20.988693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.120819ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-22T06:07:20.988782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.668479Z","time spent":"1.320299032s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-22T06:07:21.002679Z","caller":"traceutil/trace.go:171","msg":"trace[1034550733] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"1.329120563s","start":"2026-04-22T06:07:19.673506Z","end":"2026-04-22T06:07:21.002626Z","steps":["trace[1034550733] 'process raft request' (duration: 1.328820899s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.002857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.673483Z","time spent":"1.329283835s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:21.002872Z","caller":"traceutil/trace.go:171","msg":"trace[1148151202] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"1.329216834s","start":"2026-04-22T06:07:19.673628Z","end":"2026-04-22T06:07:21.002845Z","steps":["trace[1148151202] 'process raft request' (duration: 1.328932972s)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:21.002822Z","caller":"traceutil/trace.go:171","msg":"trace[771389760] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"1.329083052s","start":"2026-04-22T06:07:19.673706Z","end":"2026-04-22T06:07:21.002789Z","steps":["trace[771389760] 'process raft request' (duration: 1.32888632s)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:21.002758Z","caller":"traceutil/trace.go:171","msg":"trace[1336200077] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"1.329169314s","start":"2026-04-22T06:07:19.673558Z","end":"2026-04-22T06:07:21.002727Z","steps":["trace[1336200077] 'process raft request' (duration: 1.328967242s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.003015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.673602Z","time spent":"1.329321215s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:21.003055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.673663Z","time spent":"1.329274855s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:21.003084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.673532Z","time spent":"1.329445447s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:21.003283Z","caller":"traceutil/trace.go:171","msg":"trace[2065112829] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1990; }","duration":"1.327823499s","start":"2026-04-22T06:07:19.675447Z","end":"2026-04-22T06:07:21.00327Z","steps":["trace[2065112829] 'read index received' (duration: 1.016089728s)","trace[2065112829] 'applied index is now lower than readState.Index' (duration: 311.732721ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:21.003309Z","caller":"traceutil/trace.go:171","msg":"trace[1746631794] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"1.324808998s","start":"2026-04-22T06:07:19.678485Z","end":"2026-04-22T06:07:21.003294Z","steps":["trace[1746631794] 'process raft request' (duration: 1.324657626s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.003384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32793665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:21.003356Z","caller":"traceutil/trace.go:171","msg":"trace[58063898] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"1.329512536s","start":"2026-04-22T06:07:19.673786Z","end":"2026-04-22T06:07:21.003299Z","steps":["trace[58063898] 'process raft request' (duration: 1.328845569s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.003408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.678475Z","time spent":"1.324895309s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:21.003421Z","caller":"traceutil/trace.go:171","msg":"trace[487270814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1924; }","duration":"1.3279748s","start":"2026-04-22T06:07:19.675436Z","end":"2026-04-22T06:07:21.003411Z","steps":["trace[487270814] 'agreement among raft nodes before linearized reading' (duration: 1.327910199s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.003481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.675414Z","time spent":"1.328060322s","remote":"127.0.0.1:51582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T06:07:21.0036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:19.673776Z","time spent":"1.329699228s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:21.004789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.596956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-476708d9-857b-48d8-8d3e-5348b24e5d94\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-22T06:07:21.004901Z","caller":"traceutil/trace.go:171","msg":"trace[661023164] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-476708d9-857b-48d8-8d3e-5348b24e5d94; range_end:; response_count:1; response_revision:1926; }","duration":"360.729658ms","start":"2026-04-22T06:07:20.644122Z","end":"2026-04-22T06:07:21.004852Z","steps":["trace[661023164] 'agreement among raft nodes before linearized reading' (duration: 360.355374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.004983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:20.644103Z","time spent":"360.841309ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-476708d9-857b-48d8-8d3e-5348b24e5d94\" "} {"level":"info","ts":"2026-04-22T06:07:21.005301Z","caller":"traceutil/trace.go:171","msg":"trace[1330770818] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"173.614349ms","start":"2026-04-22T06:07:20.831668Z","end":"2026-04-22T06:07:21.005283Z","steps":["trace[1330770818] 'process raft request' (duration: 172.644269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.005379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.743707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-22T06:07:21.008948Z","caller":"traceutil/trace.go:171","msg":"trace[1944809684] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1926; }","duration":"165.346413ms","start":"2026-04-22T06:07:20.843586Z","end":"2026-04-22T06:07:21.008932Z","steps":["trace[1944809684] 'agreement among raft nodes before linearized reading' (duration: 161.709306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.00547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.270352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-22T06:07:21.009187Z","caller":"traceutil/trace.go:171","msg":"trace[469364096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1926; }","duration":"333.012191ms","start":"2026-04-22T06:07:20.676146Z","end":"2026-04-22T06:07:21.009159Z","steps":["trace[469364096] 'agreement among raft nodes before linearized reading' (duration: 329.266633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.009229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:20.676115Z","time spent":"333.102452ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-22T06:07:29.862315Z","caller":"traceutil/trace.go:171","msg":"trace[801466131] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"103.076796ms","start":"2026-04-22T06:07:29.759213Z","end":"2026-04-22T06:07:29.86229Z","steps":["trace[801466131] 'process raft request' (duration: 102.929315ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:29.894662Z","caller":"traceutil/trace.go:171","msg":"trace[868700327] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"134.966652ms","start":"2026-04-22T06:07:29.759648Z","end":"2026-04-22T06:07:29.894615Z","steps":["trace[868700327] 'process raft request' (duration: 134.721109ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:29.895165Z","caller":"traceutil/trace.go:171","msg":"trace[1936908867] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"135.423536ms","start":"2026-04-22T06:07:29.759715Z","end":"2026-04-22T06:07:29.895139Z","steps":["trace[1936908867] 'process raft request' (duration: 134.84474ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:29.895169Z","caller":"traceutil/trace.go:171","msg":"trace[458109506] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"131.584477ms","start":"2026-04-22T06:07:29.763556Z","end":"2026-04-22T06:07:29.895141Z","steps":["trace[458109506] 'process raft request' (duration: 131.443615ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:30.307807Z","caller":"traceutil/trace.go:171","msg":"trace[1551669948] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"110.261488ms","start":"2026-04-22T06:07:30.197515Z","end":"2026-04-22T06:07:30.307777Z","steps":["trace[1551669948] 'process raft request' (duration: 110.050695ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:33.489362Z","caller":"traceutil/trace.go:171","msg":"trace[2122706106] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"379.477493ms","start":"2026-04-22T06:07:33.109836Z","end":"2026-04-22T06:07:33.489313Z","steps":["trace[2122706106] 'process raft request' (duration: 366.882976ms)","trace[2122706106] 'compare' (duration: 12.321755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:33.49002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:33.109816Z","time spent":"379.659195ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:33.497959Z","caller":"traceutil/trace.go:171","msg":"trace[399842412] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2117; }","duration":"135.691958ms","start":"2026-04-22T06:07:33.362239Z","end":"2026-04-22T06:07:33.497931Z","steps":["trace[399842412] 'read index received' (duration: 114.556015ms)","trace[399842412] 'applied index is now lower than readState.Index' (duration: 21.134783ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:33.498117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.854879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:07:33.498237Z","caller":"traceutil/trace.go:171","msg":"trace[621282566] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2043; }","duration":"136.031921ms","start":"2026-04-22T06:07:33.362191Z","end":"2026-04-22T06:07:33.498223Z","steps":["trace[621282566] 'agreement among raft nodes before linearized reading' (duration: 135.839349ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:33.498289Z","caller":"traceutil/trace.go:171","msg":"trace[1460724424] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"387.435413ms","start":"2026-04-22T06:07:33.110823Z","end":"2026-04-22T06:07:33.498258Z","steps":["trace[1460724424] 'process raft request' (duration: 386.920228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:33.498453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:33.110806Z","time spent":"387.544954ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:34.093922Z","caller":"traceutil/trace.go:171","msg":"trace[812129950] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2121; }","duration":"178.100372ms","start":"2026-04-22T06:07:33.915766Z","end":"2026-04-22T06:07:34.093866Z","steps":["trace[812129950] 'read index received' (duration: 32.042032ms)","trace[812129950] 'applied index is now lower than readState.Index' (duration: 146.05699ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:34.093994Z","caller":"traceutil/trace.go:171","msg":"trace[1049371488] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"187.114314ms","start":"2026-04-22T06:07:33.906823Z","end":"2026-04-22T06:07:34.093938Z","steps":["trace[1049371488] 'process raft request' (duration: 186.614018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:34.094147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.331364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:34.094631Z","caller":"traceutil/trace.go:171","msg":"trace[335254923] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2047; }","duration":"178.917671ms","start":"2026-04-22T06:07:33.915691Z","end":"2026-04-22T06:07:34.094609Z","steps":["trace[335254923] 'agreement among raft nodes before linearized reading' (duration: 178.352156ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:34.094033Z","caller":"traceutil/trace.go:171","msg":"trace[947186901] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"186.226684ms","start":"2026-04-22T06:07:33.907751Z","end":"2026-04-22T06:07:34.093977Z","steps":["trace[947186901] 'process raft request' (duration: 186.030332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:38.010657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339357282,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:07:38.510767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339357282,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:07:38.711559Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.203587179s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T06:07:38.711978Z","caller":"traceutil/trace.go:171","msg":"trace[1710191694] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"1.204074753s","start":"2026-04-22T06:07:37.507881Z","end":"2026-04-22T06:07:38.711956Z","steps":["trace[1710191694] 'process raft request' (duration: 1.203935302s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:38.712133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:37.507862Z","time spent":"1.204183994s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:38.738005Z","caller":"traceutil/trace.go:171","msg":"trace[1058372459] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"832.041434ms","start":"2026-04-22T06:07:37.905929Z","end":"2026-04-22T06:07:38.73797Z","steps":["trace[1058372459] 'process raft request' (duration: 831.840132ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:38.738092Z","caller":"traceutil/trace.go:171","msg":"trace[664512502] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"778.529309ms","start":"2026-04-22T06:07:37.959544Z","end":"2026-04-22T06:07:38.738073Z","steps":["trace[664512502] 'process raft request' (duration: 778.353407ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:38.738109Z","caller":"traceutil/trace.go:171","msg":"trace[776191597] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"606.138298ms","start":"2026-04-22T06:07:38.131957Z","end":"2026-04-22T06:07:38.738096Z","steps":["trace[776191597] 'process raft request' (duration: 605.988227ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:38.738001Z","caller":"traceutil/trace.go:171","msg":"trace[906976383] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2140; }","duration":"1.227909191s","start":"2026-04-22T06:07:37.510069Z","end":"2026-04-22T06:07:38.737979Z","steps":["trace[906976383] 'read index received' (duration: 1.20178785s)","trace[906976383] 'applied index is now lower than readState.Index' (duration: 26.120621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:38.73828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:38.131945Z","time spent":"606.31458ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:38.738089Z","caller":"traceutil/trace.go:171","msg":"trace[1527755933] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"617.934446ms","start":"2026-04-22T06:07:38.120128Z","end":"2026-04-22T06:07:38.738063Z","steps":["trace[1527755933] 'process raft request' (duration: 617.794935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:38.738402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.153039ms","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-04-22T06:07:38.738408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.277859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T06:07:38.738454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.598972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:38.738452Z","caller":"traceutil/trace.go:171","msg":"trace[760169113] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2068; }","duration":"613.343679ms","start":"2026-04-22T06:07:38.125092Z","end":"2026-04-22T06:07:38.738436Z","steps":["trace[760169113] 'agreement among raft nodes before linearized reading' (duration: 613.283749ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:38.738471Z","caller":"traceutil/trace.go:171","msg":"trace[1492764347] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2068; }","duration":"213.665662ms","start":"2026-04-22T06:07:38.524801Z","end":"2026-04-22T06:07:38.738467Z","steps":["trace[1492764347] 'agreement among raft nodes before linearized reading' (duration: 213.636801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:38.738494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:38.125081Z","time spent":"613.40209ms","remote":"127.0.0.1:57268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:07:38.738516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:38.120111Z","time spent":"618.29911ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:38.738425Z","caller":"traceutil/trace.go:171","msg":"trace[1452217004] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2068; }","duration":"593.200919ms","start":"2026-04-22T06:07:38.145216Z","end":"2026-04-22T06:07:38.738417Z","steps":["trace[1452217004] 'agreement among raft nodes before linearized reading' (duration: 593.148459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:38.73865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:38.145206Z","time spent":"593.439171ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-22T06:07:38.738739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.527378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:38.738757Z","caller":"traceutil/trace.go:171","msg":"trace[1760293255] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2068; }","duration":"805.545908ms","start":"2026-04-22T06:07:37.933207Z","end":"2026-04-22T06:07:38.738752Z","steps":["trace[1760293255] 'agreement among raft nodes before linearized reading' (duration: 805.517828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:38.73877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:37.933114Z","time spent":"805.65206ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:07:38.738205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:37.959519Z","time spent":"778.61731ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:38.738165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:37.905913Z","time spent":"832.169496ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:38.738353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.684781ms","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":"warn","ts":"2026-04-22T06:07:38.738227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228158623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T06:07:38.738995Z","caller":"traceutil/trace.go:171","msg":"trace[264846580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2068; }","duration":"1.22893305s","start":"2026-04-22T06:07:37.510028Z","end":"2026-04-22T06:07:38.738961Z","steps":["trace[264846580] 'agreement among raft nodes before linearized reading' (duration: 1.228119323s)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:38.73891Z","caller":"traceutil/trace.go:171","msg":"trace[266209946] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2068; }","duration":"921.284426ms","start":"2026-04-22T06:07:37.817615Z","end":"2026-04-22T06:07:38.7389Z","steps":["trace[266209946] 'agreement among raft nodes before linearized reading' (duration: 920.69513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:38.73917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:37.817595Z","time spent":"921.566499ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:07:38.739134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:37.510013Z","time spent":"1.229055451s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:08:00.910988Z","caller":"traceutil/trace.go:171","msg":"trace[83716301] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"105.985091ms","start":"2026-04-22T06:08:00.804894Z","end":"2026-04-22T06:08:00.910879Z","steps":["trace[83716301] 'process raft request' (duration: 105.746029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.411266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339357601,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T06:08:01.676484Z","caller":"traceutil/trace.go:171","msg":"trace[1046543505] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"847.584545ms","start":"2026-04-22T06:08:00.828882Z","end":"2026-04-22T06:08:01.676466Z","steps":["trace[1046543505] 'process raft request' (duration: 842.209754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.676644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:00.828863Z","time spent":"847.676366ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:01.677246Z","caller":"traceutil/trace.go:171","msg":"trace[706615375] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"846.506144ms","start":"2026-04-22T06:08:00.830651Z","end":"2026-04-22T06:08:01.677157Z","steps":["trace[706615375] 'process raft request' (duration: 846.467784ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:01.677232Z","caller":"traceutil/trace.go:171","msg":"trace[1943315389] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"847.848298ms","start":"2026-04-22T06:08:00.829357Z","end":"2026-04-22T06:08:01.677205Z","steps":["trace[1943315389] 'process raft request' (duration: 847.055561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.677306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:00.830636Z","time spent":"846.641896ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:01.677387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:00.829345Z","time spent":"847.95858ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:01.681617Z","caller":"traceutil/trace.go:171","msg":"trace[1693143630] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2273; }","duration":"770.815887ms","start":"2026-04-22T06:08:00.910775Z","end":"2026-04-22T06:08:01.681591Z","steps":["trace[1693143630] 'read index received' (duration: 760.278195ms)","trace[1693143630] 'applied index is now lower than readState.Index' (duration: 10.536422ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:01.681718Z","caller":"traceutil/trace.go:171","msg":"trace[1300841185] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"766.620726ms","start":"2026-04-22T06:08:00.915077Z","end":"2026-04-22T06:08:01.681698Z","steps":["trace[1300841185] 'process raft request' (duration: 766.282522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.681773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.455566ms","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-04-22T06:08:01.681808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:00.915055Z","time spent":"766.711537ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:01.681837Z","caller":"traceutil/trace.go:171","msg":"trace[1853885784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2195; }","duration":"789.541767ms","start":"2026-04-22T06:08:00.892274Z","end":"2026-04-22T06:08:01.681816Z","steps":["trace[1853885784] 'agreement among raft nodes before linearized reading' (duration: 789.367986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.681889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:00.892258Z","time spent":"789.619938ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-22T06:08:01.681935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.732123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T06:08:01.681976Z","caller":"traceutil/trace.go:171","msg":"trace[635357317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2195; }","duration":"832.848854ms","start":"2026-04-22T06:08:00.849116Z","end":"2026-04-22T06:08:01.681965Z","steps":["trace[635357317] 'agreement among raft nodes before linearized reading' (duration: 832.603132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.682052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:00.849103Z","time spent":"832.938095ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:01.682096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.163575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-04-22T06:08:01.68218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.979035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:08:01.682286Z","caller":"traceutil/trace.go:171","msg":"trace[561688796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2195; }","duration":"406.146067ms","start":"2026-04-22T06:08:01.276127Z","end":"2026-04-22T06:08:01.682273Z","steps":["trace[561688796] 'agreement among raft nodes before linearized reading' (duration: 405.735764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.682376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:01.276116Z","time spent":"406.238418ms","remote":"127.0.0.1:51850","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-04-22T06:08:01.682196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.927666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T06:08:01.68312Z","caller":"traceutil/trace.go:171","msg":"trace[2113835236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2195; }","duration":"456.859476ms","start":"2026-04-22T06:08:01.226235Z","end":"2026-04-22T06:08:01.683095Z","steps":["trace[2113835236] 'agreement among raft nodes before linearized reading' (duration: 455.851696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.683203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:01.226213Z","time spent":"456.965246ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:01.681983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.586775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T06:08:01.683356Z","caller":"traceutil/trace.go:171","msg":"trace[1953712724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2195; }","duration":"398.023499ms","start":"2026-04-22T06:08:01.285318Z","end":"2026-04-22T06:08:01.683341Z","steps":["trace[1953712724] 'agreement among raft nodes before linearized reading' (duration: 396.503684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.683411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:01.285294Z","time spent":"398.103629ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T06:08:01.682144Z","caller":"traceutil/trace.go:171","msg":"trace[1696379362] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2195; }","duration":"202.237225ms","start":"2026-04-22T06:08:01.479889Z","end":"2026-04-22T06:08:01.682126Z","steps":["trace[1696379362] 'agreement among raft nodes before linearized reading' (duration: 202.138814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.682416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.694872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:01.683663Z","caller":"traceutil/trace.go:171","msg":"trace[474492556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"696.978296ms","start":"2026-04-22T06:08:00.986675Z","end":"2026-04-22T06:08:01.683653Z","steps":["trace[474492556] 'agreement among raft nodes before linearized reading' (duration: 695.063147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:01.683699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:00.986654Z","time spent":"697.035726ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T06:08:03.333232Z","caller":"traceutil/trace.go:171","msg":"trace[222778031] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"346.232703ms","start":"2026-04-22T06:08:02.986973Z","end":"2026-04-22T06:08:03.333206Z","steps":["trace[222778031] 'read index received' (duration: 345.97881ms)","trace[222778031] 'applied index is now lower than readState.Index' (duration: 252.783µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:03.33335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.356604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:03.333381Z","caller":"traceutil/trace.go:171","msg":"trace[1398117308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2201; }","duration":"346.442644ms","start":"2026-04-22T06:08:02.986928Z","end":"2026-04-22T06:08:03.333371Z","steps":["trace[1398117308] 'agreement among raft nodes before linearized reading' (duration: 346.370243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:03.333437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:02.986912Z","time spent":"346.518025ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T06:08:03.333285Z","caller":"traceutil/trace.go:171","msg":"trace[1641799447] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"417.634218ms","start":"2026-04-22T06:08:02.915618Z","end":"2026-04-22T06:08:03.333252Z","steps":["trace[1641799447] 'process raft request' (duration: 417.407476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:03.333689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:02.915596Z","time spent":"418.007281ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:04.188615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339357619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:08:04.445937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.363057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:08:04.446034Z","caller":"traceutil/trace.go:171","msg":"trace[282048100] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"764.278718ms","start":"2026-04-22T06:08:03.681737Z","end":"2026-04-22T06:08:04.446016Z","steps":["trace[282048100] 'process raft request' (duration: 580.725359ms)","trace[282048100] 'compare' (duration: 183.270416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:04.446091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.681699Z","time spent":"764.365218ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:04.447967Z","caller":"traceutil/trace.go:171","msg":"trace[134828532] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"765.492398ms","start":"2026-04-22T06:08:03.682451Z","end":"2026-04-22T06:08:04.447943Z","steps":["trace[134828532] 'process raft request' (duration: 765.334517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.448103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.682421Z","time spent":"765.613159ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:04.448283Z","caller":"traceutil/trace.go:171","msg":"trace[1949422675] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"765.792592ms","start":"2026-04-22T06:08:03.682478Z","end":"2026-04-22T06:08:04.448271Z","steps":["trace[1949422675] 'process raft request' (duration: 765.424408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.448393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.682452Z","time spent":"765.867202ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:04.644568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.430113ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T06:08:04.644822Z","caller":"traceutil/trace.go:171","msg":"trace[1155439858] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2284; }","duration":"956.904073ms","start":"2026-04-22T06:08:03.687898Z","end":"2026-04-22T06:08:04.644802Z","steps":["trace[1155439858] 'read index received' (duration: 574.579709ms)","trace[1155439858] 'applied index is now lower than readState.Index' (duration: 382.323254ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:04.644913Z","caller":"traceutil/trace.go:171","msg":"trace[2006340315] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"448.610338ms","start":"2026-04-22T06:08:04.196268Z","end":"2026-04-22T06:08:04.644879Z","steps":["trace[2006340315] 'process raft request' (duration: 448.402196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.645017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.102344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T06:08:04.645059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.131767ms","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-04-22T06:08:04.645067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:04.196242Z","time spent":"448.741229ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:04.645092Z","caller":"traceutil/trace.go:171","msg":"trace[1164345277] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"950.200968ms","start":"2026-04-22T06:08:03.69488Z","end":"2026-04-22T06:08:04.645081Z","steps":["trace[1164345277] 'agreement among raft nodes before linearized reading' (duration: 950.037167ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:04.645089Z","caller":"traceutil/trace.go:171","msg":"trace[853023615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2205; }","duration":"957.202915ms","start":"2026-04-22T06:08:03.687869Z","end":"2026-04-22T06:08:04.645072Z","steps":["trace[853023615] 'agreement among raft nodes before linearized reading' (duration: 957.022263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.645129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.694864Z","time spent":"950.256529ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-22T06:08:04.645132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.687861Z","time spent":"957.263156ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:08:04.645306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.459308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T06:08:04.645342Z","caller":"traceutil/trace.go:171","msg":"trace[1678580191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2205; }","duration":"905.507869ms","start":"2026-04-22T06:08:03.739823Z","end":"2026-04-22T06:08:04.645331Z","steps":["trace[1678580191] 'agreement among raft nodes before linearized reading' (duration: 905.422428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.645369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.739815Z","time spent":"905.548139ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:04.645405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.62295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T06:08:04.645447Z","caller":"traceutil/trace.go:171","msg":"trace[1980516518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2205; }","duration":"905.68881ms","start":"2026-04-22T06:08:03.739747Z","end":"2026-04-22T06:08:04.645436Z","steps":["trace[1980516518] 'agreement among raft nodes before linearized reading' (duration: 905.533659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.645463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.331247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T06:08:04.64549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.739717Z","time spent":"905.753291ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T06:08:04.645501Z","caller":"traceutil/trace.go:171","msg":"trace[1553887353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2205; }","duration":"906.395068ms","start":"2026-04-22T06:08:03.739097Z","end":"2026-04-22T06:08:04.645492Z","steps":["trace[1553887353] 'agreement among raft nodes before linearized reading' (duration: 906.293997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.645538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.739073Z","time spent":"906.457288ms","remote":"127.0.0.1:51850","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-04-22T06:08:04.645547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.68383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T06:08:04.645666Z","caller":"traceutil/trace.go:171","msg":"trace[1052486441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2205; }","duration":"905.845163ms","start":"2026-04-22T06:08:03.739807Z","end":"2026-04-22T06:08:04.645652Z","steps":["trace[1052486441] 'agreement among raft nodes before linearized reading' (duration: 905.64415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:04.645704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:03.739786Z","time spent":"905.908203ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:05.09996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.668638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:05.100048Z","caller":"traceutil/trace.go:171","msg":"trace[75772623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2210; }","duration":"114.795799ms","start":"2026-04-22T06:08:04.985223Z","end":"2026-04-22T06:08:05.100019Z","steps":["trace[75772623] 'range keys from in-memory index tree' (duration: 114.500697ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:32.993815Z","caller":"traceutil/trace.go:171","msg":"trace[74198851] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"100.551149ms","start":"2026-04-22T06:08:32.893243Z","end":"2026-04-22T06:08:32.993794Z","steps":["trace[74198851] 'process raft request' (duration: 100.515858ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:32.994281Z","caller":"traceutil/trace.go:171","msg":"trace[1413811083] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"102.442033ms","start":"2026-04-22T06:08:32.891827Z","end":"2026-04-22T06:08:32.994269Z","steps":["trace[1413811083] 'process raft request' (duration: 96.938047ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:32.994545Z","caller":"traceutil/trace.go:171","msg":"trace[2127311822] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"102.022964ms","start":"2026-04-22T06:08:32.892511Z","end":"2026-04-22T06:08:32.994534Z","steps":["trace[2127311822] 'process raft request' (duration: 101.178324ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:32.994645Z","caller":"traceutil/trace.go:171","msg":"trace[2054872059] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"101.479111ms","start":"2026-04-22T06:08:32.893142Z","end":"2026-04-22T06:08:32.994621Z","steps":["trace[2054872059] 'process raft request' (duration: 100.58282ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:48.696103Z","caller":"traceutil/trace.go:171","msg":"trace[1025409184] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"117.160948ms","start":"2026-04-22T06:08:48.578915Z","end":"2026-04-22T06:08:48.696076Z","steps":["trace[1025409184] 'process raft request' (duration: 106.560362ms)","trace[1025409184] 'compare' (duration: 10.496984ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:48.696351Z","caller":"traceutil/trace.go:171","msg":"trace[1760776486] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"116.662257ms","start":"2026-04-22T06:08:48.579658Z","end":"2026-04-22T06:08:48.69632Z","steps":["trace[1760776486] 'process raft request' (duration: 116.618186ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:48.696654Z","caller":"traceutil/trace.go:171","msg":"trace[818268745] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"117.614648ms","start":"2026-04-22T06:08:48.579027Z","end":"2026-04-22T06:08:48.696642Z","steps":["trace[818268745] 'process raft request' (duration: 117.113586ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:48.696713Z","caller":"traceutil/trace.go:171","msg":"trace[273822902] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"117.595318ms","start":"2026-04-22T06:08:48.579109Z","end":"2026-04-22T06:08:48.696705Z","steps":["trace[273822902] 'process raft request' (duration: 117.116967ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:56.877279Z","caller":"traceutil/trace.go:171","msg":"trace[961247558] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"140.74033ms","start":"2026-04-22T06:08:56.736522Z","end":"2026-04-22T06:08:56.877262Z","steps":["trace[961247558] 'process raft request' (duration: 140.598847ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:56.878196Z","caller":"traceutil/trace.go:171","msg":"trace[1310164669] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"100.478835ms","start":"2026-04-22T06:08:56.777701Z","end":"2026-04-22T06:08:56.87818Z","steps":["trace[1310164669] 'process raft request' (duration: 100.23549ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:57.694172Z","caller":"traceutil/trace.go:171","msg":"trace[977326070] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"174.849143ms","start":"2026-04-22T06:08:57.519287Z","end":"2026-04-22T06:08:57.694136Z","steps":["trace[977326070] 'process raft request' (duration: 174.68541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:30.739137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.475948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-22T06:09:30.739235Z","caller":"traceutil/trace.go:171","msg":"trace[888256340] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2778; }","duration":"259.622891ms","start":"2026-04-22T06:09:30.479589Z","end":"2026-04-22T06:09:30.739212Z","steps":["trace[888256340] 'range keys from in-memory index tree' (duration: 259.321635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:07.673773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.075771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-22T06:13:07.673897Z","caller":"traceutil/trace.go:171","msg":"trace[17656958] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4511; }","duration":"121.268954ms","start":"2026-04-22T06:13:07.5526Z","end":"2026-04-22T06:13:07.673869Z","steps":["trace[17656958] 'range keys from in-memory index tree' (duration: 120.847829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:07.674573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.903694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-22T06:13:07.674759Z","caller":"traceutil/trace.go:171","msg":"trace[881501429] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4511; }","duration":"118.035186ms","start":"2026-04-22T06:13:07.556624Z","end":"2026-04-22T06:13:07.674659Z","steps":["trace[881501429] 'range keys from in-memory index tree' (duration: 116.80821ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:09.982807Z","caller":"traceutil/trace.go:171","msg":"trace[840411087] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"110.018338ms","start":"2026-04-22T06:13:09.872743Z","end":"2026-04-22T06:13:09.982761Z","steps":["trace[840411087] 'process raft request' (duration: 109.198208ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:18.623724Z","caller":"traceutil/trace.go:171","msg":"trace[623124476] linearizableReadLoop","detail":"{readStateIndex:4866; appliedIndex:4865; }","duration":"106.408555ms","start":"2026-04-22T06:13:18.517288Z","end":"2026-04-22T06:13:18.623696Z","steps":["trace[623124476] 'read index received' (duration: 106.307404ms)","trace[623124476] 'applied index is now lower than readState.Index' (duration: 100.031µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:18.623789Z","caller":"traceutil/trace.go:171","msg":"trace[1038083128] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"132.341459ms","start":"2026-04-22T06:13:18.491407Z","end":"2026-04-22T06:13:18.623748Z","steps":["trace[1038083128] 'process raft request' (duration: 132.104157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:18.624074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.703119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T06:13:18.62405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.730958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T06:13:18.624142Z","caller":"traceutil/trace.go:171","msg":"trace[563297454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4687; }","duration":"106.87893ms","start":"2026-04-22T06:13:18.517245Z","end":"2026-04-22T06:13:18.624124Z","steps":["trace[563297454] 'agreement among raft nodes before linearized reading' (duration: 106.591216ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:18.624171Z","caller":"traceutil/trace.go:171","msg":"trace[2040390266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4687; }","duration":"101.89026ms","start":"2026-04-22T06:13:18.522219Z","end":"2026-04-22T06:13:18.624109Z","steps":["trace[2040390266] 'agreement among raft nodes before linearized reading' (duration: 101.639228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.115832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.197671616s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T06:13:21.150688Z","caller":"traceutil/trace.go:171","msg":"trace[1332014912] linearizableReadLoop","detail":"{readStateIndex:4911; appliedIndex:4909; }","duration":"1.293609706s","start":"2026-04-22T06:13:19.857056Z","end":"2026-04-22T06:13:21.150666Z","steps":["trace[1332014912] 'read index received' (duration: 61.0938ms)","trace[1332014912] 'applied index is now lower than readState.Index' (duration: 1.232514886s)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:21.150766Z","caller":"traceutil/trace.go:171","msg":"trace[57171760] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"1.295129923s","start":"2026-04-22T06:13:19.855604Z","end":"2026-04-22T06:13:21.150734Z","steps":["trace[57171760] 'process raft request' (duration: 1.260406185s)","trace[57171760] 'compare' (duration: 33.231561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:21.150821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293747237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:21.150851Z","caller":"traceutil/trace.go:171","msg":"trace[598793179] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:4731; }","duration":"1.293826039s","start":"2026-04-22T06:13:19.857017Z","end":"2026-04-22T06:13:21.150843Z","steps":["trace[598793179] 'agreement among raft nodes before linearized reading' (duration: 1.293752108s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.150886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:19.856999Z","time spent":"1.293876779s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"warn","ts":"2026-04-22T06:13:21.150931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:19.855584Z","time spent":"1.295266705s","remote":"127.0.0.1:34386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:21.356628Z","caller":"traceutil/trace.go:171","msg":"trace[2008510078] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"1.37581635s","start":"2026-04-22T06:13:19.980788Z","end":"2026-04-22T06:13:21.356605Z","steps":["trace[2008510078] 'process raft request' (duration: 1.375573758s)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.356771Z","caller":"traceutil/trace.go:171","msg":"trace[223811198] linearizableReadLoop","detail":"{readStateIndex:4916; appliedIndex:4911; }","duration":"205.910132ms","start":"2026-04-22T06:13:21.150742Z","end":"2026-04-22T06:13:21.356652Z","steps":["trace[223811198] 'read index received' (duration: 205.675559ms)","trace[223811198] 'applied index is now lower than readState.Index' (duration: 233.593µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:21.35702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.319504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:13:21.357045Z","caller":"traceutil/trace.go:171","msg":"trace[737882324] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"607.558187ms","start":"2026-04-22T06:13:20.749438Z","end":"2026-04-22T06:13:21.356996Z","steps":["trace[737882324] 'process raft request' (duration: 607.171903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.357034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.06876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-22T06:13:21.357163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.749418Z","time spent":"607.681399ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:13:21.357157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.634317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:21.357225Z","caller":"traceutil/trace.go:171","msg":"trace[537598319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4736; }","duration":"369.744818ms","start":"2026-04-22T06:13:20.98747Z","end":"2026-04-22T06:13:21.357215Z","steps":["trace[537598319] 'agreement among raft nodes before linearized reading' (duration: 369.652647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.357292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.987448Z","time spent":"369.834569ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T06:13:21.357382Z","caller":"traceutil/trace.go:171","msg":"trace[538111363] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"1.331372687s","start":"2026-04-22T06:13:20.026001Z","end":"2026-04-22T06:13:21.357374Z","steps":["trace[538111363] 'process raft request' (duration: 1.330494336s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.357432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.025981Z","time spent":"1.331430917s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:21.357478Z","caller":"traceutil/trace.go:171","msg":"trace[267266822] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"1.321212347s","start":"2026-04-22T06:13:20.036248Z","end":"2026-04-22T06:13:21.357461Z","steps":["trace[267266822] 'process raft request' (duration: 1.320323116s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.357573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.036239Z","time spent":"1.321289257s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:21.357682Z","caller":"traceutil/trace.go:171","msg":"trace[314361684] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"1.322096638s","start":"2026-04-22T06:13:20.035574Z","end":"2026-04-22T06:13:21.35767Z","steps":["trace[314361684] 'process raft request' (duration: 1.320966744s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.357732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.035561Z","time spent":"1.322149178s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:13:21.357654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.539307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:13:21.357871Z","caller":"traceutil/trace.go:171","msg":"trace[707462816] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4736; }","duration":"432.80208ms","start":"2026-04-22T06:13:20.925059Z","end":"2026-04-22T06:13:21.357861Z","steps":["trace[707462816] 'agreement among raft nodes before linearized reading' (duration: 432.551987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.357901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.925037Z","time spent":"432.85245ms","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":5,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:13:21.357829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.663327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T06:13:21.358032Z","caller":"traceutil/trace.go:171","msg":"trace[1731108908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4736; }","duration":"697.89186ms","start":"2026-04-22T06:13:20.660132Z","end":"2026-04-22T06:13:21.358024Z","steps":["trace[1731108908] 'agreement among raft nodes before linearized reading' (duration: 697.573156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.358055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.660057Z","time spent":"697.991942ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:13:21.358144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402340452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-565bcdd85-whprl.18a8991c186e7d40\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-04-22T06:13:21.357152Z","caller":"traceutil/trace.go:171","msg":"trace[1928498845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4736; }","duration":"702.201091ms","start":"2026-04-22T06:13:20.654936Z","end":"2026-04-22T06:13:21.357137Z","steps":["trace[1928498845] 'agreement among raft nodes before linearized reading' (duration: 701.962748ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.358198Z","caller":"traceutil/trace.go:171","msg":"trace[1149016556] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-565bcdd85-whprl.18a8991c186e7d40; range_end:; response_count:1; response_revision:4736; }","duration":"1.402427174s","start":"2026-04-22T06:13:19.955757Z","end":"2026-04-22T06:13:21.358184Z","steps":["trace[1149016556] 'agreement among raft nodes before linearized reading' (duration: 1.402263462s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.35824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:19.95574Z","time spent":"1.402491854s","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":918,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-565bcdd85-whprl.18a8991c186e7d40\" "} {"level":"warn","ts":"2026-04-22T06:13:21.35831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.915604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-22T06:13:21.35835Z","caller":"traceutil/trace.go:171","msg":"trace[390319479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4736; }","duration":"875.002015ms","start":"2026-04-22T06:13:20.483338Z","end":"2026-04-22T06:13:21.35834Z","steps":["trace[390319479] 'agreement among raft nodes before linearized reading' (duration: 874.889284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.358412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.483315Z","time spent":"875.085186ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:13:21.358441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341793168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-22T06:13:21.358483Z","caller":"traceutil/trace.go:171","msg":"trace[1125693701] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4736; }","duration":"1.34186503s","start":"2026-04-22T06:13:20.016605Z","end":"2026-04-22T06:13:21.35847Z","steps":["trace[1125693701] 'agreement among raft nodes before linearized reading' (duration: 1.341768299s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.35852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.016592Z","time spent":"1.34192108s","remote":"127.0.0.1:51734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-22T06:13:21.358181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054008021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T06:13:21.358678Z","caller":"traceutil/trace.go:171","msg":"trace[2132887421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4736; }","duration":"1.054560258s","start":"2026-04-22T06:13:20.304108Z","end":"2026-04-22T06:13:21.358669Z","steps":["trace[2132887421] 'agreement among raft nodes before linearized reading' (duration: 1.053975622s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.358712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.304073Z","time spent":"1.054628658s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:13:21.35897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41149993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-22T06:13:21.358999Z","caller":"traceutil/trace.go:171","msg":"trace[282222966] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4736; }","duration":"1.411562831s","start":"2026-04-22T06:13:19.94743Z","end":"2026-04-22T06:13:21.358993Z","steps":["trace[282222966] 'agreement among raft nodes before linearized reading' (duration: 1.410849922s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.35902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:19.947403Z","time spent":"1.411612282s","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2194924,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-04-22T06:13:21.359054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498585157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"warn","ts":"2026-04-22T06:13:21.359072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403963661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-22T06:13:21.359105Z","caller":"traceutil/trace.go:171","msg":"trace[1844791655] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4736; }","duration":"1.403997422s","start":"2026-04-22T06:13:19.955098Z","end":"2026-04-22T06:13:21.359096Z","steps":["trace[1844791655] 'agreement among raft nodes before linearized reading' (duration: 1.403903141s)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.359126Z","caller":"traceutil/trace.go:171","msg":"trace[713043571] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4736; }","duration":"1.498675938s","start":"2026-04-22T06:13:19.860413Z","end":"2026-04-22T06:13:21.359089Z","steps":["trace[713043571] 'agreement among raft nodes before linearized reading' (duration: 1.498529916s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.359145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:19.955076Z","time spent":"1.404061562s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12998,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-22T06:13:21.359188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:19.860407Z","time spent":"1.498770519s","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1154,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-04-22T06:13:21.358698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.654875Z","time spent":"703.402335ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T06:13:21.357076Z","caller":"traceutil/trace.go:171","msg":"trace[2018723042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4736; }","duration":"697.373624ms","start":"2026-04-22T06:13:20.659689Z","end":"2026-04-22T06:13:21.357063Z","steps":["trace[2018723042] 'agreement among raft nodes before linearized reading' (duration: 697.157782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.359519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:20.659627Z","time spent":"699.881414ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:13:21.356787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:19.980751Z","time spent":"1.375931461s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:13:21.363225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.072677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3296"} {"level":"info","ts":"2026-04-22T06:13:21.363297Z","caller":"traceutil/trace.go:171","msg":"trace[1434045885] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4737; }","duration":"202.175018ms","start":"2026-04-22T06:13:21.161103Z","end":"2026-04-22T06:13:21.363278Z","steps":["trace[1434045885] 'agreement among raft nodes before linearized reading' (duration: 201.945876ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.36382Z","caller":"traceutil/trace.go:171","msg":"trace[724671614] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"209.899869ms","start":"2026-04-22T06:13:21.153903Z","end":"2026-04-22T06:13:21.363803Z","steps":["trace[724671614] 'process raft request' (duration: 209.043169ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.524024Z","caller":"traceutil/trace.go:171","msg":"trace[1340929277] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"101.261271ms","start":"2026-04-22T06:13:21.422728Z","end":"2026-04-22T06:13:21.523989Z","steps":["trace[1340929277] 'process raft request' (duration: 18.298805ms)","trace[1340929277] 'compare' (duration: 82.735813ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:21.748113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.79956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:13:21.748492Z","caller":"traceutil/trace.go:171","msg":"trace[1945038799] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"164.015299ms","start":"2026-04-22T06:13:21.584456Z","end":"2026-04-22T06:13:21.748472Z","steps":["trace[1945038799] 'process raft request' (duration: 163.968888ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.748532Z","caller":"traceutil/trace.go:171","msg":"trace[918942135] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"194.508387ms","start":"2026-04-22T06:13:21.553994Z","end":"2026-04-22T06:13:21.748502Z","steps":["trace[918942135] 'process raft request' (duration: 194.375316ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.748631Z","caller":"traceutil/trace.go:171","msg":"trace[384421903] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"215.184941ms","start":"2026-04-22T06:13:21.533374Z","end":"2026-04-22T06:13:21.748559Z","steps":["trace[384421903] 'process raft request' (duration: 99.844125ms)","trace[384421903] 'compare' (duration: 114.665617ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:21.74872Z","caller":"traceutil/trace.go:171","msg":"trace[1111721019] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"203.013577ms","start":"2026-04-22T06:13:21.545687Z","end":"2026-04-22T06:13:21.748701Z","steps":["trace[1111721019] 'process raft request' (duration: 202.537302ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.748583Z","caller":"traceutil/trace.go:171","msg":"trace[1035934488] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4935; }","duration":"171.00009ms","start":"2026-04-22T06:13:21.577525Z","end":"2026-04-22T06:13:21.748525Z","steps":["trace[1035934488] 'read index received' (duration: 55.628173ms)","trace[1035934488] 'applied index is now lower than readState.Index' (duration: 115.368137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:21.749295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.04505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-22T06:13:21.749335Z","caller":"traceutil/trace.go:171","msg":"trace[1984757623] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4759; }","duration":"165.109231ms","start":"2026-04-22T06:13:21.584214Z","end":"2026-04-22T06:13:21.749323Z","steps":["trace[1984757623] 'agreement among raft nodes before linearized reading' (duration: 164.926679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.749392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.364493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9575"} {"level":"info","ts":"2026-04-22T06:13:21.749478Z","caller":"traceutil/trace.go:171","msg":"trace[1975864641] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4759; }","duration":"164.489025ms","start":"2026-04-22T06:13:21.584975Z","end":"2026-04-22T06:13:21.749465Z","steps":["trace[1975864641] 'agreement among raft nodes before linearized reading' (duration: 164.275441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.751372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.827182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-22T06:13:21.751426Z","caller":"traceutil/trace.go:171","msg":"trace[215182465] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4759; }","duration":"217.896503ms","start":"2026-04-22T06:13:21.533516Z","end":"2026-04-22T06:13:21.751413Z","steps":["trace[215182465] 'agreement among raft nodes before linearized reading' (duration: 215.142011ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.939589Z","caller":"traceutil/trace.go:171","msg":"trace[45857679] linearizableReadLoop","detail":"{readStateIndex:4942; appliedIndex:4940; }","duration":"168.572522ms","start":"2026-04-22T06:13:21.770992Z","end":"2026-04-22T06:13:21.939564Z","steps":["trace[45857679] 'read index received' (duration: 102.780219ms)","trace[45857679] 'applied index is now lower than readState.Index' (duration: 65.791023ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:21.939784Z","caller":"traceutil/trace.go:171","msg":"trace[1438029412] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"173.852414ms","start":"2026-04-22T06:13:21.765901Z","end":"2026-04-22T06:13:21.939754Z","steps":["trace[1438029412] 'process raft request' (duration: 173.516761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.939935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.09006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9427"} {"level":"info","ts":"2026-04-22T06:13:21.939974Z","caller":"traceutil/trace.go:171","msg":"trace[1201156891] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4762; }","duration":"181.133011ms","start":"2026-04-22T06:13:21.758829Z","end":"2026-04-22T06:13:21.939962Z","steps":["trace[1201156891] 'agreement among raft nodes before linearized reading' (duration: 181.04245ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.939968Z","caller":"traceutil/trace.go:171","msg":"trace[174225133] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"174.231509ms","start":"2026-04-22T06:13:21.765691Z","end":"2026-04-22T06:13:21.939922Z","steps":["trace[174225133] 'process raft request' (duration: 108.153551ms)","trace[174225133] 'compare' (duration: 65.42325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:21.939849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.939078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-04-22T06:13:21.940136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.071675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a8991cbfb82d1a\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-04-22T06:13:21.940236Z","caller":"traceutil/trace.go:171","msg":"trace[2116463541] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a8991cbfb82d1a; range_end:; response_count:1; response_revision:4762; }","duration":"167.218556ms","start":"2026-04-22T06:13:21.772987Z","end":"2026-04-22T06:13:21.940205Z","steps":["trace[2116463541] 'agreement among raft nodes before linearized reading' (duration: 166.969374ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:21.940149Z","caller":"traceutil/trace.go:171","msg":"trace[443044548] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4762; }","duration":"181.245062ms","start":"2026-04-22T06:13:21.758883Z","end":"2026-04-22T06:13:21.940128Z","steps":["trace[443044548] 'agreement among raft nodes before linearized reading' (duration: 180.820446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.940384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.553459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-22T06:13:21.940434Z","caller":"traceutil/trace.go:171","msg":"trace[1884110115] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4762; }","duration":"161.61636ms","start":"2026-04-22T06:13:21.778806Z","end":"2026-04-22T06:13:21.940422Z","steps":["trace[1884110115] 'agreement among raft nodes before linearized reading' (duration: 161.541799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:21.940518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.071193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-22T06:13:21.940652Z","caller":"traceutil/trace.go:171","msg":"trace[1992341792] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4762; }","duration":"183.231675ms","start":"2026-04-22T06:13:21.757401Z","end":"2026-04-22T06:13:21.940632Z","steps":["trace[1992341792] 'agreement among raft nodes before linearized reading' (duration: 182.79427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.647859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.461328ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:22.648072Z","caller":"traceutil/trace.go:171","msg":"trace[604465458] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4763; }","duration":"253.683381ms","start":"2026-04-22T06:13:22.394365Z","end":"2026-04-22T06:13:22.648048Z","steps":["trace[604465458] 'range keys from in-memory index tree' (duration: 253.445358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.648284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.95979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:13:22.648604Z","caller":"traceutil/trace.go:171","msg":"trace[1198502169] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"702.625396ms","start":"2026-04-22T06:13:21.945948Z","end":"2026-04-22T06:13:22.648574Z","steps":["trace[1198502169] 'process raft request' (duration: 467.285881ms)","trace[1198502169] 'compare' (duration: 234.308233ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:22.648648Z","caller":"traceutil/trace.go:171","msg":"trace[1287045571] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4942; }","duration":"701.598974ms","start":"2026-04-22T06:13:21.947031Z","end":"2026-04-22T06:13:22.64863Z","steps":["trace[1287045571] 'read index received' (duration: 37.254207ms)","trace[1287045571] 'applied index is now lower than readState.Index' (duration: 664.343787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:22.648747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.945925Z","time spent":"702.750097ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:22.648832Z","caller":"traceutil/trace.go:171","msg":"trace[920728198] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"700.288168ms","start":"2026-04-22T06:13:21.948533Z","end":"2026-04-22T06:13:22.648821Z","steps":["trace[920728198] 'process raft request' (duration: 699.953374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.648903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.948519Z","time spent":"700.35164ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2565,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:13:22.649101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.063849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:22.649149Z","caller":"traceutil/trace.go:171","msg":"trace[1964344887] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4766; }","duration":"702.135489ms","start":"2026-04-22T06:13:21.947002Z","end":"2026-04-22T06:13:22.649137Z","steps":["trace[1964344887] 'agreement among raft nodes before linearized reading' (duration: 702.039618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.649222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.946986Z","time spent":"702.228821ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" "} {"level":"info","ts":"2026-04-22T06:13:22.649333Z","caller":"traceutil/trace.go:171","msg":"trace[109133611] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"700.232157ms","start":"2026-04-22T06:13:21.949085Z","end":"2026-04-22T06:13:22.649317Z","steps":["trace[109133611] 'process raft request' (duration: 699.483019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.649415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.949061Z","time spent":"700.318119ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:13:22.692585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.508191ms","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-04-22T06:13:22.692644Z","caller":"traceutil/trace.go:171","msg":"trace[532513360] 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:4767; }","duration":"717.581752ms","start":"2026-04-22T06:13:21.975047Z","end":"2026-04-22T06:13:22.692629Z","steps":["trace[532513360] 'agreement among raft nodes before linearized reading' (duration: 717.364979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.692681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.975041Z","time spent":"717.631412ms","remote":"127.0.0.1:51734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-22T06:13:22.692694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.501907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-04-22T06:13:22.692853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.56066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-22T06:13:22.692906Z","caller":"traceutil/trace.go:171","msg":"trace[853343889] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4767; }","duration":"722.621841ms","start":"2026-04-22T06:13:21.970269Z","end":"2026-04-22T06:13:22.692891Z","steps":["trace[853343889] 'agreement among raft nodes before linearized reading' (duration: 722.352828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.69294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.970264Z","time spent":"722.668941ms","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9662,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-04-22T06:13:22.692948Z","caller":"traceutil/trace.go:171","msg":"trace[2076753257] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"150.783151ms","start":"2026-04-22T06:13:22.542144Z","end":"2026-04-22T06:13:22.692927Z","steps":["trace[2076753257] 'process raft request' (duration: 149.951022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.692593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.517203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-04-22T06:13:22.692822Z","caller":"traceutil/trace.go:171","msg":"trace[1317243866] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4767; }","duration":"743.677809ms","start":"2026-04-22T06:13:21.94912Z","end":"2026-04-22T06:13:22.692798Z","steps":["trace[1317243866] 'agreement among raft nodes before linearized reading' (duration: 743.339805ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:22.693052Z","caller":"traceutil/trace.go:171","msg":"trace[1219223592] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4767; }","duration":"724.996978ms","start":"2026-04-22T06:13:21.968032Z","end":"2026-04-22T06:13:22.693029Z","steps":["trace[1219223592] 'agreement among raft nodes before linearized reading' (duration: 724.405142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.693099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.949107Z","time spent":"743.973553ms","remote":"127.0.0.1:51758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-04-22T06:13:22.693145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:21.968019Z","time spent":"725.08881ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16256,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-22T06:13:22.944911Z","caller":"traceutil/trace.go:171","msg":"trace[982098970] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4948; }","duration":"226.815375ms","start":"2026-04-22T06:13:22.718065Z","end":"2026-04-22T06:13:22.944881Z","steps":["trace[982098970] 'read index received' (duration: 209.808145ms)","trace[982098970] 'applied index is now lower than readState.Index' (duration: 17.00581ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:22.945052Z","caller":"traceutil/trace.go:171","msg":"trace[1469679471] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"232.485392ms","start":"2026-04-22T06:13:22.712553Z","end":"2026-04-22T06:13:22.945039Z","steps":["trace[1469679471] 'process raft request' (duration: 215.26661ms)","trace[1469679471] 'compare' (duration: 16.727517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:22.945359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.233663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4849"} {"level":"info","ts":"2026-04-22T06:13:22.945411Z","caller":"traceutil/trace.go:171","msg":"trace[1623453159] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4769; }","duration":"234.328784ms","start":"2026-04-22T06:13:22.711066Z","end":"2026-04-22T06:13:22.945395Z","steps":["trace[1623453159] 'agreement among raft nodes before linearized reading' (duration: 234.139842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.945464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.844017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-22T06:13:22.945554Z","caller":"traceutil/trace.go:171","msg":"trace[2018170828] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4769; }","duration":"238.957958ms","start":"2026-04-22T06:13:22.706573Z","end":"2026-04-22T06:13:22.94553Z","steps":["trace[2018170828] 'agreement among raft nodes before linearized reading' (duration: 238.679325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:22.94562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.737587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-22T06:13:22.946959Z","caller":"traceutil/trace.go:171","msg":"trace[1101157159] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4769; }","duration":"224.332716ms","start":"2026-04-22T06:13:22.722602Z","end":"2026-04-22T06:13:22.946935Z","steps":["trace[1101157159] 'agreement among raft nodes before linearized reading' (duration: 222.634226ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:23.20461Z","caller":"traceutil/trace.go:171","msg":"trace[64355739] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"250.587692ms","start":"2026-04-22T06:13:22.953988Z","end":"2026-04-22T06:13:23.204575Z","steps":["trace[64355739] 'process raft request' (duration: 249.039694ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:23.206222Z","caller":"traceutil/trace.go:171","msg":"trace[188923867] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"251.854947ms","start":"2026-04-22T06:13:22.954353Z","end":"2026-04-22T06:13:23.206208Z","steps":["trace[188923867] 'process raft request' (duration: 251.690845ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:23.206663Z","caller":"traceutil/trace.go:171","msg":"trace[703952730] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"252.234512ms","start":"2026-04-22T06:13:22.954401Z","end":"2026-04-22T06:13:23.206636Z","steps":["trace[703952730] 'process raft request' (duration: 251.752746ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:23.206691Z","caller":"traceutil/trace.go:171","msg":"trace[772944887] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"250.800265ms","start":"2026-04-22T06:13:22.955866Z","end":"2026-04-22T06:13:23.206666Z","steps":["trace[772944887] 'process raft request' (duration: 250.623993ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:23.206789Z","caller":"traceutil/trace.go:171","msg":"trace[352140082] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4949; }","duration":"250.996187ms","start":"2026-04-22T06:13:22.95578Z","end":"2026-04-22T06:13:23.206776Z","steps":["trace[352140082] 'read index received' (duration: 247.215464ms)","trace[352140082] 'applied index is now lower than readState.Index' (duration: 3.779613ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:23.206845Z","caller":"traceutil/trace.go:171","msg":"trace[2003979255] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"241.762608ms","start":"2026-04-22T06:13:22.965053Z","end":"2026-04-22T06:13:23.206815Z","steps":["trace[2003979255] 'process raft request' (duration: 241.549635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:23.207065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.266041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-22T06:13:23.207104Z","caller":"traceutil/trace.go:171","msg":"trace[188460887] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4774; }","duration":"251.334082ms","start":"2026-04-22T06:13:22.95576Z","end":"2026-04-22T06:13:23.207094Z","steps":["trace[188460887] 'agreement among raft nodes before linearized reading' (duration: 251.20503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:23.207167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.513869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-22T06:13:23.207218Z","caller":"traceutil/trace.go:171","msg":"trace[1680255170] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4774; }","duration":"203.615661ms","start":"2026-04-22T06:13:23.003586Z","end":"2026-04-22T06:13:23.207201Z","steps":["trace[1680255170] 'agreement among raft nodes before linearized reading' (duration: 203.484729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:23.207238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.108184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:13:23.207287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.835943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-04-22T06:13:23.207379Z","caller":"traceutil/trace.go:171","msg":"trace[1481646271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4774; }","duration":"220.280246ms","start":"2026-04-22T06:13:22.987079Z","end":"2026-04-22T06:13:23.20736Z","steps":["trace[1481646271] 'agreement among raft nodes before linearized reading' (duration: 220.085153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:23.20733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.821551ms","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-04-22T06:13:23.207388Z","caller":"traceutil/trace.go:171","msg":"trace[1886775756] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4774; }","duration":"244.013435ms","start":"2026-04-22T06:13:22.963358Z","end":"2026-04-22T06:13:23.207371Z","steps":["trace[1886775756] 'agreement among raft nodes before linearized reading' (duration: 243.659961ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:23.207458Z","caller":"traceutil/trace.go:171","msg":"trace[350781206] 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:4774; }","duration":"196.958463ms","start":"2026-04-22T06:13:23.010488Z","end":"2026-04-22T06:13:23.207447Z","steps":["trace[350781206] 'agreement among raft nodes before linearized reading' (duration: 196.794971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:23.207508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.291213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61668"} {"level":"info","ts":"2026-04-22T06:13:23.207585Z","caller":"traceutil/trace.go:171","msg":"trace[367002242] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4774; }","duration":"241.404094ms","start":"2026-04-22T06:13:22.966168Z","end":"2026-04-22T06:13:23.207572Z","steps":["trace[367002242] 'agreement among raft nodes before linearized reading' (duration: 241.08003ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:25.253873Z","caller":"traceutil/trace.go:171","msg":"trace[465106464] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"214.145269ms","start":"2026-04-22T06:13:25.039699Z","end":"2026-04-22T06:13:25.253844Z","steps":["trace[465106464] 'process raft request' (duration: 213.917896ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:25.258214Z","caller":"traceutil/trace.go:171","msg":"trace[1476797480] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"216.305495ms","start":"2026-04-22T06:13:25.041888Z","end":"2026-04-22T06:13:25.258194Z","steps":["trace[1476797480] 'process raft request' (duration: 216.171223ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:26.258276Z","caller":"traceutil/trace.go:171","msg":"trace[217793117] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"242.903775ms","start":"2026-04-22T06:13:26.015345Z","end":"2026-04-22T06:13:26.258249Z","steps":["trace[217793117] 'process raft request' (duration: 242.654403ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:31.346738Z","caller":"traceutil/trace.go:171","msg":"trace[716703301] linearizableReadLoop","detail":"{readStateIndex:5085; appliedIndex:5084; }","duration":"227.223149ms","start":"2026-04-22T06:13:31.119489Z","end":"2026-04-22T06:13:31.346712Z","steps":["trace[716703301] 'read index received' (duration: 227.052077ms)","trace[716703301] 'applied index is now lower than readState.Index' (duration: 170.052µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:31.346835Z","caller":"traceutil/trace.go:171","msg":"trace[1040002296] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"281.636734ms","start":"2026-04-22T06:13:31.065158Z","end":"2026-04-22T06:13:31.346795Z","steps":["trace[1040002296] 'process raft request' (duration: 281.403452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:31.34702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.515482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-22T06:13:31.347015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.124391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:31.347083Z","caller":"traceutil/trace.go:171","msg":"trace[343451145] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4900; }","duration":"227.592263ms","start":"2026-04-22T06:13:31.119473Z","end":"2026-04-22T06:13:31.347065Z","steps":["trace[343451145] 'agreement among raft nodes before linearized reading' (duration: 227.384611ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:31.347104Z","caller":"traceutil/trace.go:171","msg":"trace[195515661] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4900; }","duration":"144.294922ms","start":"2026-04-22T06:13:31.202792Z","end":"2026-04-22T06:13:31.347087Z","steps":["trace[195515661] 'agreement among raft nodes before linearized reading' (duration: 144.11015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:36.622111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339364134,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:13:37.122621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339364134,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:13:37.623559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831313339364134,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:13:37.71269Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.535483281s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T06:13:37.713058Z","caller":"traceutil/trace.go:171","msg":"trace[219020882] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"1.608277787s","start":"2026-04-22T06:13:36.104759Z","end":"2026-04-22T06:13:37.713037Z","steps":["trace[219020882] 'process raft request' (duration: 1.608089645s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.713227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:36.104741Z","time spent":"1.608383348s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17217,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:37.914281Z","caller":"traceutil/trace.go:171","msg":"trace[355529666] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"1.538551185s","start":"2026-04-22T06:13:36.375696Z","end":"2026-04-22T06:13:37.914247Z","steps":["trace[355529666] 'process raft request' (duration: 1.444442443s)","trace[355529666] 'compare' (duration: 93.90021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:37.914413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:36.375671Z","time spent":"1.538673987s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:37.914553Z","caller":"traceutil/trace.go:171","msg":"trace[671649505] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"502.915248ms","start":"2026-04-22T06:13:37.411614Z","end":"2026-04-22T06:13:37.91453Z","steps":["trace[671649505] 'process raft request' (duration: 502.591225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.91469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.411593Z","time spent":"503.026549ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:37.914932Z","caller":"traceutil/trace.go:171","msg":"trace[475221971] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"471.596374ms","start":"2026-04-22T06:13:37.443324Z","end":"2026-04-22T06:13:37.914921Z","steps":["trace[475221971] 'process raft request' (duration: 471.094159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.914995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.4433Z","time spent":"471.666825ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:37.915094Z","caller":"traceutil/trace.go:171","msg":"trace[1835870692] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"471.223369ms","start":"2026-04-22T06:13:37.443855Z","end":"2026-04-22T06:13:37.915078Z","steps":["trace[1835870692] 'process raft request' (duration: 471.022587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.9152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.443826Z","time spent":"471.315541ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:13:37.915248Z","caller":"traceutil/trace.go:171","msg":"trace[1191880994] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5133; }","duration":"1.793878492s","start":"2026-04-22T06:13:36.121354Z","end":"2026-04-22T06:13:37.915232Z","steps":["trace[1191880994] 'read index received' (duration: 1.591510232s)","trace[1191880994] 'applied index is now lower than readState.Index' (duration: 202.36697ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:37.91531Z","caller":"traceutil/trace.go:171","msg":"trace[415958538] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"471.135519ms","start":"2026-04-22T06:13:37.444165Z","end":"2026-04-22T06:13:37.915301Z","steps":["trace[415958538] 'process raft request' (duration: 470.849275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.444153Z","time spent":"471.17872ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:13:37.915444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794087745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-22T06:13:37.915484Z","caller":"traceutil/trace.go:171","msg":"trace[1583308954] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4953; }","duration":"1.794153945s","start":"2026-04-22T06:13:36.121319Z","end":"2026-04-22T06:13:37.915473Z","steps":["trace[1583308954] 'agreement among raft nodes before linearized reading' (duration: 1.794019714s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752979306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-22T06:13:37.915513Z","caller":"traceutil/trace.go:171","msg":"trace[6037680] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4953; }","duration":"1.753025607s","start":"2026-04-22T06:13:36.16248Z","end":"2026-04-22T06:13:37.915505Z","steps":["trace[6037680] 'agreement among raft nodes before linearized reading' (duration: 1.752937226s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:36.121308Z","time spent":"1.794198676s","remote":"127.0.0.1:51734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-22T06:13:37.915516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.689374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T06:13:37.915531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:36.162466Z","time spent":"1.753061757s","remote":"127.0.0.1:34386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-22T06:13:37.915553Z","caller":"traceutil/trace.go:171","msg":"trace[594351171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4953; }","duration":"426.757204ms","start":"2026-04-22T06:13:37.488785Z","end":"2026-04-22T06:13:37.915542Z","steps":["trace[594351171] 'agreement among raft nodes before linearized reading' (duration: 426.647323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.488771Z","time spent":"426.807784ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:13:37.915684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.881386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-22T06:13:37.915703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.404294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:13:37.915707Z","caller":"traceutil/trace.go:171","msg":"trace[1850445830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4953; }","duration":"426.905366ms","start":"2026-04-22T06:13:37.488797Z","end":"2026-04-22T06:13:37.915702Z","steps":["trace[1850445830] 'agreement among raft nodes before linearized reading' (duration: 426.846455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.488727Z","time spent":"426.995717ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T06:13:37.915727Z","caller":"traceutil/trace.go:171","msg":"trace[1482875855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4953; }","duration":"410.466984ms","start":"2026-04-22T06:13:37.505252Z","end":"2026-04-22T06:13:37.915719Z","steps":["trace[1482875855] 'agreement among raft nodes before linearized reading' (duration: 410.393683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.505227Z","time spent":"410.519595ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:13:37.915777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.748912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:37.915797Z","caller":"traceutil/trace.go:171","msg":"trace[344228807] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:4953; }","duration":"599.806322ms","start":"2026-04-22T06:13:37.315986Z","end":"2026-04-22T06:13:37.915793Z","steps":["trace[344228807] 'agreement among raft nodes before linearized reading' (duration: 599.776191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.315963Z","time spent":"599.847742ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:13:37.915811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.686128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T06:13:37.91585Z","caller":"traceutil/trace.go:171","msg":"trace[1894466603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4953; }","duration":"417.754779ms","start":"2026-04-22T06:13:37.498086Z","end":"2026-04-22T06:13:37.915841Z","steps":["trace[1894466603] 'agreement among raft nodes before linearized reading' (duration: 417.642888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.050929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-04-22T06:13:37.915871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.578889ms","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-04-22T06:13:37.915886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.498078Z","time spent":"417.80181ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:13:37.915906Z","caller":"traceutil/trace.go:171","msg":"trace[77160328] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4953; }","duration":"198.112289ms","start":"2026-04-22T06:13:37.717785Z","end":"2026-04-22T06:13:37.915898Z","steps":["trace[77160328] 'agreement among raft nodes before linearized reading' (duration: 198.030528ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:37.915917Z","caller":"traceutil/trace.go:171","msg":"trace[1683827525] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4953; }","duration":"381.652091ms","start":"2026-04-22T06:13:37.534252Z","end":"2026-04-22T06:13:37.915905Z","steps":["trace[1683827525] 'agreement among raft nodes before linearized reading' (duration: 381.57929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.915954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.573875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:13:37.915952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.53424Z","time spent":"381.702781ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:13:37.915935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.760656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:37.916015Z","caller":"traceutil/trace.go:171","msg":"trace[964891967] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4953; }","duration":"825.895417ms","start":"2026-04-22T06:13:37.090098Z","end":"2026-04-22T06:13:37.915994Z","steps":["trace[964891967] 'agreement among raft nodes before linearized reading' (duration: 825.747885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.916067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.090077Z","time spent":"825.979128ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:13:37.915981Z","caller":"traceutil/trace.go:171","msg":"trace[1015414652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4953; }","duration":"932.624726ms","start":"2026-04-22T06:13:36.983351Z","end":"2026-04-22T06:13:37.915975Z","steps":["trace[1015414652] 'agreement among raft nodes before linearized reading' (duration: 932.584095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.916553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.671978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:13:37.916641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:36.983335Z","time spent":"933.292784ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T06:13:37.916172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.802008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T06:13:37.916765Z","caller":"traceutil/trace.go:171","msg":"trace[1043906687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4953; }","duration":"411.406626ms","start":"2026-04-22T06:13:37.505315Z","end":"2026-04-22T06:13:37.916722Z","steps":["trace[1043906687] 'agreement among raft nodes before linearized reading' (duration: 410.215492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.916817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.50531Z","time spent":"411.493267ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T06:13:37.917617Z","caller":"traceutil/trace.go:171","msg":"trace[1928330162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4953; }","duration":"816.177744ms","start":"2026-04-22T06:13:37.100467Z","end":"2026-04-22T06:13:37.916644Z","steps":["trace[1928330162] 'agreement among raft nodes before linearized reading' (duration: 815.487156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:37.91768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:13:37.100455Z","time spent":"817.209465ms","remote":"127.0.0.1:43498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:13:38.152756Z","caller":"traceutil/trace.go:171","msg":"trace[1710715245] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5149; }","duration":"160.35272ms","start":"2026-04-22T06:13:37.992372Z","end":"2026-04-22T06:13:38.152725Z","steps":["trace[1710715245] 'read index received' (duration: 65.56918ms)","trace[1710715245] 'applied index is now lower than readState.Index' (duration: 94.78201ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:13:38.152927Z","caller":"traceutil/trace.go:171","msg":"trace[1475730498] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"181.83525ms","start":"2026-04-22T06:13:37.971073Z","end":"2026-04-22T06:13:38.152908Z","steps":["trace[1475730498] 'process raft request' (duration: 86.833578ms)","trace[1475730498] 'compare' (duration: 94.627278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:38.153229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.317129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:38.153338Z","caller":"traceutil/trace.go:171","msg":"trace[577129103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4964; }","duration":"166.509312ms","start":"2026-04-22T06:13:37.9868Z","end":"2026-04-22T06:13:38.15331Z","steps":["trace[577129103] 'agreement among raft nodes before linearized reading' (duration: 166.293679ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:38.177111Z","caller":"traceutil/trace.go:171","msg":"trace[2028759204] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"181.393515ms","start":"2026-04-22T06:13:37.995675Z","end":"2026-04-22T06:13:38.177069Z","steps":["trace[2028759204] 'process raft request' (duration: 181.200931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:38.177231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.947556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-22T06:13:38.177345Z","caller":"traceutil/trace.go:171","msg":"trace[2101582176] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4965; }","duration":"179.104457ms","start":"2026-04-22T06:13:37.998223Z","end":"2026-04-22T06:13:38.177327Z","steps":["trace[2101582176] 'agreement among raft nodes before linearized reading' (duration: 178.824794ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:14.324498Z","caller":"traceutil/trace.go:171","msg":"trace[1287967229] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"123.347956ms","start":"2026-04-22T06:14:14.201122Z","end":"2026-04-22T06:14:14.32447Z","steps":["trace[1287967229] 'process raft request' (duration: 39.76107ms)","trace[1287967229] 'compare' (duration: 83.409344ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:14.324587Z","caller":"traceutil/trace.go:171","msg":"trace[628703449] linearizableReadLoop","detail":"{readStateIndex:5474; appliedIndex:5472; }","duration":"106.0148ms","start":"2026-04-22T06:14:14.218551Z","end":"2026-04-22T06:14:14.324565Z","steps":["trace[628703449] 'read index received' (duration: 22.343883ms)","trace[628703449] 'applied index is now lower than readState.Index' (duration: 83.669937ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:14.324695Z","caller":"traceutil/trace.go:171","msg":"trace[1550767250] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"123.431168ms","start":"2026-04-22T06:14:14.201192Z","end":"2026-04-22T06:14:14.324623Z","steps":["trace[1550767250] 'process raft request' (duration: 123.240056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:14.324797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.231512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T06:14:14.324877Z","caller":"traceutil/trace.go:171","msg":"trace[761827710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5278; }","duration":"106.341974ms","start":"2026-04-22T06:14:14.218514Z","end":"2026-04-22T06:14:14.324856Z","steps":["trace[761827710] 'agreement among raft nodes before linearized reading' (duration: 106.152082ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:14.438787Z","caller":"traceutil/trace.go:171","msg":"trace[1944731211] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"106.953791ms","start":"2026-04-22T06:14:14.33176Z","end":"2026-04-22T06:14:14.438714Z","steps":["trace[1944731211] 'process raft request' (duration: 106.89353ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:14.438857Z","caller":"traceutil/trace.go:171","msg":"trace[48532992] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"107.281455ms","start":"2026-04-22T06:14:14.331538Z","end":"2026-04-22T06:14:14.43882Z","steps":["trace[48532992] 'process raft request' (duration: 106.941601ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:16.58826Z","caller":"traceutil/trace.go:171","msg":"trace[1553209003] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"110.194566ms","start":"2026-04-22T06:14:16.478043Z","end":"2026-04-22T06:14:16.588238Z","steps":["trace[1553209003] 'process raft request' (duration: 109.590429ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:30.444446Z","caller":"traceutil/trace.go:171","msg":"trace[1109124960] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"158.6518ms","start":"2026-04-22T06:14:30.285766Z","end":"2026-04-22T06:14:30.444418Z","steps":["trace[1109124960] 'process raft request' (duration: 115.813569ms)","trace[1109124960] 'compare' (duration: 42.407727ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:45.704788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-04-22T06:14:45.796104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"89.788091ms","hash":3794349140,"current-db-size-bytes":53805056,"current-db-size":"54 MB","current-db-size-in-use-bytes":28905472,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-22T06:14:45.796169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3794349140,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-04-22T06:19:45.711468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5502} {"level":"info","ts":"2026-04-22T06:19:45.799713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5502,"took":"85.498942ms","hash":978384792,"current-db-size-bytes":53805056,"current-db-size":"54 MB","current-db-size-in-use-bytes":18239488,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-22T06:19:45.799779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":978384792,"revision":5502,"compact-revision":2862}