{"level":"warn","ts":"2026-07-30T18:55:41.44772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T18:55:41.447809Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-07-30T18:55:41.44787Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T18:55:41.447884Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-30T18:55:41.447902Z","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-07-30T18:55:41.448523Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-07-30T18:55:41.448602Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-07-30T18:55:41.451128Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.328902ms"} {"level":"info","ts":"2026-07-30T18:55:41.462063Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-07-30T18:55:41.462181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T18:55:41.462219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-07-30T18:55:41.462246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T18:55:41.462265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-07-30T18:55:41.462319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-07-30T18:55:41.469695Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T18:55:41.470997Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T18:55:41.472166Z","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-07-30T18:55:41.474439Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T18:55:41.474643Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T18:55:41.474731Z","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-07-30T18:55:41.474996Z","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-07-30T18:55:41.475076Z","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-07-30T18:55:41.476697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-07-30T18:55:41.47701Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-30T18:55:41.480687Z","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-07-30T18:55:41.481021Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T18:55:41.48108Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T18:55:41.4812Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-30T18:55:41.482319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-30T18:55:41.562613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T18:55:41.562701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T18:55:41.562735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-07-30T18:55:41.562756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-07-30T18:55:41.562769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-30T18:55:41.562792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-07-30T18:55:41.562812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-30T18:55:41.563887Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T18:55:41.564042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T18:55:41.564111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T18:55:41.564214Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:55:41.565879Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:55:41.565977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:55:41.566009Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:55:41.56747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-07-30T18:55:41.567665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T18:55:41.567808Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T18:55:41.567888Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T18:56:00.457435Z","caller":"traceutil/trace.go:171","msg":"trace[1029998655] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"257.724405ms","start":"2026-07-30T18:56:00.199674Z","end":"2026-07-30T18:56:00.457398Z","steps":["trace[1029998655] 'process raft request' (duration: 257.467379ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:05.861874Z","caller":"traceutil/trace.go:171","msg":"trace[634207678] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"186.338744ms","start":"2026-07-30T18:56:05.675493Z","end":"2026-07-30T18:56:05.861832Z","steps":["trace[634207678] 'process raft request' (duration: 186.130979ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:06.000648Z","caller":"traceutil/trace.go:171","msg":"trace[662517629] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"134.856133ms","start":"2026-07-30T18:56:05.865766Z","end":"2026-07-30T18:56:06.000622Z","steps":["trace[662517629] 'process raft request' (duration: 134.727379ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:24.350631Z","caller":"traceutil/trace.go:171","msg":"trace[928520] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"317.118949ms","start":"2026-07-30T18:56:24.033436Z","end":"2026-07-30T18:56:24.350555Z","steps":["trace[928520] 'read index received' (duration: 310.927944ms)","trace[928520] 'applied index is now lower than readState.Index' (duration: 6.189294ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:56:24.350856Z","caller":"traceutil/trace.go:171","msg":"trace[2142926345] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"502.354533ms","start":"2026-07-30T18:56:23.848465Z","end":"2026-07-30T18:56:24.35082Z","steps":["trace[2142926345] 'process raft request' (duration: 499.660131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:24.351098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.648052ms","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-07-30T18:56:24.351236Z","caller":"traceutil/trace.go:171","msg":"trace[1037639099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"317.848428ms","start":"2026-07-30T18:56:24.033364Z","end":"2026-07-30T18:56:24.351212Z","steps":["trace[1037639099] 'agreement among raft nodes before linearized reading' (duration: 317.620452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:24.351291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:24.033298Z","time spent":"317.982951ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T18:56:24.351472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:23.8484Z","time spent":"502.554248ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:56:24.676505Z","caller":"traceutil/trace.go:171","msg":"trace[619348847] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"325.747298ms","start":"2026-07-30T18:56:24.350731Z","end":"2026-07-30T18:56:24.676478Z","steps":["trace[619348847] 'read index received' (duration: 309.66809ms)","trace[619348847] 'applied index is now lower than readState.Index' (duration: 16.078308ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:56:24.676585Z","caller":"traceutil/trace.go:171","msg":"trace[495634469] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"356.657331ms","start":"2026-07-30T18:56:24.319895Z","end":"2026-07-30T18:56:24.676552Z","steps":["trace[495634469] 'process raft request' (duration: 340.488961ms)","trace[495634469] 'compare' (duration: 15.963205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:56:24.676662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.381882ms","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-07-30T18:56:24.676709Z","caller":"traceutil/trace.go:171","msg":"trace[692399725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"419.485435ms","start":"2026-07-30T18:56:24.257213Z","end":"2026-07-30T18:56:24.676698Z","steps":["trace[692399725] 'agreement among raft nodes before linearized reading' (duration: 419.379472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:24.676752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:24.257189Z","time spent":"419.554067ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T18:56:24.676769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:24.319876Z","time spent":"356.771295ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:56:24.681081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.665708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:56:24.681168Z","caller":"traceutil/trace.go:171","msg":"trace[231672450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"238.785472ms","start":"2026-07-30T18:56:24.442359Z","end":"2026-07-30T18:56:24.681144Z","steps":["trace[231672450] 'agreement among raft nodes before linearized reading' (duration: 238.620907ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:24.681157Z","caller":"traceutil/trace.go:171","msg":"trace[1337810904] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"325.878812ms","start":"2026-07-30T18:56:24.355236Z","end":"2026-07-30T18:56:24.681115Z","steps":["trace[1337810904] 'process raft request' (duration: 325.561233ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:24.681136Z","caller":"traceutil/trace.go:171","msg":"trace[60211693] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"266.339715ms","start":"2026-07-30T18:56:24.414775Z","end":"2026-07-30T18:56:24.681114Z","steps":["trace[60211693] 'process raft request' (duration: 266.15382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:24.681371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:24.3552Z","time spent":"326.044356ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:56:24.681684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.501402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x6z6g\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T18:56:24.682208Z","caller":"traceutil/trace.go:171","msg":"trace[2105703354] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x6z6g; range_end:; response_count:1; response_revision:631; }","duration":"173.615145ms","start":"2026-07-30T18:56:24.508139Z","end":"2026-07-30T18:56:24.681755Z","steps":["trace[2105703354] 'agreement among raft nodes before linearized reading' (duration: 172.997338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:25.321804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639752575,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T18:56:25.630083Z","caller":"traceutil/trace.go:171","msg":"trace[909422906] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"926.960355ms","start":"2026-07-30T18:56:24.703089Z","end":"2026-07-30T18:56:25.630049Z","steps":["trace[909422906] 'process raft request' (duration: 868.271901ms)","trace[909422906] 'compare' (duration: 58.361765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:56:25.630259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:24.703059Z","time spent":"927.097917ms","remote":"127.0.0.1:58376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:56:25.633274Z","caller":"traceutil/trace.go:171","msg":"trace[1111290083] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"812.072233ms","start":"2026-07-30T18:56:24.821166Z","end":"2026-07-30T18:56:25.633238Z","steps":["trace[1111290083] 'read index received' (duration: 750.232316ms)","trace[1111290083] 'applied index is now lower than readState.Index' (duration: 61.838697ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:56:25.633347Z","caller":"traceutil/trace.go:171","msg":"trace[1158350066] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"537.431357ms","start":"2026-07-30T18:56:25.095867Z","end":"2026-07-30T18:56:25.633298Z","steps":["trace[1158350066] 'process raft request' (duration: 536.77518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:25.633466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:25.095838Z","time spent":"537.566991ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:56:25.63356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.857334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T18:56:25.633621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.422923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T18:56:25.633722Z","caller":"traceutil/trace.go:171","msg":"trace[493210703] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:635; }","duration":"812.587998ms","start":"2026-07-30T18:56:24.821108Z","end":"2026-07-30T18:56:25.633696Z","steps":["trace[493210703] 'agreement among raft nodes before linearized reading' (duration: 812.335861ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:25.633617Z","caller":"traceutil/trace.go:171","msg":"trace[2107847429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"191.022539ms","start":"2026-07-30T18:56:25.442579Z","end":"2026-07-30T18:56:25.633601Z","steps":["trace[2107847429] 'agreement among raft nodes before linearized reading' (duration: 190.873675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:25.633785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:56:24.821084Z","time spent":"812.68938ms","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-07-30T18:56:25.633779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.547967ms","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-07-30T18:56:25.633838Z","caller":"traceutil/trace.go:171","msg":"trace[2018256239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"136.65877ms","start":"2026-07-30T18:56:25.497165Z","end":"2026-07-30T18:56:25.633824Z","steps":["trace[2018256239] 'agreement among raft nodes before linearized reading' (duration: 136.465265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:26.820546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.77187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T18:56:26.820593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.199969ms","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-07-30T18:56:26.820645Z","caller":"traceutil/trace.go:171","msg":"trace[304549784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"112.296672ms","start":"2026-07-30T18:56:26.708334Z","end":"2026-07-30T18:56:26.82063Z","steps":["trace[304549784] 'range keys from in-memory index tree' (duration: 112.067916ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:26.820643Z","caller":"traceutil/trace.go:171","msg":"trace[39759250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"134.926615ms","start":"2026-07-30T18:56:26.685692Z","end":"2026-07-30T18:56:26.820619Z","steps":["trace[39759250] 'range keys from in-memory index tree' (duration: 134.585965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:26.82063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.13177ms","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-07-30T18:56:26.820716Z","caller":"traceutil/trace.go:171","msg":"trace[1802525956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"123.264824ms","start":"2026-07-30T18:56:26.697432Z","end":"2026-07-30T18:56:26.820696Z","steps":["trace[1802525956] 'range keys from in-memory index tree' (duration: 122.926915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:41.69745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.515812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:56:41.697564Z","caller":"traceutil/trace.go:171","msg":"trace[975800483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"254.694597ms","start":"2026-07-30T18:56:41.442846Z","end":"2026-07-30T18:56:41.697541Z","steps":["trace[975800483] 'range keys from in-memory index tree' (duration: 254.417049ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:41.732581Z","caller":"traceutil/trace.go:171","msg":"trace[610778142] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"108.17352ms","start":"2026-07-30T18:56:41.624363Z","end":"2026-07-30T18:56:41.732537Z","steps":["trace[610778142] 'process raft request' (duration: 107.81035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:22.575006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639753498,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T18:57:22.79513Z","caller":"traceutil/trace.go:171","msg":"trace[491299080] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"720.853431ms","start":"2026-07-30T18:57:22.074247Z","end":"2026-07-30T18:57:22.795101Z","steps":["trace[491299080] 'read index received' (duration: 720.542492ms)","trace[491299080] 'applied index is now lower than readState.Index' (duration: 309.599µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:57:22.795277Z","caller":"traceutil/trace.go:171","msg":"trace[811797371] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"860.312378ms","start":"2026-07-30T18:57:21.934952Z","end":"2026-07-30T18:57:22.795264Z","steps":["trace[811797371] 'process raft request' (duration: 859.954578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:22.795349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.646299ms","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":"warn","ts":"2026-07-30T18:57:22.795396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:21.934934Z","time spent":"860.372269ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:22.795412Z","caller":"traceutil/trace.go:171","msg":"trace[1685280774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1084; }","duration":"381.761042ms","start":"2026-07-30T18:57:22.413632Z","end":"2026-07-30T18:57:22.795393Z","steps":["trace[1685280774] 'agreement among raft nodes before linearized reading' (duration: 381.656269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:22.79545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:22.413608Z","time spent":"381.833144ms","remote":"127.0.0.1:49796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T18:57:22.795633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.224397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T18:57:22.795611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.342753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:57:22.795656Z","caller":"traceutil/trace.go:171","msg":"trace[1949756303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"353.283848ms","start":"2026-07-30T18:57:22.442366Z","end":"2026-07-30T18:57:22.795649Z","steps":["trace[1949756303] 'agreement among raft nodes before linearized reading' (duration: 353.247457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:22.795676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:22.442347Z","time spent":"353.32425ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T18:57:22.795682Z","caller":"traceutil/trace.go:171","msg":"trace[1828854370] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1084; }","duration":"721.465087ms","start":"2026-07-30T18:57:22.074197Z","end":"2026-07-30T18:57:22.795662Z","steps":["trace[1828854370] 'agreement among raft nodes before linearized reading' (duration: 721.321433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:22.795736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:22.074179Z","time spent":"721.539579ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-07-30T18:57:23.031876Z","caller":"traceutil/trace.go:171","msg":"trace[583393790] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"176.399918ms","start":"2026-07-30T18:57:22.855447Z","end":"2026-07-30T18:57:23.031847Z","steps":["trace[583393790] 'process raft request' (duration: 174.99074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:51.59721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639754105,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T18:57:51.835264Z","caller":"traceutil/trace.go:171","msg":"trace[2037204056] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"901.780984ms","start":"2026-07-30T18:57:50.933455Z","end":"2026-07-30T18:57:51.835236Z","steps":["trace[2037204056] 'process raft request' (duration: 901.576708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:51.835433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:50.933444Z","time spent":"901.905857ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:57:52.307699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.984189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T18:57:52.307861Z","caller":"traceutil/trace.go:171","msg":"trace[415851174] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1461; }","duration":"1.210747674s","start":"2026-07-30T18:57:51.097097Z","end":"2026-07-30T18:57:52.307844Z","steps":["trace[415851174] 'read index received' (duration: 738.008357ms)","trace[415851174] 'applied index is now lower than readState.Index' (duration: 472.738207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:57:52.308003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210878768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T18:57:52.307985Z","caller":"traceutil/trace.go:171","msg":"trace[623477193] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"536.411343ms","start":"2026-07-30T18:57:51.771529Z","end":"2026-07-30T18:57:52.307941Z","steps":["trace[623477193] 'process raft request' (duration: 536.258759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072000274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:57:52.308128Z","caller":"traceutil/trace.go:171","msg":"trace[1217908472] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1418; }","duration":"1.072070256s","start":"2026-07-30T18:57:51.236049Z","end":"2026-07-30T18:57:52.308119Z","steps":["trace[1217908472] 'agreement among raft nodes before linearized reading' (duration: 1.072025355s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.236038Z","time spent":"1.072117977s","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T18:57:52.308192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.77151Z","time spent":"536.576878ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:57:52.308276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.621321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T18:57:52.308362Z","caller":"traceutil/trace.go:171","msg":"trace[861791384] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1418; }","duration":"805.740664ms","start":"2026-07-30T18:57:51.5026Z","end":"2026-07-30T18:57:52.30834Z","steps":["trace[861791384] 'agreement among raft nodes before linearized reading' (duration: 805.523478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.305044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-07-30T18:57:52.30841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.502586Z","time spent":"805.816086ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T18:57:52.308042Z","caller":"traceutil/trace.go:171","msg":"trace[1369475585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1418; }","duration":"1.211029812s","start":"2026-07-30T18:57:51.097001Z","end":"2026-07-30T18:57:52.308031Z","steps":["trace[1369475585] 'agreement among raft nodes before linearized reading' (duration: 1.21092884s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.851874ms","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-07-30T18:57:52.308521Z","caller":"traceutil/trace.go:171","msg":"trace[192380300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1418; }","duration":"849.950656ms","start":"2026-07-30T18:57:51.458561Z","end":"2026-07-30T18:57:52.308512Z","steps":["trace[192380300] 'agreement among raft nodes before linearized reading' (duration: 849.802632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.458541Z","time spent":"850.006757ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T18:57:52.30847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.582396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T18:57:52.308614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051493811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T18:57:52.308635Z","caller":"traceutil/trace.go:171","msg":"trace[331538210] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1418; }","duration":"527.814972ms","start":"2026-07-30T18:57:51.780788Z","end":"2026-07-30T18:57:52.308603Z","steps":["trace[331538210] 'agreement among raft nodes before linearized reading' (duration: 527.524524ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:52.308664Z","caller":"traceutil/trace.go:171","msg":"trace[619735286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1418; }","duration":"1.051568463s","start":"2026-07-30T18:57:51.257082Z","end":"2026-07-30T18:57:52.308651Z","steps":["trace[619735286] 'agreement among raft nodes before linearized reading' (duration: 1.05143733s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.780766Z","time spent":"527.911415ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":18,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-30T18:57:52.308703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.257072Z","time spent":"1.051623794s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T18:57:52.30871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.339919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T18:57:52.308757Z","caller":"traceutil/trace.go:171","msg":"trace[760864755] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1418; }","duration":"909.435101ms","start":"2026-07-30T18:57:51.399309Z","end":"2026-07-30T18:57:52.308744Z","steps":["trace[760864755] 'agreement among raft nodes before linearized reading' (duration: 909.370329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.096975Z","time spent":"1.211495035s","remote":"127.0.0.1:49856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-30T18:57:52.308767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05291398s","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-07-30T18:57:52.308793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.399287Z","time spent":"909.497903ms","remote":"127.0.0.1:49842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-30T18:57:52.308427Z","caller":"traceutil/trace.go:171","msg":"trace[1418913437] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1418; }","duration":"186.436198ms","start":"2026-07-30T18:57:52.121972Z","end":"2026-07-30T18:57:52.308408Z","steps":["trace[1418913437] 'agreement among raft nodes before linearized reading' (duration: 186.179451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.30882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043791034s","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-07-30T18:57:52.308827Z","caller":"traceutil/trace.go:171","msg":"trace[883729877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1418; }","duration":"1.052997761s","start":"2026-07-30T18:57:51.255808Z","end":"2026-07-30T18:57:52.308806Z","steps":["trace[883729877] 'agreement among raft nodes before linearized reading' (duration: 1.052787976s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.431576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T18:57:52.308865Z","caller":"traceutil/trace.go:171","msg":"trace[1377675884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1418; }","duration":"1.043857035s","start":"2026-07-30T18:57:51.264998Z","end":"2026-07-30T18:57:52.308855Z","steps":["trace[1377675884] 'agreement among raft nodes before linearized reading' (duration: 1.043737121s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.255795Z","time spent":"1.053069654s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T18:57:52.3089Z","caller":"traceutil/trace.go:171","msg":"trace[1290640882] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1418; }","duration":"601.57766ms","start":"2026-07-30T18:57:51.707302Z","end":"2026-07-30T18:57:52.308879Z","steps":["trace[1290640882] 'agreement among raft nodes before linearized reading' (duration: 601.375255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:52.308902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.264964Z","time spent":"1.043929517s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T18:57:52.308946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.707274Z","time spent":"601.664272ms","remote":"127.0.0.1:58316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-30T18:57:52.308794Z","caller":"traceutil/trace.go:171","msg":"trace[665171275] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"1.187166559s","start":"2026-07-30T18:57:51.12116Z","end":"2026-07-30T18:57:52.308327Z","steps":["trace[665171275] 'process raft request' (duration: 978.344248ms)","trace[665171275] 'compare' (duration: 207.846365ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:57:52.310002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:51.121135Z","time spent":"1.188456564s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:52.518168Z","caller":"traceutil/trace.go:171","msg":"trace[1994107746] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"204.501393ms","start":"2026-07-30T18:57:52.313638Z","end":"2026-07-30T18:57:52.518139Z","steps":["trace[1994107746] 'process raft request' (duration: 204.427591ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:52.518224Z","caller":"traceutil/trace.go:171","msg":"trace[1294739643] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"204.638558ms","start":"2026-07-30T18:57:52.313558Z","end":"2026-07-30T18:57:52.518197Z","steps":["trace[1294739643] 'process raft request' (duration: 204.3544ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:52.518894Z","caller":"traceutil/trace.go:171","msg":"trace[207665605] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"204.583306ms","start":"2026-07-30T18:57:52.31429Z","end":"2026-07-30T18:57:52.518873Z","steps":["trace[207665605] 'process raft request' (duration: 204.385791ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:52.518948Z","caller":"traceutil/trace.go:171","msg":"trace[1372493338] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"204.455723ms","start":"2026-07-30T18:57:52.314479Z","end":"2026-07-30T18:57:52.518935Z","steps":["trace[1372493338] 'process raft request' (duration: 204.282738ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:52.518993Z","caller":"traceutil/trace.go:171","msg":"trace[1462659783] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"204.930006ms","start":"2026-07-30T18:57:52.314041Z","end":"2026-07-30T18:57:52.518971Z","steps":["trace[1462659783] 'process raft request' (duration: 204.064962ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:53.708553Z","caller":"traceutil/trace.go:171","msg":"trace[1155479416] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"370.388216ms","start":"2026-07-30T18:57:53.33814Z","end":"2026-07-30T18:57:53.708529Z","steps":["trace[1155479416] 'process raft request' (duration: 369.955235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:53.708733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:53.338112Z","time spent":"370.51092ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:57:54.858081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639754124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T18:57:54.917198Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068711525s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T18:57:54.988657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149102453s","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-07-30T18:57:54.988767Z","caller":"traceutil/trace.go:171","msg":"trace[323611631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1426; }","duration":"1.149250117s","start":"2026-07-30T18:57:53.839477Z","end":"2026-07-30T18:57:54.988727Z","steps":["trace[323611631] 'range keys from in-memory index tree' (duration: 1.148903718s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:54.988804Z","caller":"traceutil/trace.go:171","msg":"trace[1709557737] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1472; }","duration":"631.061305ms","start":"2026-07-30T18:57:54.357722Z","end":"2026-07-30T18:57:54.988783Z","steps":["trace[1709557737] 'read index received' (duration: 559.753782ms)","trace[1709557737] 'applied index is now lower than readState.Index' (duration: 71.306543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:57:54.988827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:53.839453Z","time spent":"1.14935942s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T18:57:54.988955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.210149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T18:57:54.988948Z","caller":"traceutil/trace.go:171","msg":"trace[502796449] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"673.053397ms","start":"2026-07-30T18:57:54.31585Z","end":"2026-07-30T18:57:54.988904Z","steps":["trace[502796449] 'process raft request' (duration: 672.854792ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:54.988886Z","caller":"traceutil/trace.go:171","msg":"trace[422299989] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"1.140477721s","start":"2026-07-30T18:57:53.848379Z","end":"2026-07-30T18:57:54.988857Z","steps":["trace[422299989] 'process raft request' (duration: 1.069123617s)","trace[422299989] 'compare' (duration: 70.966903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:57:54.989142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.013345ms","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-07-30T18:57:54.989179Z","caller":"traceutil/trace.go:171","msg":"trace[1393972919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1428; }","duration":"466.071836ms","start":"2026-07-30T18:57:54.523099Z","end":"2026-07-30T18:57:54.989171Z","steps":["trace[1393972919] 'agreement among raft nodes before linearized reading' (duration: 465.950223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:54.989214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.375321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:57:54.989264Z","caller":"traceutil/trace.go:171","msg":"trace[498252771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1428; }","duration":"546.449293ms","start":"2026-07-30T18:57:54.442797Z","end":"2026-07-30T18:57:54.989247Z","steps":["trace[498252771] 'agreement among raft nodes before linearized reading' (duration: 546.400532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:54.989285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.618698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-30T18:57:54.989283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.95208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-30T18:57:54.989295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.442774Z","time spent":"546.514605ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T18:57:54.989312Z","caller":"traceutil/trace.go:171","msg":"trace[508797133] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:1428; }","duration":"164.643449ms","start":"2026-07-30T18:57:54.824661Z","end":"2026-07-30T18:57:54.989304Z","steps":["trace[508797133] 'agreement among raft nodes before linearized reading' (duration: 164.570657ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:54.989353Z","caller":"traceutil/trace.go:171","msg":"trace[1632787475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1428; }","duration":"467.030822ms","start":"2026-07-30T18:57:54.522282Z","end":"2026-07-30T18:57:54.989313Z","steps":["trace[1632787475] 'agreement among raft nodes before linearized reading' (duration: 466.857447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:54.989179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.651572ms","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-07-30T18:57:54.989388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.522253Z","time spent":"467.124055ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T18:57:54.989384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.194254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-07-30T18:57:54.989406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:53.84836Z","time spent":"1.140816819s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:54.989435Z","caller":"traceutil/trace.go:171","msg":"trace[152793605] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1428; }","duration":"461.287468ms","start":"2026-07-30T18:57:54.528133Z","end":"2026-07-30T18:57:54.989421Z","steps":["trace[152793605] 'agreement among raft nodes before linearized reading' (duration: 461.114433ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:54.989436Z","caller":"traceutil/trace.go:171","msg":"trace[112193442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1428; }","duration":"466.922589ms","start":"2026-07-30T18:57:54.522472Z","end":"2026-07-30T18:57:54.989395Z","steps":["trace[112193442] 'agreement among raft nodes before linearized reading' (duration: 466.653972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:54.989476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.528113Z","time spent":"461.351559ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T18:57:54.989007Z","caller":"traceutil/trace.go:171","msg":"trace[2049009202] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1428; }","duration":"631.308681ms","start":"2026-07-30T18:57:54.357684Z","end":"2026-07-30T18:57:54.988993Z","steps":["trace[2049009202] 'agreement among raft nodes before linearized reading' (duration: 631.215249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:54.989501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.522441Z","time spent":"467.040132ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T18:57:54.989546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.357672Z","time spent":"631.863037ms","remote":"127.0.0.1:45798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":8,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-30T18:57:54.989212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.523089Z","time spent":"466.115907ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T18:57:54.989088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.875711ms","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-07-30T18:57:54.989648Z","caller":"traceutil/trace.go:171","msg":"trace[1092661688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1428; }","duration":"601.435216ms","start":"2026-07-30T18:57:54.388166Z","end":"2026-07-30T18:57:54.989601Z","steps":["trace[1092661688] 'agreement among raft nodes before linearized reading' (duration: 600.800479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:54.989742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.388156Z","time spent":"601.55455ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T18:57:54.989094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:54.315818Z","time spent":"673.196241ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:56.664914Z","caller":"traceutil/trace.go:171","msg":"trace[1635091917] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"224.104722ms","start":"2026-07-30T18:57:56.440786Z","end":"2026-07-30T18:57:56.66489Z","steps":["trace[1635091917] 'read index received' (duration: 223.920047ms)","trace[1635091917] 'applied index is now lower than readState.Index' (duration: 183.675µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:57:56.665053Z","caller":"traceutil/trace.go:171","msg":"trace[1345081996] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"238.546931ms","start":"2026-07-30T18:57:56.426461Z","end":"2026-07-30T18:57:56.665008Z","steps":["trace[1345081996] 'process raft request' (duration: 238.231773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:56.665105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.072871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T18:57:56.665094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.286297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:57:56.665144Z","caller":"traceutil/trace.go:171","msg":"trace[715552553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1437; }","duration":"145.165894ms","start":"2026-07-30T18:57:56.519966Z","end":"2026-07-30T18:57:56.665132Z","steps":["trace[715552553] 'agreement among raft nodes before linearized reading' (duration: 145.072381ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:56.665165Z","caller":"traceutil/trace.go:171","msg":"trace[448885741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1437; }","duration":"224.40681ms","start":"2026-07-30T18:57:56.440743Z","end":"2026-07-30T18:57:56.66515Z","steps":["trace[448885741] 'agreement among raft nodes before linearized reading' (duration: 224.281457ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:57.199797Z","caller":"traceutil/trace.go:171","msg":"trace[992857747] linearizableReadLoop","detail":"{readStateIndex:1485; appliedIndex:1484; }","duration":"123.148391ms","start":"2026-07-30T18:57:57.076625Z","end":"2026-07-30T18:57:57.199773Z","steps":["trace[992857747] 'read index received' (duration: 122.927495ms)","trace[992857747] 'applied index is now lower than readState.Index' (duration: 219.706µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:57:57.199966Z","caller":"traceutil/trace.go:171","msg":"trace[1379715031] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"204.012471ms","start":"2026-07-30T18:57:56.995839Z","end":"2026-07-30T18:57:57.199851Z","steps":["trace[1379715031] 'process raft request' (duration: 203.725913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:57.200028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.388267ms","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-07-30T18:57:57.200103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.754672ms","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-07-30T18:57:57.200103Z","caller":"traceutil/trace.go:171","msg":"trace[1396952045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1438; }","duration":"123.50669ms","start":"2026-07-30T18:57:57.076579Z","end":"2026-07-30T18:57:57.200086Z","steps":["trace[1396952045] 'agreement among raft nodes before linearized reading' (duration: 123.281554ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:57.200141Z","caller":"traceutil/trace.go:171","msg":"trace[1895858138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1438; }","duration":"121.833724ms","start":"2026-07-30T18:57:57.078297Z","end":"2026-07-30T18:57:57.200131Z","steps":["trace[1895858138] 'agreement among raft nodes before linearized reading' (duration: 121.751172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:57.200189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.503216ms","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-07-30T18:57:57.200265Z","caller":"traceutil/trace.go:171","msg":"trace[1369517215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1438; }","duration":"121.623319ms","start":"2026-07-30T18:57:57.078624Z","end":"2026-07-30T18:57:57.200247Z","steps":["trace[1369517215] 'agreement among raft nodes before linearized reading' (duration: 121.423504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:57.200025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.38919ms","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-07-30T18:57:57.200432Z","caller":"traceutil/trace.go:171","msg":"trace[759068679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1438; }","duration":"122.8096ms","start":"2026-07-30T18:57:57.077589Z","end":"2026-07-30T18:57:57.200399Z","steps":["trace[759068679] 'agreement among raft nodes before linearized reading' (duration: 122.320527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:57.200584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.887623ms","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-07-30T18:57:57.200662Z","caller":"traceutil/trace.go:171","msg":"trace[1139292125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1438; }","duration":"123.010586ms","start":"2026-07-30T18:57:57.077637Z","end":"2026-07-30T18:57:57.200647Z","steps":["trace[1139292125] 'agreement among raft nodes before linearized reading' (duration: 122.743539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:57.200647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.878059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T18:57:57.200724Z","caller":"traceutil/trace.go:171","msg":"trace[1240731377] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1438; }","duration":"120.989182ms","start":"2026-07-30T18:57:57.079717Z","end":"2026-07-30T18:57:57.200706Z","steps":["trace[1240731377] 'agreement among raft nodes before linearized reading' (duration: 120.730315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:57.943576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639754151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T18:57:58.444469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639754151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T18:57:58.944687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639754151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T18:57:59.120592Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.838559802s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T18:57:59.12139Z","caller":"traceutil/trace.go:171","msg":"trace[1883455740] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"1.916281088s","start":"2026-07-30T18:57:57.205069Z","end":"2026-07-30T18:57:59.12135Z","steps":["trace[1883455740] 'process raft request' (duration: 1.915835676s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:59.121405Z","caller":"traceutil/trace.go:171","msg":"trace[178419568] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"1.915754454s","start":"2026-07-30T18:57:57.205592Z","end":"2026-07-30T18:57:59.121346Z","steps":["trace[178419568] 'process raft request' (duration: 1.91560877s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:59.121486Z","caller":"traceutil/trace.go:171","msg":"trace[795519162] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"1.916310619s","start":"2026-07-30T18:57:57.205145Z","end":"2026-07-30T18:57:59.121456Z","steps":["trace[795519162] 'process raft request' (duration: 1.916007001s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.1216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:57.205044Z","time spent":"1.916425722s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:57:59.121626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:57.205136Z","time spent":"1.916427082s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:59.121764Z","caller":"traceutil/trace.go:171","msg":"trace[1132524704] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"1.916064192s","start":"2026-07-30T18:57:57.205649Z","end":"2026-07-30T18:57:59.121714Z","steps":["trace[1132524704] 'process raft request' (duration: 1.91559448s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.12192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:57.205581Z","time spent":"1.915954909s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:57:59.121992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:57.205644Z","time spent":"1.916234287s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:59.122042Z","caller":"traceutil/trace.go:171","msg":"trace[95918917] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"1.916231456s","start":"2026-07-30T18:57:57.205786Z","end":"2026-07-30T18:57:59.122017Z","steps":["trace[95918917] 'process raft request' (duration: 1.915858996s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.122143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:57.205779Z","time spent":"1.9163183s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:59.123384Z","caller":"traceutil/trace.go:171","msg":"trace[1503603749] linearizableReadLoop","detail":"{readStateIndex:1492; appliedIndex:1486; }","duration":"1.680579053s","start":"2026-07-30T18:57:57.442783Z","end":"2026-07-30T18:57:59.123362Z","steps":["trace[1503603749] 'read index received' (duration: 1.678089766s)","trace[1503603749] 'applied index is now lower than readState.Index' (duration: 2.488017ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:57:59.123491Z","caller":"traceutil/trace.go:171","msg":"trace[1634372506] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"625.165397ms","start":"2026-07-30T18:57:58.498303Z","end":"2026-07-30T18:57:59.123468Z","steps":["trace[1634372506] 'process raft request' (duration: 624.91169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.123529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.680743408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:57:59.123577Z","caller":"traceutil/trace.go:171","msg":"trace[1946066110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1445; }","duration":"1.68083703s","start":"2026-07-30T18:57:57.442725Z","end":"2026-07-30T18:57:59.123562Z","steps":["trace[1946066110] 'agreement among raft nodes before linearized reading' (duration: 1.680755898s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.123606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:58.49828Z","time spent":"625.261228ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:57:59.123625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:57.442703Z","time spent":"1.680910791s","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T18:57:59.123733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600819472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T18:57:59.123811Z","caller":"traceutil/trace.go:171","msg":"trace[489442355] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1445; }","duration":"1.600914315s","start":"2026-07-30T18:57:57.522872Z","end":"2026-07-30T18:57:59.123786Z","steps":["trace[489442355] 'agreement among raft nodes before linearized reading' (duration: 1.600744761s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.123851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:57.522862Z","time spent":"1.600980577s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:57:59.123985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.262628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T18:57:59.12401Z","caller":"traceutil/trace.go:171","msg":"trace[832146469] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1445; }","duration":"411.31703ms","start":"2026-07-30T18:57:58.712685Z","end":"2026-07-30T18:57:59.124002Z","steps":["trace[832146469] 'agreement among raft nodes before linearized reading' (duration: 411.246118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.124034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:58.712671Z","time spent":"411.357912ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:57:59.124116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045090268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-30T18:57:59.124179Z","caller":"traceutil/trace.go:171","msg":"trace[1012951247] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1445; }","duration":"1.045210971s","start":"2026-07-30T18:57:58.078946Z","end":"2026-07-30T18:57:59.124156Z","steps":["trace[1012951247] 'agreement among raft nodes before linearized reading' (duration: 1.045007136s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:59.12424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:58.078925Z","time spent":"1.045288394s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T18:58:14.211311Z","caller":"traceutil/trace.go:171","msg":"trace[829462961] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"161.70665ms","start":"2026-07-30T18:58:14.049576Z","end":"2026-07-30T18:58:14.211283Z","steps":["trace[829462961] 'process raft request' (duration: 161.519355ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.50102Z","caller":"traceutil/trace.go:171","msg":"trace[333991239] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"271.645244ms","start":"2026-07-30T18:58:19.22935Z","end":"2026-07-30T18:58:19.500995Z","steps":["trace[333991239] 'process raft request' (duration: 271.547071ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.501029Z","caller":"traceutil/trace.go:171","msg":"trace[1819733487] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"272.524048ms","start":"2026-07-30T18:58:19.228463Z","end":"2026-07-30T18:58:19.500987Z","steps":["trace[1819733487] 'process raft request' (duration: 270.078972ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.501064Z","caller":"traceutil/trace.go:171","msg":"trace[1438311207] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"272.480577ms","start":"2026-07-30T18:58:19.228545Z","end":"2026-07-30T18:58:19.501026Z","steps":["trace[1438311207] 'process raft request' (duration: 272.290812ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.501375Z","caller":"traceutil/trace.go:171","msg":"trace[203254212] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"270.456082ms","start":"2026-07-30T18:58:19.230907Z","end":"2026-07-30T18:58:19.501363Z","steps":["trace[203254212] 'process raft request' (duration: 270.337659ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.501418Z","caller":"traceutil/trace.go:171","msg":"trace[1770142545] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"271.245423ms","start":"2026-07-30T18:58:19.230137Z","end":"2026-07-30T18:58:19.501383Z","steps":["trace[1770142545] 'process raft request' (duration: 270.804012ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.501635Z","caller":"traceutil/trace.go:171","msg":"trace[917214353] linearizableReadLoop","detail":"{readStateIndex:1777; appliedIndex:1769; }","duration":"119.582985ms","start":"2026-07-30T18:58:19.382038Z","end":"2026-07-30T18:58:19.501621Z","steps":["trace[917214353] 'read index received' (duration: 116.513512ms)","trace[917214353] 'applied index is now lower than readState.Index' (duration: 3.068503ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:58:19.501648Z","caller":"traceutil/trace.go:171","msg":"trace[45314704] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"270.114153ms","start":"2026-07-30T18:58:19.231505Z","end":"2026-07-30T18:58:19.501619Z","steps":["trace[45314704] 'process raft request' (duration: 269.807555ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.501744Z","caller":"traceutil/trace.go:171","msg":"trace[107941326] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"225.449239ms","start":"2026-07-30T18:58:19.276254Z","end":"2026-07-30T18:58:19.501703Z","steps":["trace[107941326] 'process raft request' (duration: 225.322216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:19.50183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.77861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T18:58:19.50188Z","caller":"traceutil/trace.go:171","msg":"trace[1246395351] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1721; }","duration":"119.880383ms","start":"2026-07-30T18:58:19.381986Z","end":"2026-07-30T18:58:19.501867Z","steps":["trace[1246395351] 'agreement among raft nodes before linearized reading' (duration: 119.719098ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:19.501873Z","caller":"traceutil/trace.go:171","msg":"trace[1494208491] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"270.076023ms","start":"2026-07-30T18:58:19.231686Z","end":"2026-07-30T18:58:19.501762Z","steps":["trace[1494208491] 'process raft request' (duration: 269.842416ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:20.397105Z","caller":"traceutil/trace.go:171","msg":"trace[461799229] linearizableReadLoop","detail":"{readStateIndex:1781; appliedIndex:1780; }","duration":"202.433849ms","start":"2026-07-30T18:58:20.194642Z","end":"2026-07-30T18:58:20.397076Z","steps":["trace[461799229] 'read index received' (duration: 198.662907ms)","trace[461799229] 'applied index is now lower than readState.Index' (duration: 3.768841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:58:20.397361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.772201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T18:58:20.3974Z","caller":"traceutil/trace.go:171","msg":"trace[473428702] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1724; }","duration":"148.925326ms","start":"2026-07-30T18:58:20.248463Z","end":"2026-07-30T18:58:20.397388Z","steps":["trace[473428702] 'agreement among raft nodes before linearized reading' (duration: 148.792132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:20.397442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.778247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-30T18:58:20.39755Z","caller":"traceutil/trace.go:171","msg":"trace[46283726] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1724; }","duration":"203.010204ms","start":"2026-07-30T18:58:20.194515Z","end":"2026-07-30T18:58:20.397525Z","steps":["trace[46283726] 'agreement among raft nodes before linearized reading' (duration: 202.684785ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:48.877304Z","caller":"traceutil/trace.go:171","msg":"trace[983597649] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"218.482161ms","start":"2026-07-30T18:58:48.658803Z","end":"2026-07-30T18:58:48.877285Z","steps":["trace[983597649] 'read index received' (duration: 218.311077ms)","trace[983597649] 'applied index is now lower than readState.Index' (duration: 170.074µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:58:48.877386Z","caller":"traceutil/trace.go:171","msg":"trace[1405907239] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"238.964833ms","start":"2026-07-30T18:58:48.638393Z","end":"2026-07-30T18:58:48.877357Z","steps":["trace[1405907239] 'process raft request' (duration: 238.759808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:48.877607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.770719ms","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":"warn","ts":"2026-07-30T18:58:48.877665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.441527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11911"} {"level":"info","ts":"2026-07-30T18:58:48.877714Z","caller":"traceutil/trace.go:171","msg":"trace[145383646] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2200; }","duration":"218.912172ms","start":"2026-07-30T18:58:48.658775Z","end":"2026-07-30T18:58:48.877688Z","steps":["trace[145383646] 'agreement among raft nodes before linearized reading' (duration: 218.682856ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:48.877734Z","caller":"traceutil/trace.go:171","msg":"trace[1459478157] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2200; }","duration":"173.544279ms","start":"2026-07-30T18:58:48.704173Z","end":"2026-07-30T18:58:48.877717Z","steps":["trace[1459478157] 'agreement among raft nodes before linearized reading' (duration: 173.374784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:49.121118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.67112ms","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-07-30T18:58:49.121218Z","caller":"traceutil/trace.go:171","msg":"trace[757133835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2200; }","duration":"220.811113ms","start":"2026-07-30T18:58:48.900383Z","end":"2026-07-30T18:58:49.121194Z","steps":["trace[757133835] 'range keys from in-memory index tree' (duration: 220.483224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:49.847268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639755659,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T18:58:50.100899Z","caller":"traceutil/trace.go:171","msg":"trace[1807233374] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"761.255375ms","start":"2026-07-30T18:58:49.33962Z","end":"2026-07-30T18:58:50.100875Z","steps":["trace[1807233374] 'process raft request' (duration: 761.088451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:50.101068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.339595Z","time spent":"761.392079ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:58:50.144494Z","caller":"traceutil/trace.go:171","msg":"trace[1213500091] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"522.981751ms","start":"2026-07-30T18:58:49.621467Z","end":"2026-07-30T18:58:50.144449Z","steps":["trace[1213500091] 'process raft request' (duration: 522.251431ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:50.1446Z","caller":"traceutil/trace.go:171","msg":"trace[448511402] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2284; }","duration":"797.449662ms","start":"2026-07-30T18:58:49.347127Z","end":"2026-07-30T18:58:50.144577Z","steps":["trace[448511402] 'read index received' (duration: 753.592029ms)","trace[448511402] 'applied index is now lower than readState.Index' (duration: 43.855853ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:58:50.144676Z","caller":"traceutil/trace.go:171","msg":"trace[1848604734] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"502.503179ms","start":"2026-07-30T18:58:49.642124Z","end":"2026-07-30T18:58:50.144627Z","steps":["trace[1848604734] 'process raft request' (duration: 502.255182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:50.144754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.201498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T18:58:50.144793Z","caller":"traceutil/trace.go:171","msg":"trace[691807448] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2203; }","duration":"661.29382ms","start":"2026-07-30T18:58:49.483489Z","end":"2026-07-30T18:58:50.144783Z","steps":["trace[691807448] 'agreement among raft nodes before linearized reading' (duration: 661.214528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:50.144838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.483467Z","time spent":"661.357902ms","remote":"127.0.0.1:58568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-30T18:58:50.144879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.642105Z","time spent":"502.663323ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:58:50.1449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.950832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T18:58:50.145016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.710004ms","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-07-30T18:58:50.14503Z","caller":"traceutil/trace.go:171","msg":"trace[1044240634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2203; }","duration":"496.128926ms","start":"2026-07-30T18:58:49.648882Z","end":"2026-07-30T18:58:50.145011Z","steps":["trace[1044240634] 'agreement among raft nodes before linearized reading' (duration: 495.87708ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:50.145088Z","caller":"traceutil/trace.go:171","msg":"trace[1857290565] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2203; }","duration":"242.816427ms","start":"2026-07-30T18:58:49.902251Z","end":"2026-07-30T18:58:50.145067Z","steps":["trace[1857290565] 'agreement among raft nodes before linearized reading' (duration: 242.600211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:50.145098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.648865Z","time spent":"496.218129ms","remote":"127.0.0.1:58480","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-07-30T18:58:50.145083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.229708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-30T18:58:50.145175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.55332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-30T18:58:50.145229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.302727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T18:58:50.145242Z","caller":"traceutil/trace.go:171","msg":"trace[624878411] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2203; }","duration":"589.379361ms","start":"2026-07-30T18:58:49.555808Z","end":"2026-07-30T18:58:50.145187Z","steps":["trace[624878411] 'agreement among raft nodes before linearized reading' (duration: 589.125954ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:50.145269Z","caller":"traceutil/trace.go:171","msg":"trace[188242781] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2203; }","duration":"591.682354ms","start":"2026-07-30T18:58:49.553561Z","end":"2026-07-30T18:58:50.145243Z","steps":["trace[188242781] 'agreement among raft nodes before linearized reading' (duration: 591.441607ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:50.145278Z","caller":"traceutil/trace.go:171","msg":"trace[1931378843] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2203; }","duration":"491.375199ms","start":"2026-07-30T18:58:49.653889Z","end":"2026-07-30T18:58:50.145264Z","steps":["trace[1931378843] 'agreement among raft nodes before linearized reading' (duration: 491.220545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:50.145265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.224934ms","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-07-30T18:58:50.145317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.5558Z","time spent":"589.503835ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:58:50.145361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.653879Z","time spent":"491.438521ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T18:58:50.145359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.553543Z","time spent":"591.772906ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:58:50.145118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.712431ms","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-07-30T18:58:50.145431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.988356ms","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-07-30T18:58:50.145461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.324065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T18:58:50.145495Z","caller":"traceutil/trace.go:171","msg":"trace[183491277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2203; }","duration":"495.076198ms","start":"2026-07-30T18:58:49.650403Z","end":"2026-07-30T18:58:50.145479Z","steps":["trace[183491277] 'agreement among raft nodes before linearized reading' (duration: 494.890233ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:50.145393Z","caller":"traceutil/trace.go:171","msg":"trace[878640027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2203; }","duration":"498.377127ms","start":"2026-07-30T18:58:49.646993Z","end":"2026-07-30T18:58:50.145371Z","steps":["trace[878640027] 'agreement among raft nodes before linearized reading' (duration: 498.11914ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:50.145511Z","caller":"traceutil/trace.go:171","msg":"trace[280143233] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2203; }","duration":"798.396577ms","start":"2026-07-30T18:58:49.3471Z","end":"2026-07-30T18:58:50.145496Z","steps":["trace[280143233] 'agreement among raft nodes before linearized reading' (duration: 797.572514ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:50.145493Z","caller":"traceutil/trace.go:171","msg":"trace[395750377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2203; }","duration":"501.161532ms","start":"2026-07-30T18:58:49.644283Z","end":"2026-07-30T18:58:50.145445Z","steps":["trace[395750377] 'agreement among raft nodes before linearized reading' (duration: 500.505394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:50.145551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.347092Z","time spent":"798.451619ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:58:50.145553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.646979Z","time spent":"498.560323ms","remote":"127.0.0.1:58480","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-07-30T18:58:50.145562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.650386Z","time spent":"495.148021ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T18:58:50.145567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.644263Z","time spent":"501.285916ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T18:58:50.14561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.621439Z","time spent":"523.191137ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:58:50.14564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.85651ms","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-07-30T18:58:50.145767Z","caller":"traceutil/trace.go:171","msg":"trace[1660382973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2203; }","duration":"499.025355ms","start":"2026-07-30T18:58:49.646715Z","end":"2026-07-30T18:58:50.14574Z","steps":["trace[1660382973] 'agreement among raft nodes before linearized reading' (duration: 498.546812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:50.145838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:49.646705Z","time spent":"499.116677ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T18:58:54.77895Z","caller":"traceutil/trace.go:171","msg":"trace[780344849] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"300.343008ms","start":"2026-07-30T18:58:54.478576Z","end":"2026-07-30T18:58:54.778919Z","steps":["trace[780344849] 'process raft request' (duration: 300.067951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:54.779132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:58:54.478564Z","time spent":"300.476662ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:00.63385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639755840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T18:59:01.134253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639755840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T18:59:01.149772Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019915029s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T18:59:01.15017Z","caller":"traceutil/trace.go:171","msg":"trace[1318051111] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"1.020396542s","start":"2026-07-30T18:59:00.129747Z","end":"2026-07-30T18:59:01.150144Z","steps":["trace[1318051111] 'process raft request' (duration: 1.020214767s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.150332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.129719Z","time spent":"1.020525095s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:01.217902Z","caller":"traceutil/trace.go:171","msg":"trace[185312706] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"1.02069829s","start":"2026-07-30T18:59:00.197175Z","end":"2026-07-30T18:59:01.217873Z","steps":["trace[185312706] 'process raft request' (duration: 1.020590837s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.21791Z","caller":"traceutil/trace.go:171","msg":"trace[186372158] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"1.024922784s","start":"2026-07-30T18:59:00.192949Z","end":"2026-07-30T18:59:01.217872Z","steps":["trace[186372158] 'process raft request' (duration: 1.019020115s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.218041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.197155Z","time spent":"1.020809082s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:01.218114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.192932Z","time spent":"1.025056467s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:01.220087Z","caller":"traceutil/trace.go:171","msg":"trace[611996628] linearizableReadLoop","detail":"{readStateIndex:2387; appliedIndex:2381; }","duration":"1.086452483s","start":"2026-07-30T18:59:00.133613Z","end":"2026-07-30T18:59:01.220066Z","steps":["trace[611996628] 'read index received' (duration: 1.016358693s)","trace[611996628] 'applied index is now lower than readState.Index' (duration: 70.09283ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:01.220109Z","caller":"traceutil/trace.go:171","msg":"trace[1227771332] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"549.162785ms","start":"2026-07-30T18:59:00.670921Z","end":"2026-07-30T18:59:01.220083Z","steps":["trace[1227771332] 'process raft request' (duration: 549.034442ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.220131Z","caller":"traceutil/trace.go:171","msg":"trace[963438845] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"303.727788ms","start":"2026-07-30T18:59:00.916379Z","end":"2026-07-30T18:59:01.220106Z","steps":["trace[963438845] 'process raft request' (duration: 303.646486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.670892Z","time spent":"549.276579ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:01.22027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.91636Z","time spent":"303.823171ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:01.220338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086703359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-sgh8c\" ","response":"range_response_count:1 size:6144"} {"level":"warn","ts":"2026-07-30T18:59:01.22037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052430675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-30T18:59:01.220377Z","caller":"traceutil/trace.go:171","msg":"trace[2037640768] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-sgh8c; range_end:; response_count:1; response_revision:2299; }","duration":"1.086805362s","start":"2026-07-30T18:59:00.133558Z","end":"2026-07-30T18:59:01.220363Z","steps":["trace[2037640768] 'agreement among raft nodes before linearized reading' (duration: 1.086634467s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.220403Z","caller":"traceutil/trace.go:171","msg":"trace[500264690] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2299; }","duration":"1.052509057s","start":"2026-07-30T18:59:00.167885Z","end":"2026-07-30T18:59:01.220394Z","steps":["trace[500264690] 'agreement among raft nodes before linearized reading' (duration: 1.052383013s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.133537Z","time spent":"1.086870274s","remote":"127.0.0.1:58376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":6166,"request content":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-sgh8c\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.167852Z","time spent":"1.052569589s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014524343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-30T18:59:01.220428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01256621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-07-30T18:59:01.220408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.604102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:59:01.220478Z","caller":"traceutil/trace.go:171","msg":"trace[1546381142] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2299; }","duration":"1.012642113s","start":"2026-07-30T18:59:00.207822Z","end":"2026-07-30T18:59:01.220464Z","steps":["trace[1546381142] 'agreement among raft nodes before linearized reading' (duration: 1.012498899s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.220501Z","caller":"traceutil/trace.go:171","msg":"trace[1424731085] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2299; }","duration":"271.724575ms","start":"2026-07-30T18:59:00.948755Z","end":"2026-07-30T18:59:01.22048Z","steps":["trace[1424731085] 'agreement among raft nodes before linearized reading' (duration: 271.51872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014183674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-30T18:59:01.220513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.20781Z","time spent":"1.012695554s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049781903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-30T18:59:01.220536Z","caller":"traceutil/trace.go:171","msg":"trace[2111544753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2299; }","duration":"1.014274047s","start":"2026-07-30T18:59:00.206257Z","end":"2026-07-30T18:59:01.220531Z","steps":["trace[2111544753] 'agreement among raft nodes before linearized reading' (duration: 1.014218975s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.220546Z","caller":"traceutil/trace.go:171","msg":"trace[19291060] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2299; }","duration":"1.049831745s","start":"2026-07-30T18:59:00.170707Z","end":"2026-07-30T18:59:01.220539Z","steps":["trace[19291060] 'agreement among raft nodes before linearized reading' (duration: 1.049774743s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.220464Z","caller":"traceutil/trace.go:171","msg":"trace[1184925018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2299; }","duration":"1.014570195s","start":"2026-07-30T18:59:00.205887Z","end":"2026-07-30T18:59:01.220457Z","steps":["trace[1184925018] 'agreement among raft nodes before linearized reading' (duration: 1.014508003s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.206205Z","time spent":"1.014344888s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.17069Z","time spent":"1.049869846s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.205875Z","time spent":"1.014697768s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T18:59:01.22062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.578336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T18:59:01.220615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014682137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-30T18:59:01.220646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016725832s","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-07-30T18:59:01.22064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049780724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-30T18:59:01.220663Z","caller":"traceutil/trace.go:171","msg":"trace[617265697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2299; }","duration":"1.01477516s","start":"2026-07-30T18:59:00.205875Z","end":"2026-07-30T18:59:01.22065Z","steps":["trace[617265697] 'agreement among raft nodes before linearized reading' (duration: 1.014636036s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.865609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T18:59:01.220711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.205851Z","time spent":"1.014852902s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.059176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T18:59:01.220716Z","caller":"traceutil/trace.go:171","msg":"trace[316272434] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2299; }","duration":"1.049855136s","start":"2026-07-30T18:59:00.17084Z","end":"2026-07-30T18:59:01.220695Z","steps":["trace[316272434] 'agreement among raft nodes before linearized reading' (duration: 1.04963835s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00401123s","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-07-30T18:59:01.220733Z","caller":"traceutil/trace.go:171","msg":"trace[884076052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2299; }","duration":"778.924781ms","start":"2026-07-30T18:59:00.4418Z","end":"2026-07-30T18:59:01.220725Z","steps":["trace[884076052] 'agreement among raft nodes before linearized reading' (duration: 778.89408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004953555s","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-07-30T18:59:01.220751Z","caller":"traceutil/trace.go:171","msg":"trace[1213404022] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2299; }","duration":"739.134078ms","start":"2026-07-30T18:59:00.481608Z","end":"2026-07-30T18:59:01.220742Z","steps":["trace[1213404022] 'agreement among raft nodes before linearized reading' (duration: 739.058106ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.22076Z","caller":"traceutil/trace.go:171","msg":"trace[1254583852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2299; }","duration":"1.00405925s","start":"2026-07-30T18:59:00.216692Z","end":"2026-07-30T18:59:01.220752Z","steps":["trace[1254583852] 'agreement among raft nodes before linearized reading' (duration: 1.003975808s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:01.220766Z","caller":"traceutil/trace.go:171","msg":"trace[862885513] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2299; }","duration":"1.004999307s","start":"2026-07-30T18:59:00.21576Z","end":"2026-07-30T18:59:01.220759Z","steps":["trace[862885513] 'agreement among raft nodes before linearized reading' (duration: 1.004932605s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.170767Z","time spent":"1.04998813s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T18:59:01.220678Z","caller":"traceutil/trace.go:171","msg":"trace[1497237426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2299; }","duration":"1.016752403s","start":"2026-07-30T18:59:00.203914Z","end":"2026-07-30T18:59:01.220666Z","steps":["trace[1497237426] 'agreement among raft nodes before linearized reading' (duration: 1.016689811s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.216684Z","time spent":"1.004095382s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.215743Z","time spent":"1.005038957s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.481591Z","time spent":"739.181519ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.441776Z","time spent":"778.981673ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T18:59:01.220803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.203857Z","time spent":"1.016937909s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T18:59:01.220648Z","caller":"traceutil/trace.go:171","msg":"trace[410677208] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2299; }","duration":"398.646197ms","start":"2026-07-30T18:59:00.821992Z","end":"2026-07-30T18:59:01.220639Z","steps":["trace[410677208] 'agreement among raft nodes before linearized reading' (duration: 398.586686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:01.220885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:00.821972Z","time spent":"398.903584ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-07-30T18:59:15.360693Z","caller":"traceutil/trace.go:171","msg":"trace[229066150] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"155.001208ms","start":"2026-07-30T18:59:15.20567Z","end":"2026-07-30T18:59:15.360671Z","steps":["trace[229066150] 'process raft request' (duration: 154.866244ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:15.582998Z","caller":"traceutil/trace.go:171","msg":"trace[490916973] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"318.396094ms","start":"2026-07-30T18:59:15.264583Z","end":"2026-07-30T18:59:15.582979Z","steps":["trace[490916973] 'process raft request' (duration: 313.037519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:15.583167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:15.26454Z","time spent":"318.521987ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:15.583248Z","caller":"traceutil/trace.go:171","msg":"trace[2046237962] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"318.021024ms","start":"2026-07-30T18:59:15.265196Z","end":"2026-07-30T18:59:15.583217Z","steps":["trace[2046237962] 'process raft request' (duration: 317.712945ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:15.583618Z","caller":"traceutil/trace.go:171","msg":"trace[1578384947] linearizableReadLoop","detail":"{readStateIndex:2500; appliedIndex:2496; }","duration":"303.375599ms","start":"2026-07-30T18:59:15.280202Z","end":"2026-07-30T18:59:15.583578Z","steps":["trace[1578384947] 'read index received' (duration: 80.319485ms)","trace[1578384947] 'applied index is now lower than readState.Index' (duration: 223.054664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:59:15.583418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:15.265171Z","time spent":"318.152267ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:15.583701Z","caller":"traceutil/trace.go:171","msg":"trace[694909465] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"317.890391ms","start":"2026-07-30T18:59:15.265769Z","end":"2026-07-30T18:59:15.583659Z","steps":["trace[694909465] 'process raft request' (duration: 317.660304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:15.583827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.622986ms","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-07-30T18:59:15.583854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:15.265755Z","time spent":"318.013343ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:15.583969Z","caller":"traceutil/trace.go:171","msg":"trace[1351612861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2409; }","duration":"303.731399ms","start":"2026-07-30T18:59:15.280156Z","end":"2026-07-30T18:59:15.583887Z","steps":["trace[1351612861] 'agreement among raft nodes before linearized reading' (duration: 303.533103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:15.584017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:15.280144Z","time spent":"303.862782ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T18:59:15.584113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.179591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-30T18:59:15.584121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.727795ms","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-07-30T18:59:15.584159Z","caller":"traceutil/trace.go:171","msg":"trace[2063833000] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2409; }","duration":"291.255443ms","start":"2026-07-30T18:59:15.292894Z","end":"2026-07-30T18:59:15.584149Z","steps":["trace[2063833000] 'agreement among raft nodes before linearized reading' (duration: 291.083468ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:15.58417Z","caller":"traceutil/trace.go:171","msg":"trace[1581639210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2409; }","duration":"291.796697ms","start":"2026-07-30T18:59:15.292361Z","end":"2026-07-30T18:59:15.584158Z","steps":["trace[1581639210] 'agreement among raft nodes before linearized reading' (duration: 291.579381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:15.584165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.285274ms","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-07-30T18:59:15.584179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.892624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-30T18:59:15.584146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.131866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:59:15.584228Z","caller":"traceutil/trace.go:171","msg":"trace[625544254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2409; }","duration":"294.382806ms","start":"2026-07-30T18:59:15.28983Z","end":"2026-07-30T18:59:15.584212Z","steps":["trace[625544254] 'agreement among raft nodes before linearized reading' (duration: 294.229342ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:15.584236Z","caller":"traceutil/trace.go:171","msg":"trace[588582495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2409; }","duration":"273.960616ms","start":"2026-07-30T18:59:15.310263Z","end":"2026-07-30T18:59:15.584224Z","steps":["trace[588582495] 'agreement among raft nodes before linearized reading' (duration: 273.839552ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:15.584272Z","caller":"traceutil/trace.go:171","msg":"trace[880818066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2409; }","duration":"144.319131ms","start":"2026-07-30T18:59:15.439927Z","end":"2026-07-30T18:59:15.584246Z","steps":["trace[880818066] 'agreement among raft nodes before linearized reading' (duration: 144.070475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:15.584079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.863262ms","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-07-30T18:59:15.584351Z","caller":"traceutil/trace.go:171","msg":"trace[2113169597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2409; }","duration":"291.179291ms","start":"2026-07-30T18:59:15.293151Z","end":"2026-07-30T18:59:15.58433Z","steps":["trace[2113169597] 'agreement among raft nodes before linearized reading' (duration: 290.727968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:15.584762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.762381ms","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-07-30T18:59:15.58482Z","caller":"traceutil/trace.go:171","msg":"trace[729636027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2409; }","duration":"296.838532ms","start":"2026-07-30T18:59:15.287966Z","end":"2026-07-30T18:59:15.584804Z","steps":["trace[729636027] 'agreement among raft nodes before linearized reading' (duration: 295.884217ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:21.609167Z","caller":"traceutil/trace.go:171","msg":"trace[1657260862] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"340.946433ms","start":"2026-07-30T18:59:21.268198Z","end":"2026-07-30T18:59:21.609145Z","steps":["trace[1657260862] 'process raft request' (duration: 340.787628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:21.609344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.268173Z","time spent":"341.068276ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:21.968716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.687925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T18:59:21.96898Z","caller":"traceutil/trace.go:171","msg":"trace[1368150252] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"592.271149ms","start":"2026-07-30T18:59:21.376689Z","end":"2026-07-30T18:59:21.96896Z","steps":["trace[1368150252] 'process raft request' (duration: 592.201447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:21.969056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.37667Z","time spent":"592.354081ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:21.969063Z","caller":"traceutil/trace.go:171","msg":"trace[1304314249] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"700.365633ms","start":"2026-07-30T18:59:21.268671Z","end":"2026-07-30T18:59:21.969037Z","steps":["trace[1304314249] 'process raft request' (duration: 599.253587ms)","trace[1304314249] 'compare' (duration: 100.361496ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:21.969069Z","caller":"traceutil/trace.go:171","msg":"trace[421658110] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"699.050438ms","start":"2026-07-30T18:59:21.269989Z","end":"2026-07-30T18:59:21.96904Z","steps":["trace[421658110] 'process raft request' (duration: 698.826802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:21.969179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.268653Z","time spent":"700.461516ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:21.969219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.269977Z","time spent":"699.162881ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:21.969308Z","caller":"traceutil/trace.go:171","msg":"trace[2094109713] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"366.229134ms","start":"2026-07-30T18:59:21.603056Z","end":"2026-07-30T18:59:21.969285Z","steps":["trace[2094109713] 'process raft request' (duration: 366.100141ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:21.969313Z","caller":"traceutil/trace.go:171","msg":"trace[1249165796] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"368.127095ms","start":"2026-07-30T18:59:21.601168Z","end":"2026-07-30T18:59:21.969295Z","steps":["trace[1249165796] 'process raft request' (duration: 367.759755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:21.969467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.603032Z","time spent":"366.362368ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:21.969488Z","caller":"traceutil/trace.go:171","msg":"trace[713034251] linearizableReadLoop","detail":"{readStateIndex:2537; appliedIndex:2536; }","duration":"700.217989ms","start":"2026-07-30T18:59:21.269259Z","end":"2026-07-30T18:59:21.969477Z","steps":["trace[713034251] 'read index received' (duration: 339.903044ms)","trace[713034251] 'applied index is now lower than readState.Index' (duration: 360.313955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:59:21.969493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.601141Z","time spent":"368.27276ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:21.96955Z","caller":"traceutil/trace.go:171","msg":"trace[1222600117] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"366.129392ms","start":"2026-07-30T18:59:21.603412Z","end":"2026-07-30T18:59:21.969542Z","steps":["trace[1222600117] 'process raft request' (duration: 365.835244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:21.969628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.603386Z","time spent":"366.193534ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:21.969698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.439756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-30T18:59:21.969744Z","caller":"traceutil/trace.go:171","msg":"trace[1006694356] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2450; }","duration":"700.501617ms","start":"2026-07-30T18:59:21.26923Z","end":"2026-07-30T18:59:21.969732Z","steps":["trace[1006694356] 'agreement among raft nodes before linearized reading' (duration: 700.349913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:21.96978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.269222Z","time spent":"700.551658ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T18:59:22.011788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.479ms","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-07-30T18:59:22.011818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.125829ms","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-07-30T18:59:22.01185Z","caller":"traceutil/trace.go:171","msg":"trace[947032539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2451; }","duration":"396.561332ms","start":"2026-07-30T18:59:21.615274Z","end":"2026-07-30T18:59:22.011836Z","steps":["trace[947032539] 'agreement among raft nodes before linearized reading' (duration: 396.416018ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.011868Z","caller":"traceutil/trace.go:171","msg":"trace[1242999388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2451; }","duration":"393.201372ms","start":"2026-07-30T18:59:21.618654Z","end":"2026-07-30T18:59:22.011855Z","steps":["trace[1242999388] 'agreement among raft nodes before linearized reading' (duration: 392.986076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:22.011886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.615265Z","time spent":"396.614814ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T18:59:22.011914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.618643Z","time spent":"393.262843ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T18:59:22.012057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.174631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T18:59:22.012065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.186949ms","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-07-30T18:59:22.012066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.589854ms","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-07-30T18:59:22.012093Z","caller":"traceutil/trace.go:171","msg":"trace[940063745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2451; }","duration":"393.224292ms","start":"2026-07-30T18:59:21.61886Z","end":"2026-07-30T18:59:22.012084Z","steps":["trace[940063745] 'agreement among raft nodes before linearized reading' (duration: 393.13654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:22.01214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.618853Z","time spent":"393.281063ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T18:59:22.012148Z","caller":"traceutil/trace.go:171","msg":"trace[1050951021] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2451; }","duration":"386.689426ms","start":"2026-07-30T18:59:21.625438Z","end":"2026-07-30T18:59:22.012127Z","steps":["trace[1050951021] 'agreement among raft nodes before linearized reading' (duration: 386.479001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:22.012156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.597279ms","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-07-30T18:59:22.012189Z","caller":"traceutil/trace.go:171","msg":"trace[672577665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2451; }","duration":"394.645031ms","start":"2026-07-30T18:59:21.617535Z","end":"2026-07-30T18:59:22.01218Z","steps":["trace[672577665] 'agreement among raft nodes before linearized reading' (duration: 394.553328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:22.012191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.62543Z","time spent":"386.751828ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-30T18:59:22.012182Z","caller":"traceutil/trace.go:171","msg":"trace[801868221] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"277.900073ms","start":"2026-07-30T18:59:21.734261Z","end":"2026-07-30T18:59:22.012161Z","steps":["trace[801868221] 'process raft request' (duration: 277.101692ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.012213Z","caller":"traceutil/trace.go:171","msg":"trace[1536008314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2451; }","duration":"397.453427ms","start":"2026-07-30T18:59:21.614651Z","end":"2026-07-30T18:59:22.012104Z","steps":["trace[1536008314] 'agreement among raft nodes before linearized reading' (duration: 397.177769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:22.012226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.617528Z","time spent":"394.691352ms","remote":"127.0.0.1:58480","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-07-30T18:59:22.011791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.670699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T18:59:22.01232Z","caller":"traceutil/trace.go:171","msg":"trace[1452738890] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2451; }","duration":"399.238104ms","start":"2026-07-30T18:59:21.613065Z","end":"2026-07-30T18:59:22.012303Z","steps":["trace[1452738890] 'agreement among raft nodes before linearized reading' (duration: 398.521285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:22.012337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.614635Z","time spent":"397.652872ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T18:59:22.01238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:21.613052Z","time spent":"399.312227ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T18:59:22.134412Z","caller":"traceutil/trace.go:171","msg":"trace[803463140] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"160.695683ms","start":"2026-07-30T18:59:21.973685Z","end":"2026-07-30T18:59:22.134381Z","steps":["trace[803463140] 'process raft request' (duration: 118.67578ms)","trace[803463140] 'compare' (duration: 41.901399ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:22.218531Z","caller":"traceutil/trace.go:171","msg":"trace[1394934043] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"202.008517ms","start":"2026-07-30T18:59:22.016489Z","end":"2026-07-30T18:59:22.218498Z","steps":["trace[1394934043] 'process raft request' (duration: 201.74445ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.218552Z","caller":"traceutil/trace.go:171","msg":"trace[2033096966] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"201.943215ms","start":"2026-07-30T18:59:22.016573Z","end":"2026-07-30T18:59:22.218517Z","steps":["trace[2033096966] 'process raft request' (duration: 201.813882ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.218559Z","caller":"traceutil/trace.go:171","msg":"trace[455447108] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"201.919284ms","start":"2026-07-30T18:59:22.016604Z","end":"2026-07-30T18:59:22.218523Z","steps":["trace[455447108] 'process raft request' (duration: 201.825492ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.218817Z","caller":"traceutil/trace.go:171","msg":"trace[1123924094] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"202.154991ms","start":"2026-07-30T18:59:22.016647Z","end":"2026-07-30T18:59:22.218802Z","steps":["trace[1123924094] 'process raft request' (duration: 201.832602ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.218978Z","caller":"traceutil/trace.go:171","msg":"trace[325958736] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"202.173601ms","start":"2026-07-30T18:59:22.016792Z","end":"2026-07-30T18:59:22.218966Z","steps":["trace[325958736] 'process raft request' (duration: 202.10699ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.21899Z","caller":"traceutil/trace.go:171","msg":"trace[1864333278] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"202.309315ms","start":"2026-07-30T18:59:22.016671Z","end":"2026-07-30T18:59:22.21898Z","steps":["trace[1864333278] 'process raft request' (duration: 202.042758ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:22.475693Z","caller":"traceutil/trace.go:171","msg":"trace[1883812820] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"126.188562ms","start":"2026-07-30T18:59:22.349467Z","end":"2026-07-30T18:59:22.475656Z","steps":["trace[1883812820] 'process raft request' (duration: 125.925145ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:24.658987Z","caller":"traceutil/trace.go:171","msg":"trace[1128690614] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"431.610938ms","start":"2026-07-30T18:59:24.22735Z","end":"2026-07-30T18:59:24.658961Z","steps":["trace[1128690614] 'process raft request' (duration: 431.563366ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:24.659019Z","caller":"traceutil/trace.go:171","msg":"trace[1062140036] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"431.647178ms","start":"2026-07-30T18:59:24.227346Z","end":"2026-07-30T18:59:24.658993Z","steps":["trace[1062140036] 'process raft request' (duration: 431.382561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:24.659174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.227325Z","time spent":"431.758931ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:24.659173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.227324Z","time spent":"431.739031ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:25.632313Z","caller":"traceutil/trace.go:171","msg":"trace[445674075] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"1.40491712s","start":"2026-07-30T18:59:24.22737Z","end":"2026-07-30T18:59:25.632287Z","steps":["trace[445674075] 'process raft request' (duration: 1.354920252s)","trace[445674075] 'compare' (duration: 49.541506ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:25.632343Z","caller":"traceutil/trace.go:171","msg":"trace[560151300] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"1.404077678s","start":"2026-07-30T18:59:24.228239Z","end":"2026-07-30T18:59:25.632317Z","steps":["trace[560151300] 'process raft request' (duration: 1.403961475s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:25.632453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.227353Z","time spent":"1.405028104s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:25.632562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.22822Z","time spent":"1.40418397s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:25.632577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347325077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12632"} {"level":"info","ts":"2026-07-30T18:59:25.632561Z","caller":"traceutil/trace.go:171","msg":"trace[1190734576] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"1.404294324s","start":"2026-07-30T18:59:24.228239Z","end":"2026-07-30T18:59:25.632534Z","steps":["trace[1190734576] 'process raft request' (duration: 1.404014096s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:25.632625Z","caller":"traceutil/trace.go:171","msg":"trace[1374822235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2470; }","duration":"1.34740109s","start":"2026-07-30T18:59:24.285212Z","end":"2026-07-30T18:59:25.632613Z","steps":["trace[1374822235] 'agreement among raft nodes before linearized reading' (duration: 1.347144923s)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:25.632311Z","caller":"traceutil/trace.go:171","msg":"trace[1502342460] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2556; }","duration":"1.34703888s","start":"2026-07-30T18:59:24.285251Z","end":"2026-07-30T18:59:25.63229Z","steps":["trace[1502342460] 'read index received' (duration: 373.540582ms)","trace[1502342460] 'applied index is now lower than readState.Index' (duration: 973.497188ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:59:25.632666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.2852Z","time spent":"1.347458521s","remote":"127.0.0.1:58376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12654,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-30T18:59:25.632694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.228224Z","time spent":"1.404405266s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:25.632778Z","caller":"traceutil/trace.go:171","msg":"trace[1002120713] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"1.40452002s","start":"2026-07-30T18:59:24.228246Z","end":"2026-07-30T18:59:25.632766Z","steps":["trace[1002120713] 'process raft request' (duration: 1.403892943s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:25.632845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.228236Z","time spent":"1.404576201s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:25.637529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195090433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:59:25.637599Z","caller":"traceutil/trace.go:171","msg":"trace[1705020975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2472; }","duration":"1.195200056s","start":"2026-07-30T18:59:24.442381Z","end":"2026-07-30T18:59:25.637581Z","steps":["trace[1705020975] 'agreement among raft nodes before linearized reading' (duration: 1.195076872s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:25.637634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.442358Z","time spent":"1.195270048s","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T18:59:25.637682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.065151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T18:59:25.63776Z","caller":"traceutil/trace.go:171","msg":"trace[1218942515] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:2472; }","duration":"807.186994ms","start":"2026-07-30T18:59:24.830555Z","end":"2026-07-30T18:59:25.637742Z","steps":["trace[1218942515] 'agreement among raft nodes before linearized reading' (duration: 806.934817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:25.637758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.785783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-30T18:59:25.637803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.830542Z","time spent":"807.252215ms","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"info","ts":"2026-07-30T18:59:25.637845Z","caller":"traceutil/trace.go:171","msg":"trace[1816843262] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2472; }","duration":"602.908686ms","start":"2026-07-30T18:59:25.034915Z","end":"2026-07-30T18:59:25.637824Z","steps":["trace[1816843262] 'agreement among raft nodes before linearized reading' (duration: 602.624649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:25.637899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:25.034901Z","time spent":"602.989228ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T18:59:25.637904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184043084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-07-30T18:59:25.637764Z","caller":"traceutil/trace.go:171","msg":"trace[2014272347] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"834.940832ms","start":"2026-07-30T18:59:24.802801Z","end":"2026-07-30T18:59:25.637742Z","steps":["trace[2014272347] 'process raft request' (duration: 834.442439ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:25.637943Z","caller":"traceutil/trace.go:171","msg":"trace[968320852] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2472; }","duration":"1.184123647s","start":"2026-07-30T18:59:24.453808Z","end":"2026-07-30T18:59:25.637931Z","steps":["trace[968320852] 'agreement among raft nodes before linearized reading' (duration: 1.183996364s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:25.63797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.453789Z","time spent":"1.184174668s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2008,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-07-30T18:59:25.638009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:24.802781Z","time spent":"835.174799ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:25.63825Z","caller":"traceutil/trace.go:171","msg":"trace[1149461362] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"607.929571ms","start":"2026-07-30T18:59:25.029548Z","end":"2026-07-30T18:59:25.637478Z","steps":["trace[1149461362] 'process raft request' (duration: 607.859489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:25.638445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:25.029526Z","time spent":"608.858036ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T18:59:26.304943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.267054ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-30T18:59:26.305088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:25.63891Z","time spent":"666.169041ms","remote":"127.0.0.1:58266","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-30T18:59:26.305316Z","caller":"traceutil/trace.go:171","msg":"trace[1119475581] linearizableReadLoop","detail":"{readStateIndex:2565; appliedIndex:2564; }","duration":"660.95693ms","start":"2026-07-30T18:59:25.644318Z","end":"2026-07-30T18:59:26.305275Z","steps":["trace[1119475581] 'read index received' (duration: 385.603407ms)","trace[1119475581] 'applied index is now lower than readState.Index' (duration: 275.348673ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:26.305514Z","caller":"traceutil/trace.go:171","msg":"trace[804659217] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"316.367279ms","start":"2026-07-30T18:59:25.989128Z","end":"2026-07-30T18:59:26.305495Z","steps":["trace[804659217] 'process raft request' (duration: 315.925167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:26.305584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.249948ms","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-07-30T18:59:26.305644Z","caller":"traceutil/trace.go:171","msg":"trace[1772889258] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2473; }","duration":"661.34056ms","start":"2026-07-30T18:59:25.64429Z","end":"2026-07-30T18:59:26.305631Z","steps":["trace[1772889258] 'agreement among raft nodes before linearized reading' (duration: 661.169355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:26.305686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:25.644277Z","time spent":"661.400872ms","remote":"127.0.0.1:58632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-30T18:59:26.305775Z","caller":"traceutil/trace.go:171","msg":"trace[1340584280] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"295.096026ms","start":"2026-07-30T18:59:26.010663Z","end":"2026-07-30T18:59:26.305759Z","steps":["trace[1340584280] 'process raft request' (duration: 294.790037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:26.305748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:25.98909Z","time spent":"316.471572ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:59:26.306019Z","caller":"traceutil/trace.go:171","msg":"trace[677558278] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"294.603662ms","start":"2026-07-30T18:59:26.011395Z","end":"2026-07-30T18:59:26.305999Z","steps":["trace[677558278] 'process raft request' (duration: 294.269733ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:26.306172Z","caller":"traceutil/trace.go:171","msg":"trace[937892236] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"294.198941ms","start":"2026-07-30T18:59:26.011948Z","end":"2026-07-30T18:59:26.306147Z","steps":["trace[937892236] 'process raft request' (duration: 293.980415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:26.306506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.825663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:59:26.306554Z","caller":"traceutil/trace.go:171","msg":"trace[147145481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2476; }","duration":"661.910946ms","start":"2026-07-30T18:59:25.644631Z","end":"2026-07-30T18:59:26.306542Z","steps":["trace[147145481] 'agreement among raft nodes before linearized reading' (duration: 661.451403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:26.306873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:59:25.644616Z","time spent":"661.963367ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T18:59:26.748376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.145647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-30T18:59:26.748466Z","caller":"traceutil/trace.go:171","msg":"trace[1592662609] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2477; }","duration":"134.28328ms","start":"2026-07-30T18:59:26.614159Z","end":"2026-07-30T18:59:26.748442Z","steps":["trace[1592662609] 'range keys from in-memory index tree' (duration: 133.950992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:26.74879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.700172ms","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-07-30T18:59:26.748893Z","caller":"traceutil/trace.go:171","msg":"trace[461257491] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2477; }","duration":"101.811785ms","start":"2026-07-30T18:59:26.647056Z","end":"2026-07-30T18:59:26.748868Z","steps":["trace[461257491] 'range keys from in-memory index tree' (duration: 101.320261ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:00:56.241478Z","caller":"traceutil/trace.go:171","msg":"trace[1505439449] transaction","detail":"{read_only:false; response_revision:3114; number_of_response:1; }","duration":"555.055394ms","start":"2026-07-30T19:00:55.686399Z","end":"2026-07-30T19:00:56.241455Z","steps":["trace[1505439449] 'process raft request' (duration: 554.853858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:00:56.241627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:00:55.686377Z","time spent":"555.179286ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:00:56.242354Z","caller":"traceutil/trace.go:171","msg":"trace[1063513262] linearizableReadLoop","detail":"{readStateIndex:3228; appliedIndex:3226; }","duration":"166.404638ms","start":"2026-07-30T19:00:56.075932Z","end":"2026-07-30T19:00:56.242337Z","steps":["trace[1063513262] 'read index received' (duration: 165.36337ms)","trace[1063513262] 'applied index is now lower than readState.Index' (duration: 1.040438ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:00:56.242452Z","caller":"traceutil/trace.go:171","msg":"trace[1902386099] transaction","detail":"{read_only:false; response_revision:3115; number_of_response:1; }","duration":"521.981041ms","start":"2026-07-30T19:00:55.720457Z","end":"2026-07-30T19:00:56.242438Z","steps":["trace[1902386099] 'process raft request' (duration: 521.771715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:00:56.242538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.604164ms","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-07-30T19:00:56.24257Z","caller":"traceutil/trace.go:171","msg":"trace[1782984331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3115; }","duration":"166.645175ms","start":"2026-07-30T19:00:56.075917Z","end":"2026-07-30T19:00:56.242562Z","steps":["trace[1782984331] 'agreement among raft nodes before linearized reading' (duration: 166.520132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:00:56.242566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:00:55.720425Z","time spent":"522.064103ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:00:56.242641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.757031ms","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-07-30T19:00:56.242675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.267308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-07-30T19:00:56.242696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.673168ms","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-07-30T19:00:56.242743Z","caller":"traceutil/trace.go:171","msg":"trace[1341796685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3115; }","duration":"165.861214ms","start":"2026-07-30T19:00:56.076859Z","end":"2026-07-30T19:00:56.24272Z","steps":["trace[1341796685] 'agreement among raft nodes before linearized reading' (duration: 165.653738ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:00:56.242751Z","caller":"traceutil/trace.go:171","msg":"trace[1800377293] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:3115; }","duration":"165.370971ms","start":"2026-07-30T19:00:56.077361Z","end":"2026-07-30T19:00:56.242731Z","steps":["trace[1800377293] 'agreement among raft nodes before linearized reading' (duration: 165.173906ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:00:56.242761Z","caller":"traceutil/trace.go:171","msg":"trace[692800210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3115; }","duration":"162.76065ms","start":"2026-07-30T19:00:56.079987Z","end":"2026-07-30T19:00:56.242748Z","steps":["trace[692800210] 'agreement among raft nodes before linearized reading' (duration: 162.561565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:01:29.766796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.38164ms","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-07-30T19:01:29.766893Z","caller":"traceutil/trace.go:171","msg":"trace[396686251] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3346; }","duration":"139.539474ms","start":"2026-07-30T19:01:29.627333Z","end":"2026-07-30T19:01:29.766872Z","steps":["trace[396686251] 'range keys from in-memory index tree' (duration: 139.143903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:01:29.767031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.127943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:01:29.767177Z","caller":"traceutil/trace.go:171","msg":"trace[987526031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3346; }","duration":"324.347009ms","start":"2026-07-30T19:01:29.442801Z","end":"2026-07-30T19:01:29.767148Z","steps":["trace[987526031] 'range keys from in-memory index tree' (duration: 324.01589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:01:29.767249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:01:29.442778Z","time spent":"324.459923ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:01:30.288854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.957504ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-30T19:01:30.546071Z","caller":"traceutil/trace.go:171","msg":"trace[1845137579] transaction","detail":"{read_only:false; response_revision:3347; number_of_response:1; }","duration":"128.916848ms","start":"2026-07-30T19:01:30.417129Z","end":"2026-07-30T19:01:30.546046Z","steps":["trace[1845137579] 'process raft request' (duration: 128.753833ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:01:30.612044Z","caller":"traceutil/trace.go:171","msg":"trace[703606621] linearizableReadLoop","detail":"{readStateIndex:3468; appliedIndex:3467; }","duration":"191.905217ms","start":"2026-07-30T19:01:30.420112Z","end":"2026-07-30T19:01:30.612017Z","steps":["trace[703606621] 'read index received' (duration: 125.933858ms)","trace[703606621] 'applied index is now lower than readState.Index' (duration: 65.970059ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:01:30.612306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.167614ms","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-07-30T19:01:30.612379Z","caller":"traceutil/trace.go:171","msg":"trace[930845196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3348; }","duration":"192.267907ms","start":"2026-07-30T19:01:30.420098Z","end":"2026-07-30T19:01:30.612366Z","steps":["trace[930845196] 'agreement among raft nodes before linearized reading' (duration: 192.044571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:01:30.61237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.913987ms","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-07-30T19:01:30.612431Z","caller":"traceutil/trace.go:171","msg":"trace[13912273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3348; }","duration":"192.02396ms","start":"2026-07-30T19:01:30.420395Z","end":"2026-07-30T19:01:30.612419Z","steps":["trace[13912273] 'agreement among raft nodes before linearized reading' (duration: 191.775113ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:01:30.612163Z","caller":"traceutil/trace.go:171","msg":"trace[547064166] transaction","detail":"{read_only:false; response_revision:3348; number_of_response:1; }","duration":"194.97349ms","start":"2026-07-30T19:01:30.417153Z","end":"2026-07-30T19:01:30.612126Z","steps":["trace[547064166] 'process raft request' (duration: 194.692683ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:05:41.898646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2973} {"level":"info","ts":"2026-07-30T19:05:41.997746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2973,"took":"97.791193ms","hash":2640146809,"current-db-size-bytes":35717120,"current-db-size":"36 MB","current-db-size-in-use-bytes":10653696,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-30T19:05:41.997802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2640146809,"revision":2973,"compact-revision":-1} {"level":"warn","ts":"2026-07-30T19:07:12.88296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639764144,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:07:12.962306Z","caller":"traceutil/trace.go:171","msg":"trace[259306031] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"986.845827ms","start":"2026-07-30T19:07:11.975424Z","end":"2026-07-30T19:07:12.962269Z","steps":["trace[259306031] 'process raft request' (duration: 983.478557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:12.962484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:11.975394Z","time spent":"987.007452ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:12.962724Z","caller":"traceutil/trace.go:171","msg":"trace[661474185] linearizableReadLoop","detail":"{readStateIndex:5726; appliedIndex:5722; }","duration":"580.623822ms","start":"2026-07-30T19:07:12.382083Z","end":"2026-07-30T19:07:12.962706Z","steps":["trace[661474185] 'read index received' (duration: 576.880861ms)","trace[661474185] 'applied index is now lower than readState.Index' (duration: 3.741541ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:07:12.963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.92737ms","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-07-30T19:07:12.963055Z","caller":"traceutil/trace.go:171","msg":"trace[1056790563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5535; }","duration":"581.050394ms","start":"2026-07-30T19:07:12.381989Z","end":"2026-07-30T19:07:12.963039Z","steps":["trace[1056790563] 'agreement among raft nodes before linearized reading' (duration: 580.90743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:12.963025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.817461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:07:12.963091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:12.381951Z","time spent":"581.132606ms","remote":"127.0.0.1:58480","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-07-30T19:07:12.963128Z","caller":"traceutil/trace.go:171","msg":"trace[1749694244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5535; }","duration":"519.978475ms","start":"2026-07-30T19:07:12.44312Z","end":"2026-07-30T19:07:12.963099Z","steps":["trace[1749694244] 'agreement among raft nodes before linearized reading' (duration: 519.78647ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:12.963145Z","caller":"traceutil/trace.go:171","msg":"trace[605898888] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"985.326187ms","start":"2026-07-30T19:07:11.977784Z","end":"2026-07-30T19:07:12.96311Z","steps":["trace[605898888] 'process raft request' (duration: 984.852074ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:12.963235Z","caller":"traceutil/trace.go:171","msg":"trace[1543300763] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"982.47867ms","start":"2026-07-30T19:07:11.98073Z","end":"2026-07-30T19:07:12.963208Z","steps":["trace[1543300763] 'process raft request' (duration: 981.941646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:12.963329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:11.977741Z","time spent":"985.490851ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:07:12.963354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.788946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-30T19:07:12.963368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:11.980715Z","time spent":"982.573042ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:12.963413Z","caller":"traceutil/trace.go:171","msg":"trace[416546543] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:5535; }","duration":"203.902349ms","start":"2026-07-30T19:07:12.759501Z","end":"2026-07-30T19:07:12.963403Z","steps":["trace[416546543] 'agreement among raft nodes before linearized reading' (duration: 203.716554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:12.963193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:12.443095Z","time spent":"520.089279ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:07:12.963945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.020133ms","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-07-30T19:07:12.963947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.439611ms","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-07-30T19:07:12.964008Z","caller":"traceutil/trace.go:171","msg":"trace[1810441065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5535; }","duration":"579.557213ms","start":"2026-07-30T19:07:12.384436Z","end":"2026-07-30T19:07:12.963993Z","steps":["trace[1810441065] 'agreement among raft nodes before linearized reading' (duration: 578.398782ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:12.962997Z","caller":"traceutil/trace.go:171","msg":"trace[1789636505] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"986.56783ms","start":"2026-07-30T19:07:11.976361Z","end":"2026-07-30T19:07:12.962929Z","steps":["trace[1789636505] 'process raft request' (duration: 986.20291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:12.964045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:12.384411Z","time spent":"579.625796ms","remote":"127.0.0.1:58480","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-07-30T19:07:12.964098Z","caller":"traceutil/trace.go:171","msg":"trace[1844386960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5535; }","duration":"578.096004ms","start":"2026-07-30T19:07:12.38589Z","end":"2026-07-30T19:07:12.963986Z","steps":["trace[1844386960] 'agreement among raft nodes before linearized reading' (duration: 577.030936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:12.964119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.800683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-30T19:07:12.964138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:12.385879Z","time spent":"578.248799ms","remote":"127.0.0.1:58480","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-07-30T19:07:12.964184Z","caller":"traceutil/trace.go:171","msg":"trace[551617089] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:5535; }","duration":"578.901016ms","start":"2026-07-30T19:07:12.385265Z","end":"2026-07-30T19:07:12.964166Z","steps":["trace[551617089] 'agreement among raft nodes before linearized reading' (duration: 577.749295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:12.964126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:11.976322Z","time spent":"987.748711ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:07:12.964216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:12.385243Z","time spent":"578.966098ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T19:07:19.524511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.506158744s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T19:07:19.536423Z","caller":"traceutil/trace.go:171","msg":"trace[624429216] linearizableReadLoop","detail":"{readStateIndex:5759; appliedIndex:5757; }","duration":"1.520288573s","start":"2026-07-30T19:07:18.016108Z","end":"2026-07-30T19:07:19.536397Z","steps":["trace[624429216] 'read index received' (duration: 2.245241ms)","trace[624429216] 'applied index is now lower than readState.Index' (duration: 1.518041812s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:07:19.536657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52052504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-07-30T19:07:19.536631Z","caller":"traceutil/trace.go:171","msg":"trace[197035760] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"1.521922727s","start":"2026-07-30T19:07:18.01468Z","end":"2026-07-30T19:07:19.536602Z","steps":["trace[197035760] 'process raft request' (duration: 1.51010612s)","trace[197035760] 'compare' (duration: 10.739688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:07:19.536839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.01467Z","time spent":"1.522080491s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:19.53669Z","caller":"traceutil/trace.go:171","msg":"trace[1366446717] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:5568; }","duration":"1.520582921s","start":"2026-07-30T19:07:18.016097Z","end":"2026-07-30T19:07:19.53668Z","steps":["trace[1366446717] 'agreement among raft nodes before linearized reading' (duration: 1.520423717s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.536986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.016092Z","time spent":"1.520863588s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1339,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-07-30T19:07:19.5372Z","caller":"traceutil/trace.go:171","msg":"trace[938381427] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"1.519386098s","start":"2026-07-30T19:07:18.017193Z","end":"2026-07-30T19:07:19.536579Z","steps":["trace[938381427] 'process raft request' (duration: 1.519111331s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.537397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.017187Z","time spent":"1.520089418s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:19.538495Z","caller":"traceutil/trace.go:171","msg":"trace[1708915313] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"558.059047ms","start":"2026-07-30T19:07:18.980384Z","end":"2026-07-30T19:07:19.538443Z","steps":["trace[1708915313] 'process raft request' (duration: 557.870942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.538591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451027795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-30T19:07:19.538622Z","caller":"traceutil/trace.go:171","msg":"trace[715669195] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"1.460412177s","start":"2026-07-30T19:07:18.078195Z","end":"2026-07-30T19:07:19.538608Z","steps":["trace[715669195] 'process raft request' (duration: 1.459938424s)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:19.538655Z","caller":"traceutil/trace.go:171","msg":"trace[1565706402] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:5574; }","duration":"1.451126737s","start":"2026-07-30T19:07:18.087508Z","end":"2026-07-30T19:07:19.538635Z","steps":["trace[1565706402] 'agreement among raft nodes before linearized reading' (duration: 1.450916242s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.538726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.980365Z","time spent":"558.211261ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:07:19.53874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.087491Z","time spent":"1.451238841s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:07:19.538757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.078172Z","time spent":"1.460480249s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:19.538863Z","caller":"traceutil/trace.go:171","msg":"trace[153413222] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"557.312447ms","start":"2026-07-30T19:07:18.981539Z","end":"2026-07-30T19:07:19.538852Z","steps":["trace[153413222] 'process raft request' (duration: 556.751762ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:19.538936Z","caller":"traceutil/trace.go:171","msg":"trace[163388421] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"555.729124ms","start":"2026-07-30T19:07:18.983195Z","end":"2026-07-30T19:07:19.538924Z","steps":["trace[163388421] 'process raft request' (duration: 555.124338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.53897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.391093ms","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-07-30T19:07:19.538996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.510316ms","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-07-30T19:07:19.539012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.983183Z","time spent":"555.787316ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:19.539026Z","caller":"traceutil/trace.go:171","msg":"trace[1326319229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5574; }","duration":"537.581568ms","start":"2026-07-30T19:07:19.001435Z","end":"2026-07-30T19:07:19.539017Z","steps":["trace[1326319229] 'agreement among raft nodes before linearized reading' (duration: 537.530176ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:19.539019Z","caller":"traceutil/trace.go:171","msg":"trace[944257309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5574; }","duration":"537.457414ms","start":"2026-07-30T19:07:19.001545Z","end":"2026-07-30T19:07:19.539003Z","steps":["trace[944257309] 'agreement among raft nodes before linearized reading' (duration: 537.348311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:19.001506Z","time spent":"537.557017ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T19:07:19.539075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:19.001408Z","time spent":"537.64502ms","remote":"127.0.0.1:58480","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-07-30T19:07:19.539118Z","caller":"traceutil/trace.go:171","msg":"trace[2106757758] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"555.526949ms","start":"2026-07-30T19:07:18.983581Z","end":"2026-07-30T19:07:19.539108Z","steps":["trace[2106757758] 'process raft request' (duration: 554.770539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097088143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:07:19.539205Z","caller":"traceutil/trace.go:171","msg":"trace[1770420611] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"409.062001ms","start":"2026-07-30T19:07:19.130134Z","end":"2026-07-30T19:07:19.539196Z","steps":["trace[1770420611] 'process raft request' (duration: 408.24754ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:19.539211Z","caller":"traceutil/trace.go:171","msg":"trace[1910446406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5574; }","duration":"1.097148535s","start":"2026-07-30T19:07:18.442054Z","end":"2026-07-30T19:07:19.539203Z","steps":["trace[1910446406] 'agreement among raft nodes before linearized reading' (duration: 1.097092253s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348991099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-30T19:07:19.53924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.442038Z","time spent":"1.097197305s","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:07:19.539278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:19.130106Z","time spent":"409.120822ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:07:19.539292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214360848s","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-07-30T19:07:19.539326Z","caller":"traceutil/trace.go:171","msg":"trace[1997650440] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:5574; }","duration":"1.349127652s","start":"2026-07-30T19:07:18.190179Z","end":"2026-07-30T19:07:19.539307Z","steps":["trace[1997650440] 'agreement among raft nodes before linearized reading' (duration: 1.348973768s)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:19.539378Z","caller":"traceutil/trace.go:171","msg":"trace[957741394] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5574; }","duration":"1.214541643s","start":"2026-07-30T19:07:18.324823Z","end":"2026-07-30T19:07:19.539365Z","steps":["trace[957741394] 'agreement among raft nodes before linearized reading' (duration: 1.214362198s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.284413ms","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-07-30T19:07:19.539387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.190163Z","time spent":"1.349212054s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:07:19.538942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.981527Z","time spent":"557.358678ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:19.539419Z","caller":"traceutil/trace.go:171","msg":"trace[1258751938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5574; }","duration":"404.342754ms","start":"2026-07-30T19:07:19.135067Z","end":"2026-07-30T19:07:19.53941Z","steps":["trace[1258751938] 'agreement among raft nodes before linearized reading' (duration: 404.282763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358999477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T19:07:19.539455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:19.135052Z","time spent":"404.396646ms","remote":"127.0.0.1:58480","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-07-30T19:07:19.539457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.324803Z","time spent":"1.214603744s","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:07:19.539476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.076021ms","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-07-30T19:07:19.539487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.195655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:07:19.539499Z","caller":"traceutil/trace.go:171","msg":"trace[174968433] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5574; }","duration":"1.359159741s","start":"2026-07-30T19:07:18.180323Z","end":"2026-07-30T19:07:19.539483Z","steps":["trace[174968433] 'agreement among raft nodes before linearized reading' (duration: 1.359009027s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.540067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.180299Z","time spent":"1.359582103s","remote":"127.0.0.1:52028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:07:19.539422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.184912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:07:19.54033Z","caller":"traceutil/trace.go:171","msg":"trace[91330422] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:5574; }","duration":"329.153638ms","start":"2026-07-30T19:07:19.211155Z","end":"2026-07-30T19:07:19.540309Z","steps":["trace[91330422] 'agreement among raft nodes before linearized reading' (duration: 328.157772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.540388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:19.211141Z","time spent":"329.22669ms","remote":"127.0.0.1:37358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:07:19.539168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.983567Z","time spent":"555.57362ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:07:19.539513Z","caller":"traceutil/trace.go:171","msg":"trace[1987748587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5574; }","duration":"538.190814ms","start":"2026-07-30T19:07:19.001313Z","end":"2026-07-30T19:07:19.539504Z","steps":["trace[1987748587] 'agreement among raft nodes before linearized reading' (duration: 538.121702ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:07:19.53954Z","caller":"traceutil/trace.go:171","msg":"trace[1199026218] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5574; }","duration":"170.244426ms","start":"2026-07-30T19:07:19.369277Z","end":"2026-07-30T19:07:19.539522Z","steps":["trace[1199026218] 'agreement among raft nodes before linearized reading' (duration: 170.177924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.532483ms","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-07-30T19:07:19.541214Z","caller":"traceutil/trace.go:171","msg":"trace[1249582709] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5574; }","duration":"578.156226ms","start":"2026-07-30T19:07:18.963007Z","end":"2026-07-30T19:07:19.541164Z","steps":["trace[1249582709] 'agreement among raft nodes before linearized reading' (duration: 576.515552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.696441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-30T19:07:19.5413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.962936Z","time spent":"578.323291ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:07:19.541393Z","caller":"traceutil/trace.go:171","msg":"trace[252142322] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:5574; }","duration":"542.540801ms","start":"2026-07-30T19:07:18.998831Z","end":"2026-07-30T19:07:19.541372Z","steps":["trace[252142322] 'agreement among raft nodes before linearized reading' (duration: 540.697881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.539628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.683313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12462"} {"level":"warn","ts":"2026-07-30T19:07:19.541464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:18.998765Z","time spent":"542.681235ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T19:07:19.54151Z","caller":"traceutil/trace.go:171","msg":"trace[1833107798] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5574; }","duration":"199.568453ms","start":"2026-07-30T19:07:19.341907Z","end":"2026-07-30T19:07:19.541476Z","steps":["trace[1833107798] 'agreement among raft nodes before linearized reading' (duration: 197.60247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:07:19.541535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:07:19.001284Z","time spent":"539.654543ms","remote":"127.0.0.1:58480","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-07-30T19:08:02.044478Z","caller":"traceutil/trace.go:171","msg":"trace[1768148918] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"336.879974ms","start":"2026-07-30T19:08:01.707553Z","end":"2026-07-30T19:08:02.044433Z","steps":["trace[1768148918] 'process raft request' (duration: 336.604207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:08:02.044756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:08:01.707533Z","time spent":"337.08234ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:08:02.047565Z","caller":"traceutil/trace.go:171","msg":"trace[517197341] transaction","detail":"{read_only:false; response_revision:5840; number_of_response:1; }","duration":"333.549256ms","start":"2026-07-30T19:08:01.713951Z","end":"2026-07-30T19:08:02.0475Z","steps":["trace[517197341] 'process raft request' (duration: 333.456733ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:08:02.047598Z","caller":"traceutil/trace.go:171","msg":"trace[1673874089] transaction","detail":"{read_only:false; response_revision:5839; number_of_response:1; }","duration":"336.33561ms","start":"2026-07-30T19:08:01.711177Z","end":"2026-07-30T19:08:02.047512Z","steps":["trace[1673874089] 'process raft request' (duration: 336.060443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:08:02.047762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:08:01.713933Z","time spent":"333.71779ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:08:02.047768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:08:01.711152Z","time spent":"336.522495ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:08:02.047963Z","caller":"traceutil/trace.go:171","msg":"trace[2080300445] transaction","detail":"{read_only:false; response_revision:5841; number_of_response:1; }","duration":"323.599078ms","start":"2026-07-30T19:08:01.724331Z","end":"2026-07-30T19:08:02.04793Z","steps":["trace[2080300445] 'process raft request' (duration: 323.122545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:08:02.048127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:08:01.724317Z","time spent":"323.723122ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:08:02.048122Z","caller":"traceutil/trace.go:171","msg":"trace[160821099] transaction","detail":"{read_only:false; response_revision:5842; number_of_response:1; }","duration":"226.612838ms","start":"2026-07-30T19:08:01.821488Z","end":"2026-07-30T19:08:02.048101Z","steps":["trace[160821099] 'process raft request' (duration: 226.31778ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:08:02.048085Z","caller":"traceutil/trace.go:171","msg":"trace[1563687999] linearizableReadLoop","detail":"{readStateIndex:6042; appliedIndex:6038; }","duration":"286.637757ms","start":"2026-07-30T19:08:01.761408Z","end":"2026-07-30T19:08:02.048046Z","steps":["trace[1563687999] 'read index received' (duration: 282.695771ms)","trace[1563687999] 'applied index is now lower than readState.Index' (duration: 3.940676ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:08:02.048551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.44796ms","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-07-30T19:08:02.048586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.334261ms","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-07-30T19:08:02.048604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.495933ms","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-07-30T19:08:02.048618Z","caller":"traceutil/trace.go:171","msg":"trace[66246598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5842; }","duration":"274.582474ms","start":"2026-07-30T19:08:01.774018Z","end":"2026-07-30T19:08:02.048601Z","steps":["trace[66246598] 'agreement among raft nodes before linearized reading' (duration: 274.403389ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:08:02.048659Z","caller":"traceutil/trace.go:171","msg":"trace[666014210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5842; }","duration":"279.465285ms","start":"2026-07-30T19:08:01.769174Z","end":"2026-07-30T19:08:02.048639Z","steps":["trace[666014210] 'agreement among raft nodes before linearized reading' (duration: 279.236239ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:08:02.048669Z","caller":"traceutil/trace.go:171","msg":"trace[881871612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5842; }","duration":"277.636536ms","start":"2026-07-30T19:08:01.771014Z","end":"2026-07-30T19:08:02.048651Z","steps":["trace[881871612] 'agreement among raft nodes before linearized reading' (duration: 277.457852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:08:02.048671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.666921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-30T19:08:02.048763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.365176ms","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-07-30T19:08:02.048763Z","caller":"traceutil/trace.go:171","msg":"trace[468224618] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:5842; }","duration":"263.791665ms","start":"2026-07-30T19:08:01.78495Z","end":"2026-07-30T19:08:02.048741Z","steps":["trace[468224618] 'agreement among raft nodes before linearized reading' (duration: 263.508607ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:08:02.048801Z","caller":"traceutil/trace.go:171","msg":"trace[456941347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5842; }","duration":"287.433538ms","start":"2026-07-30T19:08:01.761357Z","end":"2026-07-30T19:08:02.04879Z","steps":["trace[456941347] 'agreement among raft nodes before linearized reading' (duration: 286.862063ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:10:41.905059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4964} {"level":"info","ts":"2026-07-30T19:10:41.949276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4964,"took":"42.630903ms","hash":1608411763,"current-db-size-bytes":35717120,"current-db-size":"36 MB","current-db-size-in-use-bytes":11223040,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-30T19:10:41.949459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1608411763,"revision":4964,"compact-revision":2973} {"level":"info","ts":"2026-07-30T19:11:39.189248Z","caller":"traceutil/trace.go:171","msg":"trace[1531997591] transaction","detail":"{read_only:false; response_revision:7629; number_of_response:1; }","duration":"223.159109ms","start":"2026-07-30T19:11:38.966067Z","end":"2026-07-30T19:11:39.189226Z","steps":["trace[1531997591] 'process raft request' (duration: 222.933883ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:39.189874Z","caller":"traceutil/trace.go:171","msg":"trace[853819366] transaction","detail":"{read_only:false; response_revision:7630; number_of_response:1; }","duration":"222.169963ms","start":"2026-07-30T19:11:38.967662Z","end":"2026-07-30T19:11:39.189832Z","steps":["trace[853819366] 'process raft request' (duration: 221.466234ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:39.447044Z","caller":"traceutil/trace.go:171","msg":"trace[2009905711] transaction","detail":"{read_only:false; response_revision:7634; number_of_response:1; }","duration":"129.601538ms","start":"2026-07-30T19:11:39.317409Z","end":"2026-07-30T19:11:39.447011Z","steps":["trace[2009905711] 'process raft request' (duration: 127.313906ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:39.447224Z","caller":"traceutil/trace.go:171","msg":"trace[1008886661] transaction","detail":"{read_only:false; response_revision:7635; number_of_response:1; }","duration":"129.570556ms","start":"2026-07-30T19:11:39.317614Z","end":"2026-07-30T19:11:39.447185Z","steps":["trace[1008886661] 'process raft request' (duration: 129.33475ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:39.683676Z","caller":"traceutil/trace.go:171","msg":"trace[491456714] transaction","detail":"{read_only:false; response_revision:7637; number_of_response:1; }","duration":"145.491167ms","start":"2026-07-30T19:11:39.538143Z","end":"2026-07-30T19:11:39.683634Z","steps":["trace[491456714] 'process raft request' (duration: 145.253261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:11:40.562558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.275419ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-30T19:11:40.562692Z","caller":"traceutil/trace.go:171","msg":"trace[734541895] linearizableReadLoop","detail":"{readStateIndex:7895; appliedIndex:7894; }","duration":"120.719437ms","start":"2026-07-30T19:11:40.44195Z","end":"2026-07-30T19:11:40.56267Z","steps":["trace[734541895] 'read index received' (duration: 120.273µs)","trace[734541895] 'applied index is now lower than readState.Index' (duration: 120.596954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:11:40.562794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.84239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:11:40.562835Z","caller":"traceutil/trace.go:171","msg":"trace[1907302352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7641; }","duration":"120.949013ms","start":"2026-07-30T19:11:40.441874Z","end":"2026-07-30T19:11:40.562823Z","steps":["trace[1907302352] 'agreement among raft nodes before linearized reading' (duration: 120.85147ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:40.855747Z","caller":"traceutil/trace.go:171","msg":"trace[912500102] transaction","detail":"{read_only:false; response_revision:7643; number_of_response:1; }","duration":"113.352558ms","start":"2026-07-30T19:11:40.742355Z","end":"2026-07-30T19:11:40.855708Z","steps":["trace[912500102] 'process raft request' (duration: 104.315174ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:41.582714Z","caller":"traceutil/trace.go:171","msg":"trace[158677162] transaction","detail":"{read_only:false; response_revision:7661; number_of_response:1; }","duration":"122.328361ms","start":"2026-07-30T19:11:41.460357Z","end":"2026-07-30T19:11:41.582685Z","steps":["trace[158677162] 'process raft request' (duration: 122.086524ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:41.628101Z","caller":"traceutil/trace.go:171","msg":"trace[1703258080] transaction","detail":"{read_only:false; response_revision:7662; number_of_response:1; }","duration":"166.964378ms","start":"2026-07-30T19:11:41.461113Z","end":"2026-07-30T19:11:41.628078Z","steps":["trace[1703258080] 'process raft request' (duration: 166.756853ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:41.628232Z","caller":"traceutil/trace.go:171","msg":"trace[313581776] linearizableReadLoop","detail":"{readStateIndex:7916; appliedIndex:7914; }","duration":"105.840735ms","start":"2026-07-30T19:11:41.522368Z","end":"2026-07-30T19:11:41.628208Z","steps":["trace[313581776] 'read index received' (duration: 60.121408ms)","trace[313581776] 'applied index is now lower than readState.Index' (duration: 45.717967ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:11:41.628438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.05847ms","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-07-30T19:11:41.628481Z","caller":"traceutil/trace.go:171","msg":"trace[1773327079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7663; }","duration":"106.173684ms","start":"2026-07-30T19:11:41.522296Z","end":"2026-07-30T19:11:41.628469Z","steps":["trace[1773327079] 'agreement among raft nodes before linearized reading' (duration: 106.005379ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:11:41.766679Z","caller":"traceutil/trace.go:171","msg":"trace[300308716] transaction","detail":"{read_only:false; response_revision:7664; number_of_response:1; }","duration":"132.438344ms","start":"2026-07-30T19:11:41.634194Z","end":"2026-07-30T19:11:41.766632Z","steps":["trace[300308716] 'process raft request' (duration: 40.236508ms)","trace[300308716] 'compare' (duration: 92.038901ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:11:42.019398Z","caller":"traceutil/trace.go:171","msg":"trace[362064404] linearizableReadLoop","detail":"{readStateIndex:7919; appliedIndex:7918; }","duration":"178.809589ms","start":"2026-07-30T19:11:41.840562Z","end":"2026-07-30T19:11:42.019371Z","steps":["trace[362064404] 'read index received' (duration: 176.172868ms)","trace[362064404] 'applied index is now lower than readState.Index' (duration: 2.635651ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:11:42.019507Z","caller":"traceutil/trace.go:171","msg":"trace[372107655] transaction","detail":"{read_only:false; response_revision:7665; number_of_response:1; }","duration":"282.31599ms","start":"2026-07-30T19:11:41.73716Z","end":"2026-07-30T19:11:42.019476Z","steps":["trace[372107655] 'process raft request' (duration: 279.547975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:11:42.019579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.996274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:11:42.019624Z","caller":"traceutil/trace.go:171","msg":"trace[1385498568] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:7665; }","duration":"179.116087ms","start":"2026-07-30T19:11:41.840496Z","end":"2026-07-30T19:11:42.019612Z","steps":["trace[1385498568] 'agreement among raft nodes before linearized reading' (duration: 178.980583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:11:50.113353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.590118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:11:50.113531Z","caller":"traceutil/trace.go:171","msg":"trace[1040412270] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"215.138242ms","start":"2026-07-30T19:11:49.898366Z","end":"2026-07-30T19:11:50.113504Z","steps":["trace[1040412270] 'process raft request' (duration: 99.241516ms)","trace[1040412270] 'compare' (duration: 115.392022ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:15:41.91114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7180} {"level":"info","ts":"2026-07-30T19:15:41.969974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7180,"took":"56.312755ms","hash":3594961022,"current-db-size-bytes":35717120,"current-db-size":"36 MB","current-db-size-in-use-bytes":10743808,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-30T19:15:41.970054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3594961022,"revision":7180,"compact-revision":4964} {"level":"info","ts":"2026-07-30T19:16:46.399348Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T19:16:46.407301Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T19:16:46.407439Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-30T19:20:41.917952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9269} {"level":"info","ts":"2026-07-30T19:20:41.97931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9269,"took":"59.873407ms","hash":3607970892,"current-db-size-bytes":35717120,"current-db-size":"36 MB","current-db-size-in-use-bytes":9838592,"current-db-size-in-use":"9.8 MB"} {"level":"info","ts":"2026-07-30T19:20:41.979388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3607970892,"revision":9269,"compact-revision":7180} {"level":"warn","ts":"2026-07-30T19:21:46.042214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746381639779994,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:21:46.293038Z","caller":"traceutil/trace.go:171","msg":"trace[835195496] transaction","detail":"{read_only:false; response_revision:11651; number_of_response:1; }","duration":"752.583133ms","start":"2026-07-30T19:21:45.540432Z","end":"2026-07-30T19:21:46.293015Z","steps":["trace[835195496] 'process raft request' (duration: 752.389758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:46.293253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:45.540392Z","time spent":"752.743388ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:21:46.294081Z","caller":"traceutil/trace.go:171","msg":"trace[1150937029] linearizableReadLoop","detail":"{readStateIndex:12035; appliedIndex:12033; }","duration":"752.618225ms","start":"2026-07-30T19:21:45.541434Z","end":"2026-07-30T19:21:46.294052Z","steps":["trace[1150937029] 'read index received' (duration: 751.374181ms)","trace[1150937029] 'applied index is now lower than readState.Index' (duration: 1.242884ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:46.294237Z","caller":"traceutil/trace.go:171","msg":"trace[539834821] transaction","detail":"{read_only:false; response_revision:11652; number_of_response:1; }","duration":"753.18479ms","start":"2026-07-30T19:21:45.541035Z","end":"2026-07-30T19:21:46.29422Z","steps":["trace[539834821] 'process raft request' (duration: 752.868732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:46.294349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.903384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T19:21:46.294379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:45.54101Z","time spent":"753.282812ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:21:46.294423Z","caller":"traceutil/trace.go:171","msg":"trace[1748372342] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:11652; }","duration":"181.018297ms","start":"2026-07-30T19:21:46.113389Z","end":"2026-07-30T19:21:46.294407Z","steps":["trace[1748372342] 'agreement among raft nodes before linearized reading' (duration: 180.880803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:46.294401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.946533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1\" ","response":"range_response_count:1 size:32868"} {"level":"info","ts":"2026-07-30T19:21:46.294482Z","caller":"traceutil/trace.go:171","msg":"trace[309413551] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1; range_end:; response_count:1; response_revision:11652; }","duration":"753.053997ms","start":"2026-07-30T19:21:45.541408Z","end":"2026-07-30T19:21:46.294462Z","steps":["trace[309413551] 'agreement among raft nodes before linearized reading' (duration: 752.857251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:46.294637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:45.541399Z","time spent":"753.226841ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":32890,"request content":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1\" "} {"level":"warn","ts":"2026-07-30T19:21:46.29451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.215457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317290"} {"level":"info","ts":"2026-07-30T19:21:46.29472Z","caller":"traceutil/trace.go:171","msg":"trace[227099084] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:11652; }","duration":"705.467454ms","start":"2026-07-30T19:21:45.58924Z","end":"2026-07-30T19:21:46.294707Z","steps":["trace[227099084] 'agreement among raft nodes before linearized reading' (duration: 705.004512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:46.29451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.617745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:21:46.294766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:45.589228Z","time spent":"705.526826ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":317312,"request content":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" "} {"level":"info","ts":"2026-07-30T19:21:46.294784Z","caller":"traceutil/trace.go:171","msg":"trace[816349521] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:11652; }","duration":"286.936115ms","start":"2026-07-30T19:21:46.007824Z","end":"2026-07-30T19:21:46.29476Z","steps":["trace[816349521] 'agreement among raft nodes before linearized reading' (duration: 286.569575ms)"],"step_count":1}