{"level":"warn","ts":"2026-05-04T18:48:09.100126Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T18:48:09.100212Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.235:2380","--initial-cluster=instance=https://199.19.213.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.235: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-05-04T18:48:09.100293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T18:48:09.100307Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.235:2380"]} {"level":"info","ts":"2026-05-04T18:48:09.100328Z","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-05-04T18:48:09.100928Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.235:2379"]} {"level":"info","ts":"2026-05-04T18:48:09.101012Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.235:2380"],"listen-peer-urls":["https://199.19.213.235:2380"],"advertise-client-urls":["https://199.19.213.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.235: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-05-04T18:48:09.107306Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.010912ms"} {"level":"info","ts":"2026-05-04T18:48:09.114395Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61a3fe3fc9dc5d35","cluster-id":"e13fd0e1d9ce1168"} {"level":"info","ts":"2026-05-04T18:48:09.114495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T18:48:09.11453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 became follower at term 0"} {"level":"info","ts":"2026-05-04T18:48:09.114544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61a3fe3fc9dc5d35 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T18:48:09.114565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 became follower at term 1"} {"level":"info","ts":"2026-05-04T18:48:09.114615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 switched to configuration voters=(7035746592805903669)"} {"level":"warn","ts":"2026-05-04T18:48:09.123158Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T18:48:09.125018Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T18:48:09.127842Z","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-05-04T18:48:09.131994Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61a3fe3fc9dc5d35","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T18:48:09.132569Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61a3fe3fc9dc5d35","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T18:48:09.132935Z","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-05-04T18:48:09.13298Z","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-05-04T18:48:09.132992Z","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-05-04T18:48:09.136292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 switched to configuration voters=(7035746592805903669)"} {"level":"info","ts":"2026-05-04T18:48:09.13654Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e13fd0e1d9ce1168","local-member-id":"61a3fe3fc9dc5d35","added-peer-id":"61a3fe3fc9dc5d35","added-peer-peer-urls":["https://199.19.213.235:2380"]} {"level":"info","ts":"2026-05-04T18:48:09.139517Z","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-05-04T18:48:09.139703Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.235:2380"} {"level":"info","ts":"2026-05-04T18:48:09.139756Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.235:2380"} {"level":"info","ts":"2026-05-04T18:48:09.141085Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61a3fe3fc9dc5d35","initial-advertise-peer-urls":["https://199.19.213.235:2380"],"listen-peer-urls":["https://199.19.213.235:2380"],"advertise-client-urls":["https://199.19.213.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T18:48:09.141124Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T18:48:09.715276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T18:48:09.71533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T18:48:09.715351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 received MsgPreVoteResp from 61a3fe3fc9dc5d35 at term 1"} {"level":"info","ts":"2026-05-04T18:48:09.715379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 became candidate at term 2"} {"level":"info","ts":"2026-05-04T18:48:09.715396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 received MsgVoteResp from 61a3fe3fc9dc5d35 at term 2"} {"level":"info","ts":"2026-05-04T18:48:09.715417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a3fe3fc9dc5d35 became leader at term 2"} {"level":"info","ts":"2026-05-04T18:48:09.715435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61a3fe3fc9dc5d35 elected leader 61a3fe3fc9dc5d35 at term 2"} {"level":"info","ts":"2026-05-04T18:48:09.716317Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:48:09.717417Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61a3fe3fc9dc5d35","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.235:2379]}","request-path":"/0/members/61a3fe3fc9dc5d35/attributes","cluster-id":"e13fd0e1d9ce1168","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T18:48:09.717483Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:48:09.717552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:48:09.717731Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T18:48:09.717799Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T18:48:09.718169Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e13fd0e1d9ce1168","local-member-id":"61a3fe3fc9dc5d35","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:48:09.718247Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:48:09.718273Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:48:09.720117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.235:2379"} {"level":"info","ts":"2026-05-04T18:48:09.720689Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T18:48:14.065175Z","caller":"traceutil/trace.go:171","msg":"trace[1186971984] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"236.622266ms","start":"2026-05-04T18:48:13.828526Z","end":"2026-05-04T18:48:14.065148Z","steps":["trace[1186971984] 'read index received' (duration: 236.46672ms)","trace[1186971984] 'applied index is now lower than readState.Index' (duration: 154.436µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:48:14.065216Z","caller":"traceutil/trace.go:171","msg":"trace[1204310217] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"439.44277ms","start":"2026-05-04T18:48:13.625741Z","end":"2026-05-04T18:48:14.065183Z","steps":["trace[1204310217] 'process raft request' (duration: 439.254923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:14.065323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.777502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:48:14.065438Z","caller":"traceutil/trace.go:171","msg":"trace[2128059088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:251; }","duration":"236.951317ms","start":"2026-05-04T18:48:13.828472Z","end":"2026-05-04T18:48:14.065423Z","steps":["trace[2128059088] 'agreement among raft nodes before linearized reading' (duration: 236.787482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:14.065931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:13.625719Z","time spent":"439.576355ms","remote":"127.0.0.1:38932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:48:14.563176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.53746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:48:14.563313Z","caller":"traceutil/trace.go:171","msg":"trace[1086020862] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"493.7263ms","start":"2026-05-04T18:48:14.069566Z","end":"2026-05-04T18:48:14.563292Z","steps":["trace[1086020862] 'process raft request' (duration: 204.796856ms)","trace[1086020862] 'compare' (duration: 288.286251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:48:14.563549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:14.069537Z","time spent":"493.829684ms","remote":"127.0.0.1:39040","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-05-04T18:48:14.563512Z","caller":"traceutil/trace.go:171","msg":"trace[1952204027] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"492.969674ms","start":"2026-05-04T18:48:14.070326Z","end":"2026-05-04T18:48:14.563295Z","steps":["trace[1952204027] 'read index received' (duration: 203.994878ms)","trace[1952204027] 'applied index is now lower than readState.Index' (duration: 288.972886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:48:14.563783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-puyahx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:48:14.563844Z","caller":"traceutil/trace.go:171","msg":"trace[1406775961] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-puyahx; range_end:; response_count:0; response_revision:252; }","duration":"493.536614ms","start":"2026-05-04T18:48:14.07029Z","end":"2026-05-04T18:48:14.563827Z","steps":["trace[1406775961] 'agreement among raft nodes before linearized reading' (duration: 493.367778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:14.563888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:14.070279Z","time spent":"493.600176ms","remote":"127.0.0.1:38870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-puyahx\" "} {"level":"warn","ts":"2026-05-04T18:48:14.563905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.307248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:48:14.564037Z","caller":"traceutil/trace.go:171","msg":"trace[1740873827] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:252; }","duration":"378.551437ms","start":"2026-05-04T18:48:14.185462Z","end":"2026-05-04T18:48:14.564013Z","steps":["trace[1740873827] 'agreement among raft nodes before linearized reading' (duration: 378.246356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:14.564156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:14.185442Z","time spent":"378.689182ms","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-05-04T18:48:44.961733Z","caller":"traceutil/trace.go:171","msg":"trace[1381675810] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"224.663562ms","start":"2026-05-04T18:48:44.737039Z","end":"2026-05-04T18:48:44.961703Z","steps":["trace[1381675810] 'read index received' (duration: 222.848506ms)","trace[1381675810] 'applied index is now lower than readState.Index' (duration: 1.813636ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:48:44.961765Z","caller":"traceutil/trace.go:171","msg":"trace[122034099] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"466.92391ms","start":"2026-05-04T18:48:44.494818Z","end":"2026-05-04T18:48:44.961741Z","steps":["trace[122034099] 'process raft request' (duration: 465.503909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:44.96199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:44.494796Z","time spent":"467.072393ms","remote":"127.0.0.1:38870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:48:44.961978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.870026ms","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-05-04T18:48:44.962203Z","caller":"traceutil/trace.go:171","msg":"trace[1687464894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"225.135379ms","start":"2026-05-04T18:48:44.737Z","end":"2026-05-04T18:48:44.962135Z","steps":["trace[1687464894] 'agreement among raft nodes before linearized reading' (duration: 224.828545ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:48:45.574541Z","caller":"traceutil/trace.go:171","msg":"trace[63549185] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"201.957193ms","start":"2026-05-04T18:48:45.372555Z","end":"2026-05-04T18:48:45.574512Z","steps":["trace[63549185] 'process raft request' (duration: 201.78208ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:48:46.209894Z","caller":"traceutil/trace.go:171","msg":"trace[1602292930] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"833.859843ms","start":"2026-05-04T18:48:45.372832Z","end":"2026-05-04T18:48:46.206692Z","steps":["trace[1602292930] 'process raft request' (duration: 832.155748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:46.210076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:45.372817Z","time spent":"837.163812ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:48:46.213322Z","caller":"traceutil/trace.go:171","msg":"trace[129956838] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"765.997532ms","start":"2026-05-04T18:48:45.447306Z","end":"2026-05-04T18:48:46.213304Z","steps":["trace[129956838] 'process raft request' (duration: 758.289948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:46.213395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:45.447287Z","time spent":"766.070592ms","remote":"127.0.0.1:38870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:48:46.950516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.310554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:48:46.950736Z","caller":"traceutil/trace.go:171","msg":"trace[1693301671] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"679.343583ms","start":"2026-05-04T18:48:46.271371Z","end":"2026-05-04T18:48:46.950715Z","steps":["trace[1693301671] 'process raft request' (duration: 679.265022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:46.950828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:46.27135Z","time spent":"679.438085ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T18:48:46.950935Z","caller":"traceutil/trace.go:171","msg":"trace[2057952957] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.308526364s","start":"2026-05-04T18:48:45.642387Z","end":"2026-05-04T18:48:46.950913Z","steps":["trace[2057952957] 'process raft request' (duration: 651.719953ms)","trace[2057952957] 'compare' (duration: 656.177402ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:48:46.951028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:45.64236Z","time spent":"1.308619625s","remote":"127.0.0.1:39040","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-05-04T18:48:46.951059Z","caller":"traceutil/trace.go:171","msg":"trace[619098846] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:637; }","duration":"854.967037ms","start":"2026-05-04T18:48:46.096026Z","end":"2026-05-04T18:48:46.950992Z","steps":["trace[619098846] 'read index received' (duration: 108.983489ms)","trace[619098846] 'applied index is now lower than readState.Index' (duration: 745.981468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:48:46.951202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.180201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j5hw6\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-04T18:48:46.951212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.395514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:48:46.951256Z","caller":"traceutil/trace.go:171","msg":"trace[1595210458] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j5hw6; range_end:; response_count:1; response_revision:619; }","duration":"855.273352ms","start":"2026-05-04T18:48:46.095968Z","end":"2026-05-04T18:48:46.951241Z","steps":["trace[1595210458] 'agreement among raft nodes before linearized reading' (duration: 855.17475ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:48:46.951256Z","caller":"traceutil/trace.go:171","msg":"trace[1172718466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"660.467545ms","start":"2026-05-04T18:48:46.290778Z","end":"2026-05-04T18:48:46.951245Z","steps":["trace[1172718466] 'agreement among raft nodes before linearized reading' (duration: 660.397344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:48:46.951303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:46.095942Z","time spent":"855.352563ms","remote":"127.0.0.1:38946","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-j5hw6\" "} {"level":"warn","ts":"2026-05-04T18:48:46.951318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:48:46.290765Z","time spent":"660.538046ms","remote":"127.0.0.1:38750","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-05-04T18:49:01.41649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.431006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:49:01.416582Z","caller":"traceutil/trace.go:171","msg":"trace[1180627143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"123.560249ms","start":"2026-05-04T18:49:01.293002Z","end":"2026-05-04T18:49:01.416563Z","steps":["trace[1180627143] 'range keys from in-memory index tree' (duration: 123.379596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:49:01.416833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.183006ms","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-05-04T18:49:01.416946Z","caller":"traceutil/trace.go:171","msg":"trace[2106797853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"367.357489ms","start":"2026-05-04T18:49:01.049564Z","end":"2026-05-04T18:49:01.416922Z","steps":["trace[2106797853] 'range keys from in-memory index tree' (duration: 366.975163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:49:01.416957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.788573ms","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-05-04T18:49:01.416999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:49:01.049541Z","time spent":"367.444431ms","remote":"127.0.0.1:39040","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-05-04T18:49:01.417056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.189255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-88j5d\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-04T18:49:01.417127Z","caller":"traceutil/trace.go:171","msg":"trace[1998320375] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-88j5d; range_end:; response_count:1; response_revision:780; }","duration":"226.302206ms","start":"2026-05-04T18:49:01.190808Z","end":"2026-05-04T18:49:01.41711Z","steps":["trace[1998320375] 'range keys from in-memory index tree' (duration: 225.995101ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:49:01.417084Z","caller":"traceutil/trace.go:171","msg":"trace[1366436431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"365.930056ms","start":"2026-05-04T18:49:01.051081Z","end":"2026-05-04T18:49:01.417012Z","steps":["trace[1366436431] 'range keys from in-memory index tree' (duration: 365.462548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:49:01.41724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:49:01.051032Z","time spent":"366.135359ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-04T18:50:19.082075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.953637ms","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-05-04T18:50:19.082173Z","caller":"traceutil/trace.go:171","msg":"trace[2047257166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1861; }","duration":"427.118362ms","start":"2026-05-04T18:50:18.655034Z","end":"2026-05-04T18:50:19.082152Z","steps":["trace[2047257166] 'range keys from in-memory index tree' (duration: 426.747252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:19.082325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.588315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-04T18:50:19.082462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:18.655004Z","time spent":"427.202143ms","remote":"127.0.0.1:39040","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":"info","ts":"2026-05-04T18:50:19.082512Z","caller":"traceutil/trace.go:171","msg":"trace[1208061295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1861; }","duration":"418.8228ms","start":"2026-05-04T18:50:18.663665Z","end":"2026-05-04T18:50:19.082488Z","steps":["trace[1208061295] 'range keys from in-memory index tree' (duration: 418.431461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:19.082701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:18.663657Z","time spent":"419.029275ms","remote":"127.0.0.1:39040","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-05-04T18:50:19.141099Z","caller":"traceutil/trace.go:171","msg":"trace[2001145441] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"147.621853ms","start":"2026-05-04T18:50:18.993452Z","end":"2026-05-04T18:50:19.141074Z","steps":["trace[2001145441] 'read index received' (duration: 147.385027ms)","trace[2001145441] 'applied index is now lower than readState.Index' (duration: 235.716µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:50:19.141206Z","caller":"traceutil/trace.go:171","msg":"trace[1359743540] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"206.678156ms","start":"2026-05-04T18:50:18.93449Z","end":"2026-05-04T18:50:19.141169Z","steps":["trace[1359743540] 'process raft request' (duration: 206.372929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:19.141365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.769276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:50:19.141818Z","caller":"traceutil/trace.go:171","msg":"trace[746359811] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1862; }","duration":"148.360961ms","start":"2026-05-04T18:50:18.993435Z","end":"2026-05-04T18:50:19.141796Z","steps":["trace[746359811] 'agreement among raft nodes before linearized reading' (duration: 147.747236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:19.17662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.923091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18ac716174e811fd\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-05-04T18:50:19.176689Z","caller":"traceutil/trace.go:171","msg":"trace[1883506867] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18ac716174e811fd; range_end:; response_count:1; response_revision:1864; }","duration":"146.044724ms","start":"2026-05-04T18:50:19.030625Z","end":"2026-05-04T18:50:19.176669Z","steps":["trace[1883506867] 'agreement among raft nodes before linearized reading' (duration: 145.549712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:19.735526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.029202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-95f4365c-f234-4055-b602-33d5b2c0378b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-04T18:50:19.735623Z","caller":"traceutil/trace.go:171","msg":"trace[58502224] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-95f4365c-f234-4055-b602-33d5b2c0378b; range_end:; response_count:1; response_revision:1866; }","duration":"187.190056ms","start":"2026-05-04T18:50:19.548413Z","end":"2026-05-04T18:50:19.735603Z","steps":["trace[58502224] 'range keys from in-memory index tree' (duration: 186.874058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:19.735547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.378151ms","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-05-04T18:50:19.736583Z","caller":"traceutil/trace.go:171","msg":"trace[819566206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1866; }","duration":"331.482788ms","start":"2026-05-04T18:50:19.405081Z","end":"2026-05-04T18:50:19.736563Z","steps":["trace[819566206] 'range keys from in-memory index tree' (duration: 330.136856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:19.737514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:19.405068Z","time spent":"332.430201ms","remote":"127.0.0.1:34710","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-05-04T18:50:52.487862Z","caller":"traceutil/trace.go:171","msg":"trace[1403030806] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"128.614972ms","start":"2026-05-04T18:50:52.359208Z","end":"2026-05-04T18:50:52.487823Z","steps":["trace[1403030806] 'process raft request' (duration: 128.327904ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:50:53.087277Z","caller":"traceutil/trace.go:171","msg":"trace[3099969] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2177; }","duration":"109.175854ms","start":"2026-05-04T18:50:52.978076Z","end":"2026-05-04T18:50:53.087252Z","steps":["trace[3099969] 'read index received' (duration: 108.913877ms)","trace[3099969] 'applied index is now lower than readState.Index' (duration: 260.987µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:50:53.087537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.4416ms","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-05-04T18:50:53.087613Z","caller":"traceutil/trace.go:171","msg":"trace[2035138616] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2102; }","duration":"109.559064ms","start":"2026-05-04T18:50:52.978037Z","end":"2026-05-04T18:50:53.087596Z","steps":["trace[2035138616] 'agreement among raft nodes before linearized reading' (duration: 109.304327ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:50:53.087761Z","caller":"traceutil/trace.go:171","msg":"trace[648718621] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"131.295907ms","start":"2026-05-04T18:50:52.956168Z","end":"2026-05-04T18:50:53.087464Z","steps":["trace[648718621] 'process raft request' (duration: 130.885197ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:50:53.720986Z","caller":"traceutil/trace.go:171","msg":"trace[996333670] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"360.179338ms","start":"2026-05-04T18:50:53.360747Z","end":"2026-05-04T18:50:53.720926Z","steps":["trace[996333670] 'process raft request' (duration: 359.8933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:53.721169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.001171ms","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-05-04T18:50:53.721206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:53.360736Z","time spent":"360.357712ms","remote":"127.0.0.1:39040","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-05-04T18:50:53.721248Z","caller":"traceutil/trace.go:171","msg":"trace[615688482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2103; }","duration":"344.143454ms","start":"2026-05-04T18:50:53.377094Z","end":"2026-05-04T18:50:53.721237Z","steps":["trace[615688482] 'agreement among raft nodes before linearized reading' (duration: 343.906738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:53.721295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:53.377079Z","time spent":"344.201996ms","remote":"127.0.0.1:39040","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":"info","ts":"2026-05-04T18:50:53.720949Z","caller":"traceutil/trace.go:171","msg":"trace[1925321276] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2178; }","duration":"343.743113ms","start":"2026-05-04T18:50:53.377135Z","end":"2026-05-04T18:50:53.720879Z","steps":["trace[1925321276] 'read index received' (duration: 343.523228ms)","trace[1925321276] 'applied index is now lower than readState.Index' (duration: 218.385µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:50:54.247394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.954128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:50:54.248024Z","caller":"traceutil/trace.go:171","msg":"trace[152025950] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"564.677954ms","start":"2026-05-04T18:50:53.683303Z","end":"2026-05-04T18:50:54.247981Z","steps":["trace[152025950] 'process raft request' (duration: 243.934985ms)","trace[152025950] 'compare' (duration: 319.798484ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:50:54.248091Z","caller":"traceutil/trace.go:171","msg":"trace[2131674648] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"522.232304ms","start":"2026-05-04T18:50:53.725842Z","end":"2026-05-04T18:50:54.248074Z","steps":["trace[2131674648] 'process raft request' (duration: 521.645848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.248169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:53.683264Z","time spent":"564.827868ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:50:54.248323Z","caller":"traceutil/trace.go:171","msg":"trace[116908940] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2179; }","duration":"376.929913ms","start":"2026-05-04T18:50:53.871321Z","end":"2026-05-04T18:50:54.248251Z","steps":["trace[116908940] 'read index received' (duration: 55.931858ms)","trace[116908940] 'applied index is now lower than readState.Index' (duration: 320.996965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:50:54.249577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.246958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:50:54.249629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.964985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:50:54.249683Z","caller":"traceutil/trace.go:171","msg":"trace[1569615434] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"333.014173ms","start":"2026-05-04T18:50:53.916658Z","end":"2026-05-04T18:50:54.249672Z","steps":["trace[1569615434] 'process raft request' (duration: 331.37427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.249752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:53.916635Z","time spent":"333.079225ms","remote":"127.0.0.1:39040","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-05-04T18:50:54.249684Z","caller":"traceutil/trace.go:171","msg":"trace[882720289] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2106; }","duration":"335.082058ms","start":"2026-05-04T18:50:53.914587Z","end":"2026-05-04T18:50:54.249669Z","steps":["trace[882720289] 'agreement among raft nodes before linearized reading' (duration: 334.978645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.249858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:53.914561Z","time spent":"335.287264ms","remote":"127.0.0.1:49716","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/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-04T18:50:54.249906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.756589ms","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-05-04T18:50:54.249863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.020395ms","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-05-04T18:50:54.249945Z","caller":"traceutil/trace.go:171","msg":"trace[639089173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2106; }","duration":"295.837051ms","start":"2026-05-04T18:50:53.954098Z","end":"2026-05-04T18:50:54.249935Z","steps":["trace[639089173] 'agreement among raft nodes before linearized reading' (duration: 295.623335ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:50:54.249638Z","caller":"traceutil/trace.go:171","msg":"trace[2074157027] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2106; }","duration":"378.355932ms","start":"2026-05-04T18:50:53.871268Z","end":"2026-05-04T18:50:54.249624Z","steps":["trace[2074157027] 'agreement among raft nodes before linearized reading' (duration: 378.248819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.250022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:53.871246Z","time spent":"378.765912ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-04T18:50:54.2501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.486747ms","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-05-04T18:50:54.250129Z","caller":"traceutil/trace.go:171","msg":"trace[1705836351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2106; }","duration":"260.542348ms","start":"2026-05-04T18:50:53.989579Z","end":"2026-05-04T18:50:54.250121Z","steps":["trace[1705836351] 'agreement among raft nodes before linearized reading' (duration: 260.127047ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:50:54.249961Z","caller":"traceutil/trace.go:171","msg":"trace[194723922] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"155.161958ms","start":"2026-05-04T18:50:54.094782Z","end":"2026-05-04T18:50:54.249944Z","steps":["trace[194723922] 'agreement among raft nodes before linearized reading' (duration: 154.903551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.249898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:53.725816Z","time spent":"522.309655ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:50:54.467747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.471203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:50:54.468084Z","caller":"traceutil/trace.go:171","msg":"trace[1503450922] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"213.514883ms","start":"2026-05-04T18:50:54.254368Z","end":"2026-05-04T18:50:54.467883Z","steps":["trace[1503450922] 'process raft request' (duration: 93.68189ms)","trace[1503450922] 'compare' (duration: 119.253418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:50:54.807443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.609723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:50:54.807644Z","caller":"traceutil/trace.go:171","msg":"trace[1368099622] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"552.976862ms","start":"2026-05-04T18:50:54.254629Z","end":"2026-05-04T18:50:54.807606Z","steps":["trace[1368099622] 'process raft request' (duration: 378.06272ms)","trace[1368099622] 'compare' (duration: 174.144741ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:50:54.807819Z","caller":"traceutil/trace.go:171","msg":"trace[1174072013] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"241.213088ms","start":"2026-05-04T18:50:54.566583Z","end":"2026-05-04T18:50:54.807797Z","steps":["trace[1174072013] 'process raft request' (duration: 241.169156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.807927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:54.254591Z","time spent":"553.229668ms","remote":"127.0.0.1:39040","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-05-04T18:50:54.80796Z","caller":"traceutil/trace.go:171","msg":"trace[2099382896] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"290.287396ms","start":"2026-05-04T18:50:54.517636Z","end":"2026-05-04T18:50:54.807924Z","steps":["trace[2099382896] 'process raft request' (duration: 290.009819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.808194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.887428ms","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-05-04T18:50:54.808274Z","caller":"traceutil/trace.go:171","msg":"trace[2018309350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2110; }","duration":"315.015531ms","start":"2026-05-04T18:50:54.493241Z","end":"2026-05-04T18:50:54.808257Z","steps":["trace[2018309350] 'agreement among raft nodes before linearized reading' (duration: 314.731503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.808323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:54.493221Z","time spent":"315.093953ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-04T18:50:54.807805Z","caller":"traceutil/trace.go:171","msg":"trace[1943963660] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"314.488427ms","start":"2026-05-04T18:50:54.493286Z","end":"2026-05-04T18:50:54.807775Z","steps":["trace[1943963660] 'read index received' (duration: 139.613086ms)","trace[1943963660] 'applied index is now lower than readState.Index' (duration: 174.872041ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:50:54.848936Z","caller":"traceutil/trace.go:171","msg":"trace[1901543814] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"112.089488ms","start":"2026-05-04T18:50:54.73682Z","end":"2026-05-04T18:50:54.84891Z","steps":["trace[1901543814] 'process raft request' (duration: 111.830061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:54.849078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.924488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:50:54.849193Z","caller":"traceutil/trace.go:171","msg":"trace[67845711] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2111; }","duration":"247.065322ms","start":"2026-05-04T18:50:54.602097Z","end":"2026-05-04T18:50:54.849162Z","steps":["trace[67845711] 'agreement among raft nodes before linearized reading' (duration: 246.757334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:56.016038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.138743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:50:56.016223Z","caller":"traceutil/trace.go:171","msg":"trace[1945593718] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"885.089774ms","start":"2026-05-04T18:50:55.131114Z","end":"2026-05-04T18:50:56.016204Z","steps":["trace[1945593718] 'read index received' (duration: 203.601812ms)","trace[1945593718] 'applied index is now lower than readState.Index' (duration: 681.486372ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:50:56.016278Z","caller":"traceutil/trace.go:171","msg":"trace[2040680661] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"1.203486581s","start":"2026-05-04T18:50:54.812763Z","end":"2026-05-04T18:50:56.016249Z","steps":["trace[2040680661] 'process raft request' (duration: 522.061131ms)","trace[2040680661] 'compare' (duration: 681.005469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:50:56.01641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.291829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:50:56.01649Z","caller":"traceutil/trace.go:171","msg":"trace[1366864509] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2112; }","duration":"885.412792ms","start":"2026-05-04T18:50:55.131062Z","end":"2026-05-04T18:50:56.016474Z","steps":["trace[1366864509] 'agreement among raft nodes before linearized reading' (duration: 885.260898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:56.016567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.436773ms","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-05-04T18:50:56.016606Z","caller":"traceutil/trace.go:171","msg":"trace[1264025740] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2112; }","duration":"758.501935ms","start":"2026-05-04T18:50:55.258093Z","end":"2026-05-04T18:50:56.016595Z","steps":["trace[1264025740] 'agreement among raft nodes before linearized reading' (duration: 758.371842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:56.016637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:55.258073Z","time spent":"758.555877ms","remote":"127.0.0.1:34710","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-05-04T18:50:56.016569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:55.131039Z","time spent":"885.505895ms","remote":"127.0.0.1:39208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-04T18:50:56.016861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.345391ms","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-05-04T18:50:56.016894Z","caller":"traceutil/trace.go:171","msg":"trace[1121952514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2112; }","duration":"291.406992ms","start":"2026-05-04T18:50:55.72548Z","end":"2026-05-04T18:50:56.016886Z","steps":["trace[1121952514] 'agreement among raft nodes before linearized reading' (duration: 291.32238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:56.016417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:54.812727Z","time spent":"1.203619074s","remote":"127.0.0.1:39040","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-05-04T18:50:56.017022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.976962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:50:56.017052Z","caller":"traceutil/trace.go:171","msg":"trace[1526772490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2112; }","duration":"726.037044ms","start":"2026-05-04T18:50:55.291008Z","end":"2026-05-04T18:50:56.017045Z","steps":["trace[1526772490] 'agreement among raft nodes before linearized reading' (duration: 725.992983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:50:56.017076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:50:55.290965Z","time spent":"726.106276ms","remote":"127.0.0.1:38750","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-05-04T18:51:49.240935Z","caller":"traceutil/trace.go:171","msg":"trace[752727503] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"238.695277ms","start":"2026-05-04T18:51:49.002208Z","end":"2026-05-04T18:51:49.240903Z","steps":["trace[752727503] 'process raft request' (duration: 238.43939ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:51:49.240964Z","caller":"traceutil/trace.go:171","msg":"trace[317146180] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"202.318647ms","start":"2026-05-04T18:51:49.03862Z","end":"2026-05-04T18:51:49.240939Z","steps":["trace[317146180] 'process raft request' (duration: 202.133532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:51:49.241097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.272518ms","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-05-04T18:51:49.241149Z","caller":"traceutil/trace.go:171","msg":"trace[1338738298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2452; }","duration":"200.375461ms","start":"2026-05-04T18:51:49.040766Z","end":"2026-05-04T18:51:49.241142Z","steps":["trace[1338738298] 'agreement among raft nodes before linearized reading' (duration: 200.247827ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:51:49.240988Z","caller":"traceutil/trace.go:171","msg":"trace[1677462622] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"240.024335ms","start":"2026-05-04T18:51:49.000942Z","end":"2026-05-04T18:51:49.240966Z","steps":["trace[1677462622] 'process raft request' (duration: 166.219445ms)","trace[1677462622] 'compare' (duration: 73.11552ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:51:49.240826Z","caller":"traceutil/trace.go:171","msg":"trace[272219915] linearizableReadLoop","detail":"{readStateIndex:2541; appliedIndex:2538; }","duration":"199.9805ms","start":"2026-05-04T18:51:49.040819Z","end":"2026-05-04T18:51:49.2408Z","steps":["trace[272219915] 'read index received' (duration: 126.355946ms)","trace[272219915] 'applied index is now lower than readState.Index' (duration: 73.623584ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:52:30.61279Z","caller":"traceutil/trace.go:171","msg":"trace[685656894] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"112.993057ms","start":"2026-05-04T18:52:30.499764Z","end":"2026-05-04T18:52:30.612757Z","steps":["trace[685656894] 'process raft request' (duration: 112.482892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:31.464954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.731002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ac717c1f88dc67\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-04T18:52:31.46506Z","caller":"traceutil/trace.go:171","msg":"trace[2037724937] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ac717c1f88dc67; range_end:; response_count:1; response_revision:2729; }","duration":"639.870976ms","start":"2026-05-04T18:52:30.825158Z","end":"2026-05-04T18:52:31.465029Z","steps":["trace[2037724937] 'range keys from in-memory index tree' (duration: 639.572167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:31.465099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:30.825139Z","time spent":"639.950658ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ac717c1f88dc67\" "} {"level":"warn","ts":"2026-05-04T18:52:31.465241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.819654ms","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-05-04T18:52:31.465342Z","caller":"traceutil/trace.go:171","msg":"trace[1002192236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2729; }","duration":"639.924828ms","start":"2026-05-04T18:52:30.825391Z","end":"2026-05-04T18:52:31.465316Z","steps":["trace[1002192236] 'range keys from in-memory index tree' (duration: 639.68136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:31.465455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:30.825384Z","time spent":"640.053201ms","remote":"127.0.0.1:39040","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-05-04T18:52:31.466114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.430111ms","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-05-04T18:52:31.466179Z","caller":"traceutil/trace.go:171","msg":"trace[2029196782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2729; }","duration":"439.525564ms","start":"2026-05-04T18:52:31.026636Z","end":"2026-05-04T18:52:31.466161Z","steps":["trace[2029196782] 'range keys from in-memory index tree' (duration: 439.227104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:31.46622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.026615Z","time spent":"439.594715ms","remote":"127.0.0.1:39040","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-05-04T18:52:31.466364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.269183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:52:31.466513Z","caller":"traceutil/trace.go:171","msg":"trace[1160657573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2729; }","duration":"174.136959ms","start":"2026-05-04T18:52:31.29232Z","end":"2026-05-04T18:52:31.466457Z","steps":["trace[1160657573] 'range keys from in-memory index tree' (duration: 173.193741ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:52:31.572532Z","caller":"traceutil/trace.go:171","msg":"trace[987679278] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"188.180371ms","start":"2026-05-04T18:52:31.384322Z","end":"2026-05-04T18:52:31.572502Z","steps":["trace[987679278] 'process raft request' (duration: 187.78767ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:52:32.242248Z","caller":"traceutil/trace.go:171","msg":"trace[79085504] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"850.969458ms","start":"2026-05-04T18:52:31.391245Z","end":"2026-05-04T18:52:32.242214Z","steps":["trace[79085504] 'process raft request' (duration: 850.69608ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:52:32.242302Z","caller":"traceutil/trace.go:171","msg":"trace[1774035964] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"850.539645ms","start":"2026-05-04T18:52:31.391736Z","end":"2026-05-04T18:52:32.242276Z","steps":["trace[1774035964] 'process raft request' (duration: 850.338319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:32.242422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.391236Z","time spent":"851.106022ms","remote":"127.0.0.1:39040","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-05-04T18:52:32.242425Z","caller":"traceutil/trace.go:171","msg":"trace[115367542] linearizableReadLoop","detail":"{readStateIndex:2830; appliedIndex:2827; }","duration":"819.335097ms","start":"2026-05-04T18:52:31.423055Z","end":"2026-05-04T18:52:32.24239Z","steps":["trace[115367542] 'read index received' (duration: 149.296352ms)","trace[115367542] 'applied index is now lower than readState.Index' (duration: 670.037605ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:52:32.242557Z","caller":"traceutil/trace.go:171","msg":"trace[1769332196] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"771.964851ms","start":"2026-05-04T18:52:31.470573Z","end":"2026-05-04T18:52:32.242537Z","steps":["trace[1769332196] 'process raft request' (duration: 771.696273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:32.242565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.391726Z","time spent":"850.633959ms","remote":"127.0.0.1:39040","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-05-04T18:52:32.242616Z","caller":"traceutil/trace.go:171","msg":"trace[1060085281] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"771.991472ms","start":"2026-05-04T18:52:31.470609Z","end":"2026-05-04T18:52:32.2426Z","steps":["trace[1060085281] 'process raft request' (duration: 771.712344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:32.242648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.470555Z","time spent":"772.029104ms","remote":"127.0.0.1:39040","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-05-04T18:52:32.242414Z","caller":"traceutil/trace.go:171","msg":"trace[362195813] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"771.851958ms","start":"2026-05-04T18:52:31.470511Z","end":"2026-05-04T18:52:32.242363Z","steps":["trace[362195813] 'process raft request' (duration: 771.632321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:32.242854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.76535ms","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-05-04T18:52:32.242919Z","caller":"traceutil/trace.go:171","msg":"trace[2064017931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2735; }","duration":"819.875063ms","start":"2026-05-04T18:52:31.423027Z","end":"2026-05-04T18:52:32.242902Z","steps":["trace[2064017931] 'agreement among raft nodes before linearized reading' (duration: 819.576024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:32.242944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.4706Z","time spent":"772.041723ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:52:32.242961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.423008Z","time spent":"819.944665ms","remote":"127.0.0.1:39040","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-05-04T18:52:32.242986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.470489Z","time spent":"772.341733ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:52:32.247398Z","caller":"traceutil/trace.go:171","msg":"trace[748763924] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"597.454041ms","start":"2026-05-04T18:52:31.649909Z","end":"2026-05-04T18:52:32.247364Z","steps":["trace[748763924] 'process raft request' (duration: 597.350398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:32.24742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.437906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:52:32.247447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.556925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-04T18:52:32.247474Z","caller":"traceutil/trace.go:171","msg":"trace[2102942712] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2736; }","duration":"717.584405ms","start":"2026-05-04T18:52:31.529881Z","end":"2026-05-04T18:52:32.247466Z","steps":["trace[2102942712] 'agreement among raft nodes before linearized reading' (duration: 717.488512ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:52:32.247473Z","caller":"traceutil/trace.go:171","msg":"trace[180070093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2736; }","duration":"779.532819ms","start":"2026-05-04T18:52:31.467927Z","end":"2026-05-04T18:52:32.247459Z","steps":["trace[180070093] 'agreement among raft nodes before linearized reading' (duration: 779.416506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:52:32.247493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.529836Z","time spent":"717.652297ms","remote":"127.0.0.1:38958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-04T18:52:32.247495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.46791Z","time spent":"779.58023ms","remote":"127.0.0.1:38766","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-05-04T18:52:32.247492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:52:31.649884Z","time spent":"597.563684ms","remote":"127.0.0.1:39040","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-05-04T18:55:09.041926Z","caller":"traceutil/trace.go:171","msg":"trace[257076118] transaction","detail":"{read_only:false; response_revision:3656; number_of_response:1; }","duration":"110.269075ms","start":"2026-05-04T18:55:08.931603Z","end":"2026-05-04T18:55:09.041872Z","steps":["trace[257076118] 'process raft request' (duration: 109.963795ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:06.506187Z","caller":"traceutil/trace.go:171","msg":"trace[1035898442] linearizableReadLoop","detail":"{readStateIndex:4541; appliedIndex:4540; }","duration":"213.591236ms","start":"2026-05-04T18:56:06.292567Z","end":"2026-05-04T18:56:06.506158Z","steps":["trace[1035898442] 'read index received' (duration: 213.433861ms)","trace[1035898442] 'applied index is now lower than readState.Index' (duration: 155.825µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:06.506322Z","caller":"traceutil/trace.go:171","msg":"trace[13880374] transaction","detail":"{read_only:false; response_revision:4376; number_of_response:1; }","duration":"257.986857ms","start":"2026-05-04T18:56:06.248295Z","end":"2026-05-04T18:56:06.506282Z","steps":["trace[13880374] 'process raft request' (duration: 257.698328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:06.506453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.862546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:06.506497Z","caller":"traceutil/trace.go:171","msg":"trace[1753956734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4376; }","duration":"213.958489ms","start":"2026-05-04T18:56:06.292528Z","end":"2026-05-04T18:56:06.506487Z","steps":["trace[1753956734] 'agreement among raft nodes before linearized reading' (duration: 213.846835ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:06.569309Z","caller":"traceutil/trace.go:171","msg":"trace[1245281050] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"169.988693ms","start":"2026-05-04T18:56:06.399291Z","end":"2026-05-04T18:56:06.569279Z","steps":["trace[1245281050] 'process raft request' (duration: 169.829748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:06.569852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.167034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-77npx\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-05-04T18:56:06.569866Z","caller":"traceutil/trace.go:171","msg":"trace[1803649506] transaction","detail":"{read_only:false; response_revision:4377; number_of_response:1; }","duration":"229.742594ms","start":"2026-05-04T18:56:06.340093Z","end":"2026-05-04T18:56:06.569836Z","steps":["trace[1803649506] 'process raft request' (duration: 228.009518ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:06.569909Z","caller":"traceutil/trace.go:171","msg":"trace[1252392741] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-77npx; range_end:; response_count:1; response_revision:4378; }","duration":"277.276947ms","start":"2026-05-04T18:56:06.292618Z","end":"2026-05-04T18:56:06.569895Z","steps":["trace[1252392741] 'agreement among raft nodes before linearized reading' (duration: 277.094211ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:08.494509Z","caller":"traceutil/trace.go:171","msg":"trace[1806882011] transaction","detail":"{read_only:false; response_revision:4440; number_of_response:1; }","duration":"103.171051ms","start":"2026-05-04T18:56:08.391294Z","end":"2026-05-04T18:56:08.494465Z","steps":["trace[1806882011] 'process raft request' (duration: 102.902762ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:09.408826Z","caller":"traceutil/trace.go:171","msg":"trace[478635032] linearizableReadLoop","detail":"{readStateIndex:4637; appliedIndex:4635; }","duration":"134.024548ms","start":"2026-05-04T18:56:09.274772Z","end":"2026-05-04T18:56:09.408797Z","steps":["trace[478635032] 'read index received' (duration: 16.599232ms)","trace[478635032] 'applied index is now lower than readState.Index' (duration: 117.423866ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:09.408907Z","caller":"traceutil/trace.go:171","msg":"trace[453058665] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"134.466453ms","start":"2026-05-04T18:56:09.274409Z","end":"2026-05-04T18:56:09.408876Z","steps":["trace[453058665] 'process raft request' (duration: 132.978684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:09.410609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.793456ms","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-05-04T18:56:09.41074Z","caller":"traceutil/trace.go:171","msg":"trace[175609926] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4471; }","duration":"135.991043ms","start":"2026-05-04T18:56:09.274709Z","end":"2026-05-04T18:56:09.4107Z","steps":["trace[175609926] 'agreement among raft nodes before linearized reading' (duration: 134.218795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:09.43742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.786738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:09.43756Z","caller":"traceutil/trace.go:171","msg":"trace[599569961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4472; }","duration":"143.976244ms","start":"2026-05-04T18:56:09.293568Z","end":"2026-05-04T18:56:09.437544Z","steps":["trace[599569961] 'agreement among raft nodes before linearized reading' (duration: 143.770617ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:11.579701Z","caller":"traceutil/trace.go:171","msg":"trace[1904847855] linearizableReadLoop","detail":"{readStateIndex:4668; appliedIndex:4667; }","duration":"156.174115ms","start":"2026-05-04T18:56:11.423502Z","end":"2026-05-04T18:56:11.579676Z","steps":["trace[1904847855] 'read index received' (duration: 154.129958ms)","trace[1904847855] 'applied index is now lower than readState.Index' (duration: 2.043177ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:11.579834Z","caller":"traceutil/trace.go:171","msg":"trace[2077649356] transaction","detail":"{read_only:false; response_revision:4502; number_of_response:1; }","duration":"164.29011ms","start":"2026-05-04T18:56:11.415516Z","end":"2026-05-04T18:56:11.579806Z","steps":["trace[2077649356] 'process raft request' (duration: 162.116829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:11.579964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.443123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1370"} {"level":"info","ts":"2026-05-04T18:56:11.580004Z","caller":"traceutil/trace.go:171","msg":"trace[900027866] range","detail":"{range_begin:/registry/clusterroles/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4502; }","duration":"156.530647ms","start":"2026-05-04T18:56:11.423463Z","end":"2026-05-04T18:56:11.579993Z","steps":["trace[900027866] 'agreement among raft nodes before linearized reading' (duration: 156.359731ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:11.581712Z","caller":"traceutil/trace.go:171","msg":"trace[1780404832] transaction","detail":"{read_only:false; response_revision:4503; number_of_response:1; }","duration":"139.637785ms","start":"2026-05-04T18:56:11.442056Z","end":"2026-05-04T18:56:11.581694Z","steps":["trace[1780404832] 'process raft request' (duration: 139.260262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:11.581695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.330915ms","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-05-04T18:56:11.58181Z","caller":"traceutil/trace.go:171","msg":"trace[678400700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4503; }","duration":"117.501341ms","start":"2026-05-04T18:56:11.464287Z","end":"2026-05-04T18:56:11.581788Z","steps":["trace[678400700] 'agreement among raft nodes before linearized reading' (duration: 117.196721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:16.53964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.631086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:56:16.53967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.440454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-cn7nx\" ","response":"range_response_count:1 size:9830"} {"level":"warn","ts":"2026-05-04T18:56:16.539761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.526931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cn7nx.18ac71b5086759ab\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-05-04T18:56:16.539737Z","caller":"traceutil/trace.go:171","msg":"trace[1907273173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4609; }","duration":"246.782181ms","start":"2026-05-04T18:56:16.292932Z","end":"2026-05-04T18:56:16.539715Z","steps":["trace[1907273173] 'range keys from in-memory index tree' (duration: 246.541823ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:16.53985Z","caller":"traceutil/trace.go:171","msg":"trace[554178325] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cn7nx.18ac71b5086759ab; range_end:; response_count:1; response_revision:4609; }","duration":"275.682616ms","start":"2026-05-04T18:56:16.264145Z","end":"2026-05-04T18:56:16.539828Z","steps":["trace[554178325] 'range keys from in-memory index tree' (duration: 275.290493ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:16.539778Z","caller":"traceutil/trace.go:171","msg":"trace[1841093119] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-cn7nx; range_end:; response_count:1; response_revision:4609; }","duration":"277.62465ms","start":"2026-05-04T18:56:16.262118Z","end":"2026-05-04T18:56:16.539743Z","steps":["trace[1841093119] 'range keys from in-memory index tree' (duration: 277.161944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:22.144772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6716448091961475891,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T18:56:22.645445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6716448091961475891,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T18:56:22.74185Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.180007825s","expected-duration":"1s"} {"level":"info","ts":"2026-05-04T18:56:22.742293Z","caller":"traceutil/trace.go:171","msg":"trace[388398671] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"1.180507211s","start":"2026-05-04T18:56:21.561765Z","end":"2026-05-04T18:56:22.742272Z","steps":["trace[388398671] 'process raft request' (duration: 1.180261132s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:22.742458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.561748Z","time spent":"1.180612714s","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T18:56:23.380168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.78325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:56:23.380476Z","caller":"traceutil/trace.go:171","msg":"trace[103077130] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"1.758765761s","start":"2026-05-04T18:56:21.62169Z","end":"2026-05-04T18:56:23.380456Z","steps":["trace[103077130] 'process raft request' (duration: 1.284612099s)","trace[103077130] 'compare' (duration: 473.551362ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:23.380603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.621664Z","time spent":"1.758858974s","remote":"127.0.0.1:39040","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-05-04T18:56:23.380715Z","caller":"traceutil/trace.go:171","msg":"trace[1989448209] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"1.740861025s","start":"2026-05-04T18:56:21.639833Z","end":"2026-05-04T18:56:23.380694Z","steps":["trace[1989448209] 'process raft request' (duration: 1.740433371s)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.380808Z","caller":"traceutil/trace.go:171","msg":"trace[1852393134] linearizableReadLoop","detail":"{readStateIndex:4875; appliedIndex:4871; }","duration":"1.736662338s","start":"2026-05-04T18:56:21.644121Z","end":"2026-05-04T18:56:23.380784Z","steps":["trace[1852393134] 'read index received' (duration: 1.097939429s)","trace[1852393134] 'applied index is now lower than readState.Index' (duration: 638.721879ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:23.380852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.639804Z","time spent":"1.740960338s","remote":"127.0.0.1:39040","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-05-04T18:56:23.380914Z","caller":"traceutil/trace.go:171","msg":"trace[2011600434] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"1.740485573s","start":"2026-05-04T18:56:21.640414Z","end":"2026-05-04T18:56:23.3809Z","steps":["trace[2011600434] 'process raft request' (duration: 1.740218794s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.381004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.640389Z","time spent":"1.740566225s","remote":"127.0.0.1:39040","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-05-04T18:56:23.381018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736879795s","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-05-04T18:56:23.381054Z","caller":"traceutil/trace.go:171","msg":"trace[1009601072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4702; }","duration":"1.736951307s","start":"2026-05-04T18:56:21.644091Z","end":"2026-05-04T18:56:23.381042Z","steps":["trace[1009601072] 'agreement among raft nodes before linearized reading' (duration: 1.736806103s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.381133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.644075Z","time spent":"1.737012959s","remote":"127.0.0.1:39040","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-05-04T18:56:23.461255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.600516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-05-04T18:56:23.461288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.806213865s","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-05-04T18:56:23.461333Z","caller":"traceutil/trace.go:171","msg":"trace[605947022] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4704; }","duration":"218.727851ms","start":"2026-05-04T18:56:23.242579Z","end":"2026-05-04T18:56:23.461307Z","steps":["trace[605947022] 'agreement among raft nodes before linearized reading' (duration: 218.501824ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.461356Z","caller":"traceutil/trace.go:171","msg":"trace[407540300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4704; }","duration":"1.806323728s","start":"2026-05-04T18:56:21.655016Z","end":"2026-05-04T18:56:23.46134Z","steps":["trace[407540300] 'agreement among raft nodes before linearized reading' (duration: 1.805985987s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.461359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127763258s","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-05-04T18:56:23.462513Z","caller":"traceutil/trace.go:171","msg":"trace[1037758319] 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:4704; }","duration":"1.128972218s","start":"2026-05-04T18:56:22.333513Z","end":"2026-05-04T18:56:23.462485Z","steps":["trace[1037758319] 'agreement among raft nodes before linearized reading' (duration: 1.1275131s)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.461426Z","caller":"traceutil/trace.go:171","msg":"trace[2002327753] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"619.507231ms","start":"2026-05-04T18:56:22.84188Z","end":"2026-05-04T18:56:23.461388Z","steps":["trace[2002327753] 'process raft request' (duration: 619.055476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.461498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.655002Z","time spent":"1.806387121s","remote":"127.0.0.1:39040","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-05-04T18:56:23.462678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:22.841852Z","time spent":"620.754492ms","remote":"127.0.0.1:39040","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-05-04T18:56:23.461477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.829707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-05-04T18:56:23.461599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155132663s","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-05-04T18:56:23.4628Z","caller":"traceutil/trace.go:171","msg":"trace[2069132517] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4704; }","duration":"528.192391ms","start":"2026-05-04T18:56:22.934586Z","end":"2026-05-04T18:56:23.462778Z","steps":["trace[2069132517] 'agreement among raft nodes before linearized reading' (duration: 526.747934ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.46282Z","caller":"traceutil/trace.go:171","msg":"trace[1200306571] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4704; }","duration":"1.156376124s","start":"2026-05-04T18:56:22.306431Z","end":"2026-05-04T18:56:23.462807Z","steps":["trace[1200306571] 'agreement among raft nodes before linearized reading' (duration: 1.155094052s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.462847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:22.934566Z","time spent":"528.266574ms","remote":"127.0.0.1:56256","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":"warn","ts":"2026-05-04T18:56:23.462856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:22.306401Z","time spent":"1.156443526s","remote":"127.0.0.1:38946","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":"warn","ts":"2026-05-04T18:56:23.461587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.804016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-04T18:56:23.462585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:22.33349Z","time spent":"1.129077751s","remote":"127.0.0.1:38924","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":"info","ts":"2026-05-04T18:56:23.461595Z","caller":"traceutil/trace.go:171","msg":"trace[545551294] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"715.296227ms","start":"2026-05-04T18:56:22.746274Z","end":"2026-05-04T18:56:23.461571Z","steps":["trace[545551294] 'process raft request' (duration: 714.547352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.46309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:22.746248Z","time spent":"716.806825ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T18:56:23.463076Z","caller":"traceutil/trace.go:171","msg":"trace[1465629788] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4704; }","duration":"294.175641ms","start":"2026-05-04T18:56:23.168732Z","end":"2026-05-04T18:56:23.462908Z","steps":["trace[1465629788] 'agreement among raft nodes before linearized reading' (duration: 292.824456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.461637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168923346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:23.463194Z","caller":"traceutil/trace.go:171","msg":"trace[1428269122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4704; }","duration":"1.170506947s","start":"2026-05-04T18:56:22.292673Z","end":"2026-05-04T18:56:23.46318Z","steps":["trace[1428269122] 'agreement among raft nodes before linearized reading' (duration: 1.168944156s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.463239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:22.292652Z","time spent":"1.170573249s","remote":"127.0.0.1:38750","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-05-04T18:56:23.461641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.916847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-04T18:56:23.463303Z","caller":"traceutil/trace.go:171","msg":"trace[1894905546] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4704; }","duration":"125.569831ms","start":"2026-05-04T18:56:23.337715Z","end":"2026-05-04T18:56:23.463285Z","steps":["trace[1894905546] 'agreement among raft nodes before linearized reading' (duration: 123.864315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.461639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.233154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-04T18:56:23.463471Z","caller":"traceutil/trace.go:171","msg":"trace[1582332886] range","detail":"{range_begin:/registry/masterleases/199.19.213.235; range_end:; response_count:1; response_revision:4704; }","duration":"845.125256ms","start":"2026-05-04T18:56:22.618336Z","end":"2026-05-04T18:56:23.463461Z","steps":["trace[1582332886] 'agreement among raft nodes before linearized reading' (duration: 843.151401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.463507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:22.618315Z","time spent":"845.179358ms","remote":"127.0.0.1:38798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.235\" "} {"level":"warn","ts":"2026-05-04T18:56:23.461701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522467048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a19fe09-3360-43df-85ec-1a00f92459de\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-04T18:56:23.464053Z","caller":"traceutil/trace.go:171","msg":"trace[1343023332] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a19fe09-3360-43df-85ec-1a00f92459de; range_end:; response_count:1; response_revision:4704; }","duration":"1.524836035s","start":"2026-05-04T18:56:21.939198Z","end":"2026-05-04T18:56:23.464034Z","steps":["trace[1343023332] 'agreement among raft nodes before linearized reading' (duration: 1.522464528s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.464331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.939184Z","time spent":"1.524948929s","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a19fe09-3360-43df-85ec-1a00f92459de\" "} {"level":"warn","ts":"2026-05-04T18:56:23.461725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645009079s","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-05-04T18:56:23.464794Z","caller":"traceutil/trace.go:171","msg":"trace[42326651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4704; }","duration":"1.648055399s","start":"2026-05-04T18:56:21.816687Z","end":"2026-05-04T18:56:23.464743Z","steps":["trace[42326651] 'agreement among raft nodes before linearized reading' (duration: 1.644964247s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.464945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.816677Z","time spent":"1.648257785s","remote":"127.0.0.1:39040","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-05-04T18:56:23.461726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738458207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-04T18:56:23.465175Z","caller":"traceutil/trace.go:171","msg":"trace[1107495511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4704; }","duration":"1.74191652s","start":"2026-05-04T18:56:21.723233Z","end":"2026-05-04T18:56:23.465149Z","steps":["trace[1107495511] 'agreement among raft nodes before linearized reading' (duration: 1.738441466s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.465219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.72322Z","time spent":"1.741987813s","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T18:56:23.461762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552729758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-04T18:56:23.465308Z","caller":"traceutil/trace.go:171","msg":"trace[618237339] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4704; }","duration":"1.556300725s","start":"2026-05-04T18:56:21.908997Z","end":"2026-05-04T18:56:23.465298Z","steps":["trace[618237339] 'agreement among raft nodes before linearized reading' (duration: 1.552724458s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.465345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.908984Z","time spent":"1.556349807s","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-04T18:56:23.461763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64107703s","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-05-04T18:56:23.46551Z","caller":"traceutil/trace.go:171","msg":"trace[1573383666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4704; }","duration":"1.644847373s","start":"2026-05-04T18:56:21.820654Z","end":"2026-05-04T18:56:23.465501Z","steps":["trace[1573383666] 'agreement among raft nodes before linearized reading' (duration: 1.640996887s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.465538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:21.820642Z","time spent":"1.644886824s","remote":"127.0.0.1:39040","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-05-04T18:56:23.61233Z","caller":"traceutil/trace.go:171","msg":"trace[912686175] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4877; }","duration":"146.731412ms","start":"2026-05-04T18:56:23.465572Z","end":"2026-05-04T18:56:23.612303Z","steps":["trace[912686175] 'read index received' (duration: 120.221205ms)","trace[912686175] 'applied index is now lower than readState.Index' (duration: 26.508897ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:23.612388Z","caller":"traceutil/trace.go:171","msg":"trace[943768903] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"227.435795ms","start":"2026-05-04T18:56:23.384918Z","end":"2026-05-04T18:56:23.612354Z","steps":["trace[943768903] 'process raft request' (duration: 200.898107ms)","trace[943768903] 'compare' (duration: 26.2883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:23.612588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.995661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-04T18:56:23.612644Z","caller":"traceutil/trace.go:171","msg":"trace[1018678824] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4705; }","duration":"147.088104ms","start":"2026-05-04T18:56:23.465543Z","end":"2026-05-04T18:56:23.612631Z","steps":["trace[1018678824] 'agreement among raft nodes before linearized reading' (duration: 146.869437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.683528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.897453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:23.683622Z","caller":"traceutil/trace.go:171","msg":"trace[1592716691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4712; }","duration":"218.020177ms","start":"2026-05-04T18:56:23.465561Z","end":"2026-05-04T18:56:23.683581Z","steps":["trace[1592716691] 'agreement among raft nodes before linearized reading' (duration: 217.912213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.683812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.470726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-595cb6dd9c-xhnx2.18ac71b670a08393\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-05-04T18:56:23.683921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.856353ms","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-05-04T18:56:23.68396Z","caller":"traceutil/trace.go:171","msg":"trace[108636311] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-595cb6dd9c-xhnx2.18ac71b670a08393; range_end:; response_count:1; response_revision:4712; }","duration":"216.675972ms","start":"2026-05-04T18:56:23.467224Z","end":"2026-05-04T18:56:23.6839Z","steps":["trace[108636311] 'agreement among raft nodes before linearized reading' (duration: 216.176096ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.683986Z","caller":"traceutil/trace.go:171","msg":"trace[1015154298] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"214.410169ms","start":"2026-05-04T18:56:23.469563Z","end":"2026-05-04T18:56:23.683973Z","steps":["trace[1015154298] 'process raft request' (duration: 213.53301ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.684033Z","caller":"traceutil/trace.go:171","msg":"trace[256213728] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"206.291352ms","start":"2026-05-04T18:56:23.477732Z","end":"2026-05-04T18:56:23.684023Z","steps":["trace[256213728] 'process raft request' (duration: 205.510267ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.684122Z","caller":"traceutil/trace.go:171","msg":"trace[1407498034] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"214.360237ms","start":"2026-05-04T18:56:23.469753Z","end":"2026-05-04T18:56:23.684113Z","steps":["trace[1407498034] 'process raft request' (duration: 213.408376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:23.684122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.772536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f398573d-ea72-4f62-8bc2-147fc45b3ae4\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-04T18:56:23.684201Z","caller":"traceutil/trace.go:171","msg":"trace[454382117] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f398573d-ea72-4f62-8bc2-147fc45b3ae4; range_end:; response_count:1; response_revision:4712; }","duration":"216.87723ms","start":"2026-05-04T18:56:23.467311Z","end":"2026-05-04T18:56:23.684188Z","steps":["trace[454382117] 'agreement among raft nodes before linearized reading' (duration: 216.702544ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.684224Z","caller":"traceutil/trace.go:171","msg":"trace[2043772595] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"214.617466ms","start":"2026-05-04T18:56:23.469594Z","end":"2026-05-04T18:56:23.684211Z","steps":["trace[2043772595] 'process raft request' (duration: 213.53386ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.684273Z","caller":"traceutil/trace.go:171","msg":"trace[1887020317] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"215.213525ms","start":"2026-05-04T18:56:23.469048Z","end":"2026-05-04T18:56:23.684261Z","steps":["trace[1887020317] 'process raft request' (duration: 213.903142ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.684427Z","caller":"traceutil/trace.go:171","msg":"trace[1719677900] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"214.929745ms","start":"2026-05-04T18:56:23.469488Z","end":"2026-05-04T18:56:23.684418Z","steps":["trace[1719677900] 'process raft request' (duration: 213.567011ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.684413Z","caller":"traceutil/trace.go:171","msg":"trace[322852680] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"214.556143ms","start":"2026-05-04T18:56:23.469793Z","end":"2026-05-04T18:56:23.68435Z","steps":["trace[322852680] 'process raft request' (duration: 213.397565ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:23.683992Z","caller":"traceutil/trace.go:171","msg":"trace[1817864261] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4712; }","duration":"214.962277ms","start":"2026-05-04T18:56:23.469014Z","end":"2026-05-04T18:56:23.683976Z","steps":["trace[1817864261] 'agreement among raft nodes before linearized reading' (duration: 214.782651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.08342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.704284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:56:24.083789Z","caller":"traceutil/trace.go:171","msg":"trace[944170716] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4887; }","duration":"386.507674ms","start":"2026-05-04T18:56:23.697265Z","end":"2026-05-04T18:56:24.083772Z","steps":["trace[944170716] 'read index received' (duration: 27.324254ms)","trace[944170716] 'applied index is now lower than readState.Index' (duration: 359.18243ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:24.083901Z","caller":"traceutil/trace.go:171","msg":"trace[630657769] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"392.315394ms","start":"2026-05-04T18:56:23.69157Z","end":"2026-05-04T18:56:24.083886Z","steps":["trace[630657769] 'process raft request' (duration: 33.057113ms)","trace[630657769] 'compare' (duration: 358.411744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:24.083974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.691553Z","time spent":"392.383016ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:56:24.084139Z","caller":"traceutil/trace.go:171","msg":"trace[403037213] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"389.553773ms","start":"2026-05-04T18:56:23.694545Z","end":"2026-05-04T18:56:24.084098Z","steps":["trace[403037213] 'process raft request' (duration: 389.184311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.084276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.694539Z","time spent":"389.660568ms","remote":"127.0.0.1:39056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:56:24.084392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.763656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-05-04T18:56:24.084429Z","caller":"traceutil/trace.go:171","msg":"trace[166451792] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4718; }","duration":"391.815898ms","start":"2026-05-04T18:56:23.692603Z","end":"2026-05-04T18:56:24.084419Z","steps":["trace[166451792] 'agreement among raft nodes before linearized reading' (duration: 391.695764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.084458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.692594Z","time spent":"391.858189ms","remote":"127.0.0.1:38958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1288,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"info","ts":"2026-05-04T18:56:24.084475Z","caller":"traceutil/trace.go:171","msg":"trace[524976645] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"389.999068ms","start":"2026-05-04T18:56:23.694467Z","end":"2026-05-04T18:56:24.084466Z","steps":["trace[524976645] 'process raft request' (duration: 389.228673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.084515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.694446Z","time spent":"390.04679ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:56:24.08428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.810552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:24.084623Z","caller":"traceutil/trace.go:171","msg":"trace[753625076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4718; }","duration":"390.170134ms","start":"2026-05-04T18:56:23.694445Z","end":"2026-05-04T18:56:24.084615Z","steps":["trace[753625076] 'agreement among raft nodes before linearized reading' (duration: 389.72594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.084646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.694439Z","time spent":"390.200945ms","remote":"127.0.0.1:38750","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-05-04T18:56:24.084714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.898874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-04T18:56:24.084706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.476349ms","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":"warn","ts":"2026-05-04T18:56:24.084841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.412586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:56:24.084873Z","caller":"traceutil/trace.go:171","msg":"trace[534170125] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4718; }","duration":"344.477728ms","start":"2026-05-04T18:56:23.740387Z","end":"2026-05-04T18:56:24.084864Z","steps":["trace[534170125] 'agreement among raft nodes before linearized reading' (duration: 344.418696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.084909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.74037Z","time spent":"344.53232ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-04T18:56:24.084955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.948733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:24.084987Z","caller":"traceutil/trace.go:171","msg":"trace[1745092887] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4718; }","duration":"323.016145ms","start":"2026-05-04T18:56:23.761962Z","end":"2026-05-04T18:56:24.084978Z","steps":["trace[1745092887] 'agreement among raft nodes before linearized reading' (duration: 322.961563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.085007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.761942Z","time spent":"323.060447ms","remote":"127.0.0.1:39242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-04T18:56:24.084917Z","caller":"traceutil/trace.go:171","msg":"trace[264733385] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"390.289487ms","start":"2026-05-04T18:56:23.694332Z","end":"2026-05-04T18:56:24.084622Z","steps":["trace[264733385] 'process raft request' (duration: 389.190981ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:24.084892Z","caller":"traceutil/trace.go:171","msg":"trace[562613183] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4718; }","duration":"386.165633ms","start":"2026-05-04T18:56:23.698666Z","end":"2026-05-04T18:56:24.084832Z","steps":["trace[562613183] 'agreement among raft nodes before linearized reading' (duration: 385.744009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.085158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.698655Z","time spent":"386.484923ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-04T18:56:24.085241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.135931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f5e5d1dc-e0d9-4e83-a4c0-da7f09d81e06\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-05-04T18:56:24.085152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.694315Z","time spent":"390.759883ms","remote":"127.0.0.1:39056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:56:24.085268Z","caller":"traceutil/trace.go:171","msg":"trace[49528166] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f5e5d1dc-e0d9-4e83-a4c0-da7f09d81e06; range_end:; response_count:1; response_revision:4718; }","duration":"386.168823ms","start":"2026-05-04T18:56:23.699091Z","end":"2026-05-04T18:56:24.085259Z","steps":["trace[49528166] 'agreement among raft nodes before linearized reading' (duration: 386.06594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.085289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.699087Z","time spent":"386.197894ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-f5e5d1dc-e0d9-4e83-a4c0-da7f09d81e06\" "} {"level":"info","ts":"2026-05-04T18:56:24.085457Z","caller":"traceutil/trace.go:171","msg":"trace[2108729001] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4718; }","duration":"392.834141ms","start":"2026-05-04T18:56:23.692154Z","end":"2026-05-04T18:56:24.084988Z","steps":["trace[2108729001] 'agreement among raft nodes before linearized reading' (duration: 392.254852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.08566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.692146Z","time spent":"393.364048ms","remote":"127.0.0.1:38946","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":"info","ts":"2026-05-04T18:56:24.088408Z","caller":"traceutil/trace.go:171","msg":"trace[1352340098] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"390.025969ms","start":"2026-05-04T18:56:23.694445Z","end":"2026-05-04T18:56:24.084471Z","steps":["trace[1352340098] 'process raft request' (duration: 389.198872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.088539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:23.694438Z","time spent":"394.03435ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:56:24.400551Z","caller":"traceutil/trace.go:171","msg":"trace[1914077908] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"306.114122ms","start":"2026-05-04T18:56:24.094405Z","end":"2026-05-04T18:56:24.400519Z","steps":["trace[1914077908] 'process raft request' (duration: 254.862684ms)","trace[1914077908] 'compare' (duration: 50.948458ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:24.400645Z","caller":"traceutil/trace.go:171","msg":"trace[1940699016] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4892; }","duration":"306.191766ms","start":"2026-05-04T18:56:24.094428Z","end":"2026-05-04T18:56:24.40062Z","steps":["trace[1940699016] 'read index received' (duration: 26.541µs)","trace[1940699016] 'applied index is now lower than readState.Index' (duration: 306.162344ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:24.400684Z","caller":"traceutil/trace.go:171","msg":"trace[1531821911] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"301.976257ms","start":"2026-05-04T18:56:24.09869Z","end":"2026-05-04T18:56:24.400667Z","steps":["trace[1531821911] 'process raft request' (duration: 301.840303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.400768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:24.094389Z","time spent":"306.236477ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:56:24.400858Z","caller":"traceutil/trace.go:171","msg":"trace[271291337] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"279.543813ms","start":"2026-05-04T18:56:24.121296Z","end":"2026-05-04T18:56:24.40084Z","steps":["trace[271291337] 'process raft request' (duration: 279.341476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.400877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:24.098665Z","time spent":"302.123252ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:56:24.400955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.283355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"warn","ts":"2026-05-04T18:56:24.400955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.514236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-04T18:56:24.400982Z","caller":"traceutil/trace.go:171","msg":"trace[119543304] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4721; }","duration":"305.345617ms","start":"2026-05-04T18:56:24.095627Z","end":"2026-05-04T18:56:24.400973Z","steps":["trace[119543304] 'agreement among raft nodes before linearized reading' (duration: 305.188552ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:24.401001Z","caller":"traceutil/trace.go:171","msg":"trace[1166700212] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4721; }","duration":"306.577578ms","start":"2026-05-04T18:56:24.094409Z","end":"2026-05-04T18:56:24.400987Z","steps":["trace[1166700212] 'agreement among raft nodes before linearized reading' (duration: 306.35345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.401009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:24.095615Z","time spent":"305.388039ms","remote":"127.0.0.1:39256","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":"warn","ts":"2026-05-04T18:56:24.401032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:24.094403Z","time spent":"306.620989ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-04T18:56:24.671098Z","caller":"traceutil/trace.go:171","msg":"trace[1061010599] linearizableReadLoop","detail":"{readStateIndex:4900; appliedIndex:4899; }","duration":"201.140166ms","start":"2026-05-04T18:56:24.469937Z","end":"2026-05-04T18:56:24.671077Z","steps":["trace[1061010599] 'read index received' (duration: 198.266992ms)","trace[1061010599] 'applied index is now lower than readState.Index' (duration: 2.872294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:24.671264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.157884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-05-04T18:56:24.671287Z","caller":"traceutil/trace.go:171","msg":"trace[248580884] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4726; }","duration":"262.212356ms","start":"2026-05-04T18:56:24.409068Z","end":"2026-05-04T18:56:24.67128Z","steps":["trace[248580884] 'agreement among raft nodes before linearized reading' (duration: 262.078401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.671325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.689653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a19fe09-3360-43df-85ec-1a00f92459de\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-04T18:56:24.671384Z","caller":"traceutil/trace.go:171","msg":"trace[629884008] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a19fe09-3360-43df-85ec-1a00f92459de; range_end:; response_count:1; response_revision:4726; }","duration":"201.789277ms","start":"2026-05-04T18:56:24.469582Z","end":"2026-05-04T18:56:24.671371Z","steps":["trace[629884008] 'agreement among raft nodes before linearized reading' (duration: 201.615671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:24.671475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.845532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-04T18:56:24.671509Z","caller":"traceutil/trace.go:171","msg":"trace[1461427057] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4726; }","duration":"253.890753ms","start":"2026-05-04T18:56:24.417599Z","end":"2026-05-04T18:56:24.671489Z","steps":["trace[1461427057] 'agreement among raft nodes before linearized reading' (duration: 253.81557ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:24.671532Z","caller":"traceutil/trace.go:171","msg":"trace[1747170062] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"216.85055ms","start":"2026-05-04T18:56:24.454672Z","end":"2026-05-04T18:56:24.671522Z","steps":["trace[1747170062] 'process raft request' (duration: 213.520551ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:24.672179Z","caller":"traceutil/trace.go:171","msg":"trace[787543766] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"199.73684ms","start":"2026-05-04T18:56:24.47242Z","end":"2026-05-04T18:56:24.672156Z","steps":["trace[787543766] 'process raft request' (duration: 199.617867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:37.341843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.719224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:56:37.341966Z","caller":"traceutil/trace.go:171","msg":"trace[1322074002] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5057; }","duration":"567.352443ms","start":"2026-05-04T18:56:36.774595Z","end":"2026-05-04T18:56:37.341947Z","steps":["trace[1322074002] 'read index received' (duration: 43.141µs)","trace[1322074002] 'applied index is now lower than readState.Index' (duration: 567.307242ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:37.342029Z","caller":"traceutil/trace.go:171","msg":"trace[258394179] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"600.105618ms","start":"2026-05-04T18:56:36.741887Z","end":"2026-05-04T18:56:37.341992Z","steps":["trace[258394179] 'process raft request' (duration: 11.146256ms)","trace[258394179] 'compare' (duration: 588.545108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:37.342154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.55027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-04T18:56:37.342162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:36.741852Z","time spent":"600.249692ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T18:56:37.342192Z","caller":"traceutil/trace.go:171","msg":"trace[302171806] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4878; }","duration":"567.624313ms","start":"2026-05-04T18:56:36.774557Z","end":"2026-05-04T18:56:37.342181Z","steps":["trace[302171806] 'agreement among raft nodes before linearized reading' (duration: 567.483388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:37.342229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:36.774541Z","time spent":"567.679914ms","remote":"127.0.0.1:38968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":69,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-04T18:56:38.288507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.489472ms","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-05-04T18:56:38.288607Z","caller":"traceutil/trace.go:171","msg":"trace[699124552] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4878; }","duration":"138.600565ms","start":"2026-05-04T18:56:38.14999Z","end":"2026-05-04T18:56:38.288591Z","steps":["trace[699124552] 'range keys from in-memory index tree' (duration: 138.473332ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:38.288765Z","caller":"traceutil/trace.go:171","msg":"trace[190780792] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"856.166685ms","start":"2026-05-04T18:56:37.432554Z","end":"2026-05-04T18:56:38.28872Z","steps":["trace[190780792] 'process raft request' (duration: 800.576922ms)","trace[190780792] 'compare' (duration: 54.715414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:38.289043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.43253Z","time spent":"856.410793ms","remote":"127.0.0.1:39040","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-05-04T18:56:38.387879Z","caller":"traceutil/trace.go:171","msg":"trace[97837931] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"952.941599ms","start":"2026-05-04T18:56:37.434909Z","end":"2026-05-04T18:56:38.387851Z","steps":["trace[97837931] 'process raft request' (duration: 952.823165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.43487Z","time spent":"953.065073ms","remote":"127.0.0.1:39040","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-05-04T18:56:38.517234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.26327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:56:38.517483Z","caller":"traceutil/trace.go:171","msg":"trace[1345332907] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"1.073907916s","start":"2026-05-04T18:56:37.443557Z","end":"2026-05-04T18:56:38.517465Z","steps":["trace[1345332907] 'process raft request' (duration: 1.073786642s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.517587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.443549Z","time spent":"1.074000088s","remote":"127.0.0.1:39040","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-05-04T18:56:38.517657Z","caller":"traceutil/trace.go:171","msg":"trace[378348329] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"1.009885876s","start":"2026-05-04T18:56:37.507723Z","end":"2026-05-04T18:56:38.517609Z","steps":["trace[378348329] 'process raft request' (duration: 1.00969882s)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:38.517678Z","caller":"traceutil/trace.go:171","msg":"trace[550693444] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"165.27376ms","start":"2026-05-04T18:56:38.352387Z","end":"2026-05-04T18:56:38.51766Z","steps":["trace[550693444] 'process raft request' (duration: 165.178007ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:38.517732Z","caller":"traceutil/trace.go:171","msg":"trace[1350823663] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5058; }","duration":"766.708752ms","start":"2026-05-04T18:56:37.751014Z","end":"2026-05-04T18:56:38.517723Z","steps":["trace[1350823663] 'read index received' (duration: 482.197562ms)","trace[1350823663] 'applied index is now lower than readState.Index' (duration: 284.51034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:38.517819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.507697Z","time spent":"1.010027001s","remote":"127.0.0.1:39040","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-05-04T18:56:38.517662Z","caller":"traceutil/trace.go:171","msg":"trace[1062969515] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"1.08168223s","start":"2026-05-04T18:56:37.435949Z","end":"2026-05-04T18:56:38.517631Z","steps":["trace[1062969515] 'process raft request' (duration: 951.891643ms)","trace[1062969515] 'compare' (duration: 128.876867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:38.517908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.435933Z","time spent":"1.081941049s","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:56:38.517924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.893539ms","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-05-04T18:56:38.517957Z","caller":"traceutil/trace.go:171","msg":"trace[646086261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4884; }","duration":"766.977141ms","start":"2026-05-04T18:56:37.75097Z","end":"2026-05-04T18:56:38.517948Z","steps":["trace[646086261] 'agreement among raft nodes before linearized reading' (duration: 766.823826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.517998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.750951Z","time spent":"767.039053ms","remote":"127.0.0.1:39040","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-05-04T18:56:38.518031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.325068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:56:38.518074Z","caller":"traceutil/trace.go:171","msg":"trace[142710578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4884; }","duration":"726.38983ms","start":"2026-05-04T18:56:37.791672Z","end":"2026-05-04T18:56:38.518062Z","steps":["trace[142710578] 'agreement among raft nodes before linearized reading' (duration: 726.322028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.518112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.791659Z","time spent":"726.445062ms","remote":"127.0.0.1:34648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-04T18:56:38.518177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.482871ms","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-05-04T18:56:38.51821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.396025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-04T18:56:38.518244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.449004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-04T18:56:38.518252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.932848ms","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-05-04T18:56:38.518263Z","caller":"traceutil/trace.go:171","msg":"trace[375538377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4884; }","duration":"706.496518ms","start":"2026-05-04T18:56:37.811752Z","end":"2026-05-04T18:56:38.518248Z","steps":["trace[375538377] 'agreement among raft nodes before linearized reading' (duration: 706.344663ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:38.51829Z","caller":"traceutil/trace.go:171","msg":"trace[1412286533] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4884; }","duration":"160.984819ms","start":"2026-05-04T18:56:38.357292Z","end":"2026-05-04T18:56:38.518276Z","steps":["trace[1412286533] 'agreement among raft nodes before linearized reading' (duration: 160.894076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.518323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.811693Z","time spent":"706.618062ms","remote":"127.0.0.1:39040","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-05-04T18:56:38.518243Z","caller":"traceutil/trace.go:171","msg":"trace[1003679219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4884; }","duration":"664.547473ms","start":"2026-05-04T18:56:37.853684Z","end":"2026-05-04T18:56:38.518231Z","steps":["trace[1003679219] 'agreement among raft nodes before linearized reading' (duration: 664.381057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.518434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.853614Z","time spent":"664.7831ms","remote":"127.0.0.1:39040","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":"info","ts":"2026-05-04T18:56:38.518277Z","caller":"traceutil/trace.go:171","msg":"trace[431267251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4884; }","duration":"662.502966ms","start":"2026-05-04T18:56:37.855764Z","end":"2026-05-04T18:56:38.518267Z","steps":["trace[431267251] 'agreement among raft nodes before linearized reading' (duration: 662.447784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.518556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.855754Z","time spent":"662.779805ms","remote":"127.0.0.1:39040","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-05-04T18:56:38.51818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.379401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-04T18:56:38.518665Z","caller":"traceutil/trace.go:171","msg":"trace[1780779876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4884; }","duration":"662.881158ms","start":"2026-05-04T18:56:37.855745Z","end":"2026-05-04T18:56:38.518626Z","steps":["trace[1780779876] 'agreement among raft nodes before linearized reading' (duration: 662.308849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.518727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:37.855726Z","time spent":"662.985992ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T18:56:38.848776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.063997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:38.848844Z","caller":"traceutil/trace.go:171","msg":"trace[885262938] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4889; }","duration":"159.19363ms","start":"2026-05-04T18:56:38.689636Z","end":"2026-05-04T18:56:38.848829Z","steps":["trace[885262938] 'count revisions from in-memory index tree' (duration: 158.765326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:38.848906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.32869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-04T18:56:38.848964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.915961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-04T18:56:38.849001Z","caller":"traceutil/trace.go:171","msg":"trace[1156801385] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4889; }","duration":"157.470024ms","start":"2026-05-04T18:56:38.691507Z","end":"2026-05-04T18:56:38.848977Z","steps":["trace[1156801385] 'range keys from in-memory index tree' (duration: 157.088502ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:38.849026Z","caller":"traceutil/trace.go:171","msg":"trace[1835552468] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4889; }","duration":"159.004584ms","start":"2026-05-04T18:56:38.690003Z","end":"2026-05-04T18:56:38.849008Z","steps":["trace[1835552468] 'range keys from in-memory index tree' (duration: 158.708925ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:39.592753Z","caller":"traceutil/trace.go:171","msg":"trace[1675428697] linearizableReadLoop","detail":"{readStateIndex:5075; appliedIndex:5074; }","duration":"160.788253ms","start":"2026-05-04T18:56:39.431906Z","end":"2026-05-04T18:56:39.592694Z","steps":["trace[1675428697] 'read index received' (duration: 35.886287ms)","trace[1675428697] 'applied index is now lower than readState.Index' (duration: 124.900706ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:39.592841Z","caller":"traceutil/trace.go:171","msg":"trace[1367889978] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"164.108953ms","start":"2026-05-04T18:56:39.428595Z","end":"2026-05-04T18:56:39.592704Z","steps":["trace[1367889978] 'process raft request' (duration: 97.278361ms)","trace[1367889978] 'compare' (duration: 66.647486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:39.592958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.031502ms","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-05-04T18:56:39.593145Z","caller":"traceutil/trace.go:171","msg":"trace[32915121] 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:4894; }","duration":"161.246059ms","start":"2026-05-04T18:56:39.43186Z","end":"2026-05-04T18:56:39.593106Z","steps":["trace[32915121] 'agreement among raft nodes before linearized reading' (duration: 160.925288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.288313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.957642ms","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-05-04T18:56:40.288391Z","caller":"traceutil/trace.go:171","msg":"trace[1799542842] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4894; }","duration":"587.090167ms","start":"2026-05-04T18:56:39.701282Z","end":"2026-05-04T18:56:40.288372Z","steps":["trace[1799542842] 'range keys from in-memory index tree' (duration: 586.711274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.288432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:39.701261Z","time spent":"587.162869ms","remote":"127.0.0.1:56256","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":"warn","ts":"2026-05-04T18:56:40.288986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.576064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:56:40.289045Z","caller":"traceutil/trace.go:171","msg":"trace[170335964] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5075; }","duration":"423.006474ms","start":"2026-05-04T18:56:39.866028Z","end":"2026-05-04T18:56:40.289035Z","steps":["trace[170335964] 'read index received' (duration: 6.286966ms)","trace[170335964] 'applied index is now lower than readState.Index' (duration: 416.718508ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:40.289136Z","caller":"traceutil/trace.go:171","msg":"trace[429402874] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"540.88349ms","start":"2026-05-04T18:56:39.748217Z","end":"2026-05-04T18:56:40.2891Z","steps":["trace[429402874] 'process raft request' (duration: 124.148761ms)","trace[429402874] 'compare' (duration: 415.8488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:40.289166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.143519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-04T18:56:40.289199Z","caller":"traceutil/trace.go:171","msg":"trace[1467381049] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4895; }","duration":"423.17721ms","start":"2026-05-04T18:56:39.866013Z","end":"2026-05-04T18:56:40.289191Z","steps":["trace[1467381049] 'agreement among raft nodes before linearized reading' (duration: 423.052326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.289226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:39.865959Z","time spent":"423.260963ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-04T18:56:40.289258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:39.748191Z","time spent":"541.005775ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":806,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T18:56:40.943423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.806134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:56:40.943633Z","caller":"traceutil/trace.go:171","msg":"trace[471845923] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"647.52458ms","start":"2026-05-04T18:56:40.29603Z","end":"2026-05-04T18:56:40.943555Z","steps":["trace[471845923] 'process raft request' (duration: 329.465697ms)","trace[471845923] 'compare' (duration: 317.336979ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:40.943647Z","caller":"traceutil/trace.go:171","msg":"trace[1782325775] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5076; }","duration":"647.574232ms","start":"2026-05-04T18:56:40.296049Z","end":"2026-05-04T18:56:40.943623Z","steps":["trace[1782325775] 'read index received' (duration: 329.463127ms)","trace[1782325775] 'applied index is now lower than readState.Index' (duration: 318.108415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:40.943872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.801529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10698"} {"level":"info","ts":"2026-05-04T18:56:40.943971Z","caller":"traceutil/trace.go:171","msg":"trace[1593245158] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4897; }","duration":"647.932964ms","start":"2026-05-04T18:56:40.296025Z","end":"2026-05-04T18:56:40.943958Z","steps":["trace[1593245158] 'agreement among raft nodes before linearized reading' (duration: 647.667855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.944046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.296015Z","time spent":"648.019507ms","remote":"127.0.0.1:38932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":10721,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-04T18:56:40.944186Z","caller":"traceutil/trace.go:171","msg":"trace[922864755] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"420.421391ms","start":"2026-05-04T18:56:40.523683Z","end":"2026-05-04T18:56:40.944104Z","steps":["trace[922864755] 'process raft request' (duration: 420.021908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.943836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.29601Z","time spent":"647.722577ms","remote":"127.0.0.1:39040","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-05-04T18:56:40.94393Z","caller":"traceutil/trace.go:171","msg":"trace[681062038] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"551.157619ms","start":"2026-05-04T18:56:40.392751Z","end":"2026-05-04T18:56:40.943908Z","steps":["trace[681062038] 'process raft request' (duration: 550.797398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.944399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.523655Z","time spent":"420.569846ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:56:40.944485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.392707Z","time spent":"551.728308ms","remote":"127.0.0.1:39040","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-05-04T18:56:40.944749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.103557ms","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-05-04T18:56:40.9448Z","caller":"traceutil/trace.go:171","msg":"trace[1212919511] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4900; }","duration":"647.193969ms","start":"2026-05-04T18:56:40.297593Z","end":"2026-05-04T18:56:40.944787Z","steps":["trace[1212919511] 'agreement among raft nodes before linearized reading' (duration: 646.876689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.944982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.201104ms","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-05-04T18:56:40.945023Z","caller":"traceutil/trace.go:171","msg":"trace[473545789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4900; }","duration":"374.277197ms","start":"2026-05-04T18:56:40.570735Z","end":"2026-05-04T18:56:40.945012Z","steps":["trace[473545789] 'agreement among raft nodes before linearized reading' (duration: 374.118461ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:40.945024Z","caller":"traceutil/trace.go:171","msg":"trace[1360929684] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"421.107544ms","start":"2026-05-04T18:56:40.523872Z","end":"2026-05-04T18:56:40.94498Z","steps":["trace[1360929684] 'process raft request' (duration: 420.240195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.945065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.570716Z","time spent":"374.327829ms","remote":"127.0.0.1:39040","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-05-04T18:56:40.944993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.297574Z","time spent":"647.383456ms","remote":"127.0.0.1:38870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1344,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"warn","ts":"2026-05-04T18:56:40.945158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.523864Z","time spent":"421.247128ms","remote":"127.0.0.1:39040","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-05-04T18:56:40.944936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.589452ms","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-05-04T18:56:40.945333Z","caller":"traceutil/trace.go:171","msg":"trace[1823283192] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4900; }","duration":"322.984225ms","start":"2026-05-04T18:56:40.622331Z","end":"2026-05-04T18:56:40.945315Z","steps":["trace[1823283192] 'agreement among raft nodes before linearized reading' (duration: 322.504349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.945485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.622269Z","time spent":"323.196162ms","remote":"127.0.0.1:56256","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":"warn","ts":"2026-05-04T18:56:40.945521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.145553ms","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-05-04T18:56:40.947662Z","caller":"traceutil/trace.go:171","msg":"trace[1835322319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4900; }","duration":"376.301803ms","start":"2026-05-04T18:56:40.571343Z","end":"2026-05-04T18:56:40.947645Z","steps":["trace[1835322319] 'agreement among raft nodes before linearized reading' (duration: 374.094061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.947716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.571334Z","time spent":"376.366106ms","remote":"127.0.0.1:39040","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-05-04T18:56:40.945396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.870272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-04T18:56:40.947865Z","caller":"traceutil/trace.go:171","msg":"trace[1740593367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4900; }","duration":"404.379565ms","start":"2026-05-04T18:56:40.543474Z","end":"2026-05-04T18:56:40.947854Z","steps":["trace[1740593367] 'agreement among raft nodes before linearized reading' (duration: 401.823061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.947901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.543455Z","time spent":"404.433286ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T18:56:40.945631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.957889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:56:40.948124Z","caller":"traceutil/trace.go:171","msg":"trace[1725268346] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4900; }","duration":"263.494513ms","start":"2026-05-04T18:56:40.684618Z","end":"2026-05-04T18:56:40.948112Z","steps":["trace[1725268346] 'agreement among raft nodes before linearized reading' (duration: 260.96599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.945384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.599167ms","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-05-04T18:56:40.948373Z","caller":"traceutil/trace.go:171","msg":"trace[1901039607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4900; }","duration":"377.618706ms","start":"2026-05-04T18:56:40.570739Z","end":"2026-05-04T18:56:40.948358Z","steps":["trace[1901039607] 'agreement among raft nodes before linearized reading' (duration: 374.538695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.948423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.570727Z","time spent":"377.679969ms","remote":"127.0.0.1:39040","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-05-04T18:56:40.9457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.510905ms","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-05-04T18:56:40.948542Z","caller":"traceutil/trace.go:171","msg":"trace[430054041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4900; }","duration":"377.403359ms","start":"2026-05-04T18:56:40.571132Z","end":"2026-05-04T18:56:40.948535Z","steps":["trace[430054041] 'agreement among raft nodes before linearized reading' (duration: 374.432962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:40.948564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:40.571124Z","time spent":"377.433241ms","remote":"127.0.0.1:39040","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-05-04T18:56:46.904963Z","caller":"traceutil/trace.go:171","msg":"trace[639010282] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"130.660588ms","start":"2026-05-04T18:56:46.774276Z","end":"2026-05-04T18:56:46.904936Z","steps":["trace[639010282] 'process raft request' (duration: 130.501423ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:58.369694Z","caller":"traceutil/trace.go:171","msg":"trace[773895805] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"109.500318ms","start":"2026-05-04T18:56:58.260167Z","end":"2026-05-04T18:56:58.369667Z","steps":["trace[773895805] 'process raft request' (duration: 104.049379ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:58.370395Z","caller":"traceutil/trace.go:171","msg":"trace[1560537368] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"106.21277ms","start":"2026-05-04T18:56:58.264155Z","end":"2026-05-04T18:56:58.370367Z","steps":["trace[1560537368] 'process raft request' (duration: 105.437354ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:59.15428Z","caller":"traceutil/trace.go:171","msg":"trace[1504163426] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"145.649787ms","start":"2026-05-04T18:56:59.008604Z","end":"2026-05-04T18:56:59.154254Z","steps":["trace[1504163426] 'process raft request' (duration: 145.478011ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:59.154304Z","caller":"traceutil/trace.go:171","msg":"trace[1807071611] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"140.169636ms","start":"2026-05-04T18:56:59.014106Z","end":"2026-05-04T18:56:59.154276Z","steps":["trace[1807071611] 'process raft request' (duration: 140.031382ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:59.154482Z","caller":"traceutil/trace.go:171","msg":"trace[1122998754] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"148.556302ms","start":"2026-05-04T18:56:59.005914Z","end":"2026-05-04T18:56:59.15447Z","steps":["trace[1122998754] 'process raft request' (duration: 66.391831ms)","trace[1122998754] 'compare' (duration: 81.638353ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:59.154498Z","caller":"traceutil/trace.go:171","msg":"trace[1779161290] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"140.070233ms","start":"2026-05-04T18:56:59.014411Z","end":"2026-05-04T18:56:59.154481Z","steps":["trace[1779161290] 'process raft request' (duration: 139.765383ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:13.374517Z","caller":"traceutil/trace.go:171","msg":"trace[942759833] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"173.846591ms","start":"2026-05-04T18:57:13.200644Z","end":"2026-05-04T18:57:13.374491Z","steps":["trace[942759833] 'process raft request' (duration: 167.912655ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:13.374679Z","caller":"traceutil/trace.go:171","msg":"trace[1850414436] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"170.986806ms","start":"2026-05-04T18:57:13.203675Z","end":"2026-05-04T18:57:13.374662Z","steps":["trace[1850414436] 'process raft request' (duration: 170.696857ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:13.374839Z","caller":"traceutil/trace.go:171","msg":"trace[335301102] linearizableReadLoop","detail":"{readStateIndex:5394; appliedIndex:5391; }","duration":"133.156512ms","start":"2026-05-04T18:57:13.241671Z","end":"2026-05-04T18:57:13.374827Z","steps":["trace[335301102] 'read index received' (duration: 126.881305ms)","trace[335301102] 'applied index is now lower than readState.Index' (duration: 6.274387ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:13.374956Z","caller":"traceutil/trace.go:171","msg":"trace[180083735] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"169.8836ms","start":"2026-05-04T18:57:13.205064Z","end":"2026-05-04T18:57:13.374947Z","steps":["trace[180083735] 'process raft request' (duration: 169.545948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:13.375128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.447411ms","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-05-04T18:57:13.375165Z","caller":"traceutil/trace.go:171","msg":"trace[133923562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5204; }","duration":"133.518063ms","start":"2026-05-04T18:57:13.241637Z","end":"2026-05-04T18:57:13.375155Z","steps":["trace[133923562] 'agreement among raft nodes before linearized reading' (duration: 133.387299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:15.901885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6716448091961477142,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-04T18:57:15.913392Z","caller":"traceutil/trace.go:171","msg":"trace[778378832] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"533.706664ms","start":"2026-05-04T18:57:15.379662Z","end":"2026-05-04T18:57:15.913368Z","steps":["trace[778378832] 'process raft request' (duration: 533.552239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:15.913681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.379643Z","time spent":"533.812188ms","remote":"127.0.0.1:39040","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-05-04T18:57:16.040455Z","caller":"traceutil/trace.go:171","msg":"trace[2111768555] linearizableReadLoop","detail":"{readStateIndex:5403; appliedIndex:5400; }","duration":"638.817853ms","start":"2026-05-04T18:57:15.401612Z","end":"2026-05-04T18:57:16.04043Z","steps":["trace[2111768555] 'read index received' (duration: 511.660158ms)","trace[2111768555] 'applied index is now lower than readState.Index' (duration: 127.156825ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:16.040495Z","caller":"traceutil/trace.go:171","msg":"trace[197859161] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"660.210048ms","start":"2026-05-04T18:57:15.38025Z","end":"2026-05-04T18:57:16.04046Z","steps":["trace[197859161] 'process raft request' (duration: 660.085404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.04145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.380241Z","time spent":"661.131008ms","remote":"127.0.0.1:39040","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-05-04T18:57:16.040765Z","caller":"traceutil/trace.go:171","msg":"trace[1244870077] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"660.391254ms","start":"2026-05-04T18:57:15.380003Z","end":"2026-05-04T18:57:16.040394Z","steps":["trace[1244870077] 'process raft request' (duration: 631.43116ms)","trace[1244870077] 'compare' (duration: 28.475488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:57:16.041727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.631214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:57:16.041798Z","caller":"traceutil/trace.go:171","msg":"trace[1452935662] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5213; }","duration":"362.728517ms","start":"2026-05-04T18:57:15.679051Z","end":"2026-05-04T18:57:16.041779Z","steps":["trace[1452935662] 'agreement among raft nodes before linearized reading' (duration: 362.619804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.041849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.679036Z","time spent":"362.79986ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-05-04T18:57:16.041857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.207062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-05-04T18:57:16.041908Z","caller":"traceutil/trace.go:171","msg":"trace[859936469] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5213; }","duration":"352.302534ms","start":"2026-05-04T18:57:15.689593Z","end":"2026-05-04T18:57:16.041895Z","steps":["trace[859936469] 'agreement among raft nodes before linearized reading' (duration: 352.111458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.041956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.689587Z","time spent":"352.359286ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6182,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-05-04T18:57:16.041913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.379965Z","time spent":"661.762639ms","remote":"127.0.0.1:39040","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-05-04T18:57:16.04209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.541253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T18:57:16.042156Z","caller":"traceutil/trace.go:171","msg":"trace[1739296903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5213; }","duration":"256.623295ms","start":"2026-05-04T18:57:15.785494Z","end":"2026-05-04T18:57:16.042117Z","steps":["trace[1739296903] 'agreement among raft nodes before linearized reading' (duration: 256.559843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.042244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.187589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:57:16.0423Z","caller":"traceutil/trace.go:171","msg":"trace[1595280662] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5213; }","duration":"362.252612ms","start":"2026-05-04T18:57:15.680033Z","end":"2026-05-04T18:57:16.042286Z","steps":["trace[1595280662] 'agreement among raft nodes before linearized reading' (duration: 362.168349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.04234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.680029Z","time spent":"362.298973ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-05-04T18:57:16.042521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.390423ms","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-05-04T18:57:16.042563Z","caller":"traceutil/trace.go:171","msg":"trace[1871804785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5213; }","duration":"636.444695ms","start":"2026-05-04T18:57:15.406108Z","end":"2026-05-04T18:57:16.042553Z","steps":["trace[1871804785] 'agreement among raft nodes before linearized reading' (duration: 636.338811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.042598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.406103Z","time spent":"636.481177ms","remote":"127.0.0.1:39040","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-05-04T18:57:16.042423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.809919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-04T18:57:16.042745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.143315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-04T18:57:16.042778Z","caller":"traceutil/trace.go:171","msg":"trace[1859037648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5213; }","duration":"636.191647ms","start":"2026-05-04T18:57:15.406578Z","end":"2026-05-04T18:57:16.04277Z","steps":["trace[1859037648] 'agreement among raft nodes before linearized reading' (duration: 636.094864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.042783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.401213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-04T18:57:16.042803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.40657Z","time spent":"636.227738ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-04T18:57:16.042869Z","caller":"traceutil/trace.go:171","msg":"trace[713512438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5213; }","duration":"641.14435ms","start":"2026-05-04T18:57:15.401565Z","end":"2026-05-04T18:57:16.04271Z","steps":["trace[713512438] 'agreement among raft nodes before linearized reading' (duration: 638.966058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.042965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.861729ms","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-05-04T18:57:16.042998Z","caller":"traceutil/trace.go:171","msg":"trace[209096371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5213; }","duration":"636.8932ms","start":"2026-05-04T18:57:15.406097Z","end":"2026-05-04T18:57:16.04299Z","steps":["trace[209096371] 'agreement among raft nodes before linearized reading' (duration: 636.810427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.043024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.406077Z","time spent":"636.941791ms","remote":"127.0.0.1:39040","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-05-04T18:57:16.043012Z","caller":"traceutil/trace.go:171","msg":"trace[1249660775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5213; }","duration":"636.810987ms","start":"2026-05-04T18:57:15.406023Z","end":"2026-05-04T18:57:16.042834Z","steps":["trace[1249660775] 'agreement among raft nodes before linearized reading' (duration: 635.640549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:16.043155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.406012Z","time spent":"637.125958ms","remote":"127.0.0.1:39040","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-05-04T18:57:16.04324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:15.401552Z","time spent":"641.349996ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-04T18:57:47.449075Z","caller":"traceutil/trace.go:171","msg":"trace[1459079013] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"103.196654ms","start":"2026-05-04T18:57:47.345852Z","end":"2026-05-04T18:57:47.449049Z","steps":["trace[1459079013] 'process raft request' (duration: 103.0553ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:47.488529Z","caller":"traceutil/trace.go:171","msg":"trace[494380315] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"136.466211ms","start":"2026-05-04T18:57:47.352045Z","end":"2026-05-04T18:57:47.488511Z","steps":["trace[494380315] 'process raft request' (duration: 136.176851ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:58:09.757531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2932} {"level":"info","ts":"2026-05-04T18:58:09.838363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2932,"took":"78.958691ms","hash":3400158827,"current-db-size-bytes":52785152,"current-db-size":"53 MB","current-db-size-in-use-bytes":28950528,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-04T18:58:09.838425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3400158827,"revision":2932,"compact-revision":-1} {"level":"warn","ts":"2026-05-04T18:58:31.002006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.817954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-05-04T18:58:31.002133Z","caller":"traceutil/trace.go:171","msg":"trace[1820849048] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5733; }","duration":"445.000929ms","start":"2026-05-04T18:58:30.557111Z","end":"2026-05-04T18:58:31.002112Z","steps":["trace[1820849048] 'range keys from in-memory index tree' (duration: 444.603217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:31.002235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:30.557075Z","time spent":"445.147303ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-04T18:58:31.002271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.592072ms","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-05-04T18:58:31.002328Z","caller":"traceutil/trace.go:171","msg":"trace[376331815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5733; }","duration":"363.678415ms","start":"2026-05-04T18:58:30.638635Z","end":"2026-05-04T18:58:31.002314Z","steps":["trace[376331815] 'range keys from in-memory index tree' (duration: 363.387017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:31.002361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:30.638623Z","time spent":"363.730646ms","remote":"127.0.0.1:39040","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-05-04T18:58:31.002521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.241588ms","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-05-04T18:58:31.002539Z","caller":"traceutil/trace.go:171","msg":"trace[366936452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5733; }","duration":"382.29356ms","start":"2026-05-04T18:58:30.62024Z","end":"2026-05-04T18:58:31.002534Z","steps":["trace[366936452] 'range keys from in-memory index tree' (duration: 382.155727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:31.002552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:30.620224Z","time spent":"382.324871ms","remote":"127.0.0.1:39040","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-05-04T18:58:31.002675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.727441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-04T18:58:31.002775Z","caller":"traceutil/trace.go:171","msg":"trace[1704913581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5733; }","duration":"382.891706ms","start":"2026-05-04T18:58:30.619863Z","end":"2026-05-04T18:58:31.002755Z","steps":["trace[1704913581] 'range keys from in-memory index tree' (duration: 382.509966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:31.002824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:30.619845Z","time spent":"382.970098ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T18:58:31.002803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.289025ms","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-05-04T18:58:31.002925Z","caller":"traceutil/trace.go:171","msg":"trace[880642964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5733; }","duration":"366.449168ms","start":"2026-05-04T18:58:30.636448Z","end":"2026-05-04T18:58:31.002897Z","steps":["trace[880642964] 'range keys from in-memory index tree' (duration: 366.075249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:31.002982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:30.636437Z","time spent":"366.532651ms","remote":"127.0.0.1:39040","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-05-04T18:58:31.799386Z","caller":"traceutil/trace.go:171","msg":"trace[2132327375] transaction","detail":"{read_only:false; response_revision:5739; number_of_response:1; }","duration":"347.439961ms","start":"2026-05-04T18:58:31.451867Z","end":"2026-05-04T18:58:31.799307Z","steps":["trace[2132327375] 'process raft request' (duration: 346.602149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:31.799662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:31.451844Z","time spent":"347.644407ms","remote":"127.0.0.1:39040","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-05-04T19:00:56.871467Z","caller":"traceutil/trace.go:171","msg":"trace[1347221785] transaction","detail":"{read_only:false; response_revision:6731; number_of_response:1; }","duration":"148.431744ms","start":"2026-05-04T19:00:56.723018Z","end":"2026-05-04T19:00:56.87145Z","steps":["trace[1347221785] 'process raft request' (duration: 148.084254ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:00:57.176102Z","caller":"traceutil/trace.go:171","msg":"trace[1623825825] transaction","detail":"{read_only:false; response_revision:6732; number_of_response:1; }","duration":"206.725504ms","start":"2026-05-04T19:00:56.969355Z","end":"2026-05-04T19:00:57.17608Z","steps":["trace[1623825825] 'process raft request' (duration: 131.336807ms)","trace[1623825825] 'compare' (duration: 75.258563ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:09.766235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5602} {"level":"info","ts":"2026-05-04T19:03:09.852691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5602,"took":"84.918587ms","hash":3670539379,"current-db-size-bytes":52785152,"current-db-size":"53 MB","current-db-size-in-use-bytes":18169856,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-04T19:03:09.852764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3670539379,"revision":5602,"compact-revision":2932}