{"level":"warn","ts":"2026-04-07T00:10:14.968416Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:10:14.968518Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.154:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.154:2380","--initial-cluster=instance=https://199.19.213.154:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.154:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.154:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:10:14.968661Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:10:14.968682Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-04-07T00:10:14.968728Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:10:14.969591Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"]} {"level":"info","ts":"2026-04-07T00:10:14.969693Z","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.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.154:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:10:14.974796Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.820613ms"} {"level":"info","ts":"2026-04-07T00:10:14.986767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f4aebb100319a62d","cluster-id":"23a505f69026554e"} {"level":"info","ts":"2026-04-07T00:10:14.986881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:10:14.986906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 0"} {"level":"info","ts":"2026-04-07T00:10:14.986918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f4aebb100319a62d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:10:14.986926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 1"} {"level":"info","ts":"2026-04-07T00:10:14.986964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"warn","ts":"2026-04-07T00:10:14.993111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:10:14.994775Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:10:14.996028Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T00:10:14.999077Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f4aebb100319a62d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:10:14.999223Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:10:14.999316Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:10:14.999332Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:10:14.999468Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f4aebb100319a62d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:10:15.000706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"info","ts":"2026-04-07T00:10:15.000875Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","added-peer-id":"f4aebb100319a62d","added-peer-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-04-07T00:10:15.004455Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:10:15.004565Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-04-07T00:10:15.004608Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-04-07T00:10:15.004763Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f4aebb100319a62d","initial-advertise-peer-urls":["https://199.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:10:15.004815Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:10:15.487691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:10:15.487866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:10:15.487903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgPreVoteResp from f4aebb100319a62d at term 1"} {"level":"info","ts":"2026-04-07T00:10:15.487926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:10:15.487944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgVoteResp from f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-04-07T00:10:15.48796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became leader at term 2"} {"level":"info","ts":"2026-04-07T00:10:15.487974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f4aebb100319a62d elected leader f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-04-07T00:10:15.490003Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:10:15.489975Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:15.490007Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f4aebb100319a62d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.154:2379]}","request-path":"/0/members/f4aebb100319a62d/attributes","cluster-id":"23a505f69026554e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:10:15.49014Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:10:15.490396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:10:15.490461Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:10:15.492174Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:15.492341Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:15.492385Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:15.493781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:10:15.496863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.154:2379"} {"level":"warn","ts":"2026-04-07T00:10:18.571279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.044687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:18.571384Z","caller":"traceutil/trace.go:171","msg":"trace[1206818025] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:0; response_revision:129; }","duration":"100.207292ms","start":"2026-04-07T00:10:18.471153Z","end":"2026-04-07T00:10:18.57136Z","steps":["trace[1206818025] 'range keys from in-memory index tree' (duration: 99.917962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:19.046781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.779191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:10:19.046904Z","caller":"traceutil/trace.go:171","msg":"trace[427169443] linearizableReadLoop","detail":"{readStateIndex:140; appliedIndex:139; }","duration":"207.318338ms","start":"2026-04-07T00:10:18.839567Z","end":"2026-04-07T00:10:19.046886Z","steps":["trace[427169443] 'read index received' (duration: 50.812288ms)","trace[427169443] 'applied index is now lower than readState.Index' (duration: 156.50488ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:19.04693Z","caller":"traceutil/trace.go:171","msg":"trace[1882677665] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"212.792108ms","start":"2026-04-07T00:10:18.834104Z","end":"2026-04-07T00:10:19.046896Z","steps":["trace[1882677665] 'process raft request' (duration: 56.310149ms)","trace[1882677665] 'compare' (duration: 155.627078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:19.047192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.598116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:19.047275Z","caller":"traceutil/trace.go:171","msg":"trace[622316741] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:136; }","duration":"207.709118ms","start":"2026-04-07T00:10:18.83955Z","end":"2026-04-07T00:10:19.047259Z","steps":["trace[622316741] 'agreement among raft nodes before linearized reading' (duration: 207.543794ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:19.22348Z","caller":"traceutil/trace.go:171","msg":"trace[207145254] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"171.129294ms","start":"2026-04-07T00:10:19.052316Z","end":"2026-04-07T00:10:19.223445Z","steps":["trace[207145254] 'process raft request' (duration: 171.085152ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:19.223542Z","caller":"traceutil/trace.go:171","msg":"trace[262080930] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"173.792001ms","start":"2026-04-07T00:10:19.049725Z","end":"2026-04-07T00:10:19.223517Z","steps":["trace[262080930] 'process raft request' (duration: 99.03801ms)","trace[262080930] 'compare' (duration: 74.504465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:19.538546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.780032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:10:19.538656Z","caller":"traceutil/trace.go:171","msg":"trace[1466178693] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"235.400866ms","start":"2026-04-07T00:10:19.303235Z","end":"2026-04-07T00:10:19.538636Z","steps":["trace[1466178693] 'process raft request' (duration: 104.425359ms)","trace[1466178693] 'compare' (duration: 130.608148ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:21.721264Z","caller":"traceutil/trace.go:171","msg":"trace[758070297] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"159.495487ms","start":"2026-04-07T00:10:21.56173Z","end":"2026-04-07T00:10:21.721225Z","steps":["trace[758070297] 'process raft request' (duration: 155.72503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:24.115644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.871074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-07T00:10:24.115755Z","caller":"traceutil/trace.go:171","msg":"trace[1268425567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:291; }","duration":"104.05524ms","start":"2026-04-07T00:10:24.011679Z","end":"2026-04-07T00:10:24.115734Z","steps":["trace[1268425567] 'range keys from in-memory index tree' (duration: 103.69251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:24.115681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.367785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T00:10:24.115991Z","caller":"traceutil/trace.go:171","msg":"trace[1625039370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:291; }","duration":"100.711963ms","start":"2026-04-07T00:10:24.015248Z","end":"2026-04-07T00:10:24.11596Z","steps":["trace[1625039370] 'range keys from in-memory index tree' (duration: 100.199961ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:30.753607Z","caller":"traceutil/trace.go:171","msg":"trace[970564082] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"316.691035ms","start":"2026-04-07T00:10:30.436888Z","end":"2026-04-07T00:10:30.753579Z","steps":["trace[970564082] 'process raft request' (duration: 316.49672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:30.754259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:30.436862Z","time spent":"317.092146ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:10:31.008748Z","caller":"traceutil/trace.go:171","msg":"trace[2023154188] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"239.063491ms","start":"2026-04-07T00:10:30.769645Z","end":"2026-04-07T00:10:31.008709Z","steps":["trace[2023154188] 'process raft request' (duration: 238.106987ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:42.347158Z","caller":"traceutil/trace.go:171","msg":"trace[1280042143] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"134.97345ms","start":"2026-04-07T00:10:42.212153Z","end":"2026-04-07T00:10:42.347126Z","steps":["trace[1280042143] 'read index received' (duration: 134.798155ms)","trace[1280042143] 'applied index is now lower than readState.Index' (duration: 174.015µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:42.347239Z","caller":"traceutil/trace.go:171","msg":"trace[1321918634] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"530.305135ms","start":"2026-04-07T00:10:41.816901Z","end":"2026-04-07T00:10:42.347206Z","steps":["trace[1321918634] 'process raft request' (duration: 530.047188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:42.347317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.150735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-07T00:10:42.347362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:41.816872Z","time spent":"530.423059ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:10:42.347383Z","caller":"traceutil/trace.go:171","msg":"trace[1958058301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"135.260927ms","start":"2026-04-07T00:10:42.212108Z","end":"2026-04-07T00:10:42.347368Z","steps":["trace[1958058301] 'agreement among raft nodes before linearized reading' (duration: 135.143085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:49.633112Z","caller":"traceutil/trace.go:171","msg":"trace[652611920] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"207.901443ms","start":"2026-04-07T00:10:49.425175Z","end":"2026-04-07T00:10:49.633077Z","steps":["trace[652611920] 'process raft request' (duration: 207.754449ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:51.743325Z","caller":"traceutil/trace.go:171","msg":"trace[1701788402] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"102.012598ms","start":"2026-04-07T00:10:51.641287Z","end":"2026-04-07T00:10:51.743299Z","steps":["trace[1701788402] 'process raft request' (duration: 101.811192ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.100809Z","caller":"traceutil/trace.go:171","msg":"trace[1101823241] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"537.730474ms","start":"2026-04-07T00:10:52.563043Z","end":"2026-04-07T00:10:53.100774Z","steps":["trace[1101823241] 'process raft request' (duration: 537.553949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.101007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.563011Z","time spent":"537.911648ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.549845Z","caller":"traceutil/trace.go:171","msg":"trace[77920564] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"982.693681ms","start":"2026-04-07T00:10:52.56713Z","end":"2026-04-07T00:10:53.549823Z","steps":["trace[77920564] 'process raft request' (duration: 982.493816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.549946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.567105Z","time spent":"982.795283ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.549937Z","caller":"traceutil/trace.go:171","msg":"trace[1817537300] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"982.598987ms","start":"2026-04-07T00:10:52.567294Z","end":"2026-04-07T00:10:53.549893Z","steps":["trace[1817537300] 'process raft request' (duration: 982.464674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.54998Z","caller":"traceutil/trace.go:171","msg":"trace[1948610628] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"978.446573ms","start":"2026-04-07T00:10:52.571497Z","end":"2026-04-07T00:10:53.549943Z","steps":["trace[1948610628] 'process raft request' (duration: 978.321101ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.550039Z","caller":"traceutil/trace.go:171","msg":"trace[1918736729] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"975.411538ms","start":"2026-04-07T00:10:52.57462Z","end":"2026-04-07T00:10:53.550032Z","steps":["trace[1918736729] 'process raft request' (duration: 975.246574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.550077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.574609Z","time spent":"975.451968ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:10:53.550087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.571404Z","time spent":"978.636258ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:10:53.550104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.567281Z","time spent":"982.749692ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.550179Z","caller":"traceutil/trace.go:171","msg":"trace[1474853188] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"974.990248ms","start":"2026-04-07T00:10:52.575182Z","end":"2026-04-07T00:10:53.550172Z","steps":["trace[1474853188] 'process raft request' (duration: 974.755801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.550214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.575174Z","time spent":"975.025759ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.550351Z","caller":"traceutil/trace.go:171","msg":"trace[633545358] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"969.284165ms","start":"2026-04-07T00:10:52.581061Z","end":"2026-04-07T00:10:53.550345Z","steps":["trace[633545358] 'process raft request' (duration: 969.098331ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.550315Z","caller":"traceutil/trace.go:171","msg":"trace[498568862] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"971.370577ms","start":"2026-04-07T00:10:52.578887Z","end":"2026-04-07T00:10:53.550257Z","steps":["trace[498568862] 'process raft request' (duration: 971.170251ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.550292Z","caller":"traceutil/trace.go:171","msg":"trace[1768598693] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"973.617723ms","start":"2026-04-07T00:10:52.576604Z","end":"2026-04-07T00:10:53.550221Z","steps":["trace[1768598693] 'process raft request' (duration: 973.392267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.550392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.581036Z","time spent":"969.339706ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:10:53.550447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.578856Z","time spent":"971.533761ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.550579Z","caller":"traceutil/trace.go:171","msg":"trace[257509964] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"963.605491ms","start":"2026-04-07T00:10:52.586965Z","end":"2026-04-07T00:10:53.55057Z","steps":["trace[257509964] 'process raft request' (duration: 963.299584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.55058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.576592Z","time spent":"973.824168ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.550745Z","caller":"traceutil/trace.go:171","msg":"trace[2045956698] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:502; }","duration":"787.756892ms","start":"2026-04-07T00:10:52.76297Z","end":"2026-04-07T00:10:53.550727Z","steps":["trace[2045956698] 'read index received' (duration: 337.76411ms)","trace[2045956698] 'applied index is now lower than readState.Index' (duration: 449.991442ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:53.550774Z","caller":"traceutil/trace.go:171","msg":"trace[1864698129] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"818.79985ms","start":"2026-04-07T00:10:52.731965Z","end":"2026-04-07T00:10:53.550765Z","steps":["trace[1864698129] 'process raft request' (duration: 818.464172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.550623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.586944Z","time spent":"963.662472ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:10:53.550816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.731941Z","time spent":"818.860383ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.550813Z","caller":"traceutil/trace.go:171","msg":"trace[372253311] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"931.22927ms","start":"2026-04-07T00:10:52.619528Z","end":"2026-04-07T00:10:53.550757Z","steps":["trace[372253311] 'process raft request' (duration: 930.81537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.550876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.906097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:53.550936Z","caller":"traceutil/trace.go:171","msg":"trace[1754970485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"788.00742ms","start":"2026-04-07T00:10:52.762911Z","end":"2026-04-07T00:10:53.550919Z","steps":["trace[1754970485] 'agreement among raft nodes before linearized reading' (duration: 787.872246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.551001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.76288Z","time spent":"788.109631ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:10:53.551005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.619493Z","time spent":"931.429916ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:10:53.831838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.42882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:10:53.832115Z","caller":"traceutil/trace.go:171","msg":"trace[2074115160] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"724.486385ms","start":"2026-04-07T00:10:53.107611Z","end":"2026-04-07T00:10:53.832098Z","steps":["trace[2074115160] 'process raft request' (duration: 724.354222ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.832092Z","caller":"traceutil/trace.go:171","msg":"trace[1468890943] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"725.574232ms","start":"2026-04-07T00:10:53.106473Z","end":"2026-04-07T00:10:53.832047Z","steps":["trace[1468890943] 'process raft request' (duration: 537.864795ms)","trace[1468890943] 'compare' (duration: 187.163943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:53.832206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.107586Z","time spent":"724.576047ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:53.832253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.106452Z","time spent":"725.724115ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:10:53.832319Z","caller":"traceutil/trace.go:171","msg":"trace[1789608320] transaction","detail":"{read_only:false; number_of_response:1; response_revision:497; }","duration":"722.566356ms","start":"2026-04-07T00:10:53.109725Z","end":"2026-04-07T00:10:53.832292Z","steps":["trace[1789608320] 'process raft request' (duration: 722.33546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.832502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.109702Z","time spent":"722.701351ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:10:53.832637Z","caller":"traceutil/trace.go:171","msg":"trace[433453385] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"274.40219ms","start":"2026-04-07T00:10:53.558221Z","end":"2026-04-07T00:10:53.832623Z","steps":["trace[433453385] 'process raft request' (duration: 274.065342ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.83269Z","caller":"traceutil/trace.go:171","msg":"trace[703973724] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"274.368079ms","start":"2026-04-07T00:10:53.558309Z","end":"2026-04-07T00:10:53.832677Z","steps":["trace[703973724] 'process raft request' (duration: 274.129594ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.832788Z","caller":"traceutil/trace.go:171","msg":"trace[749210774] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:514; }","duration":"281.980249ms","start":"2026-04-07T00:10:53.550796Z","end":"2026-04-07T00:10:53.832776Z","steps":["trace[749210774] 'read index received' (duration: 93.920924ms)","trace[749210774] 'applied index is now lower than readState.Index' (duration: 188.056015ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:53.832987Z","caller":"traceutil/trace.go:171","msg":"trace[466742523] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"272.776439ms","start":"2026-04-07T00:10:53.560188Z","end":"2026-04-07T00:10:53.832964Z","steps":["trace[466742523] 'process raft request' (duration: 272.339798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.833007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.666927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T00:10:53.833051Z","caller":"traceutil/trace.go:171","msg":"trace[1694689378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"463.748128ms","start":"2026-04-07T00:10:53.369293Z","end":"2026-04-07T00:10:53.833041Z","steps":["trace[1694689378] 'agreement among raft nodes before linearized reading' (duration: 463.569763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.833081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.369275Z","time spent":"463.7993ms","remote":"127.0.0.1:55308","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-04-07T00:10:53.833095Z","caller":"traceutil/trace.go:171","msg":"trace[543618459] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"267.021936ms","start":"2026-04-07T00:10:53.566059Z","end":"2026-04-07T00:10:53.833081Z","steps":["trace[543618459] 'process raft request' (duration: 266.624736ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.833155Z","caller":"traceutil/trace.go:171","msg":"trace[1704832800] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"265.064036ms","start":"2026-04-07T00:10:53.568075Z","end":"2026-04-07T00:10:53.833139Z","steps":["trace[1704832800] 'process raft request' (duration: 264.676906ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.833175Z","caller":"traceutil/trace.go:171","msg":"trace[150473351] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"263.647281ms","start":"2026-04-07T00:10:53.569473Z","end":"2026-04-07T00:10:53.833121Z","steps":["trace[150473351] 'process raft request' (duration: 263.379844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.833181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.402169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:10:53.833277Z","caller":"traceutil/trace.go:171","msg":"trace[1765130990] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"271.110627ms","start":"2026-04-07T00:10:53.562157Z","end":"2026-04-07T00:10:53.833267Z","steps":["trace[1765130990] 'process raft request' (duration: 270.461081ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.833298Z","caller":"traceutil/trace.go:171","msg":"trace[332454182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"938.563313ms","start":"2026-04-07T00:10:52.894704Z","end":"2026-04-07T00:10:53.833267Z","steps":["trace[332454182] 'agreement among raft nodes before linearized reading' (duration: 938.212265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.833383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:52.894685Z","time spent":"938.682766ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T00:10:53.833452Z","caller":"traceutil/trace.go:171","msg":"trace[59278078] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"263.874735ms","start":"2026-04-07T00:10:53.569566Z","end":"2026-04-07T00:10:53.833441Z","steps":["trace[59278078] 'process raft request' (duration: 263.464476ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:53.833959Z","caller":"traceutil/trace.go:171","msg":"trace[233100667] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"220.818686ms","start":"2026-04-07T00:10:53.613121Z","end":"2026-04-07T00:10:53.833939Z","steps":["trace[233100667] 'process raft request' (duration: 220.268772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:53.835499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.96628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:53.835575Z","caller":"traceutil/trace.go:171","msg":"trace[1939343763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"280.574604ms","start":"2026-04-07T00:10:53.554978Z","end":"2026-04-07T00:10:53.835552Z","steps":["trace[1939343763] 'agreement among raft nodes before linearized reading' (duration: 278.647317ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.123944Z","caller":"traceutil/trace.go:171","msg":"trace[1323738490] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"285.53419ms","start":"2026-04-07T00:10:53.838383Z","end":"2026-04-07T00:10:54.123918Z","steps":["trace[1323738490] 'process raft request' (duration: 279.423336ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.123994Z","caller":"traceutil/trace.go:171","msg":"trace[1247426194] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"285.50181ms","start":"2026-04-07T00:10:53.838451Z","end":"2026-04-07T00:10:54.123953Z","steps":["trace[1247426194] 'process raft request' (duration: 285.369797ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.12413Z","caller":"traceutil/trace.go:171","msg":"trace[1057460124] transaction","detail":"{read_only:false; number_of_response:1; response_revision:510; }","duration":"272.070392ms","start":"2026-04-07T00:10:53.852048Z","end":"2026-04-07T00:10:54.124119Z","steps":["trace[1057460124] 'process raft request' (duration: 271.926678ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.124243Z","caller":"traceutil/trace.go:171","msg":"trace[662150913] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"285.713326ms","start":"2026-04-07T00:10:53.838504Z","end":"2026-04-07T00:10:54.124218Z","steps":["trace[662150913] 'process raft request' (duration: 285.377727ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.124342Z","caller":"traceutil/trace.go:171","msg":"trace[479293280] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"272.280048ms","start":"2026-04-07T00:10:53.851979Z","end":"2026-04-07T00:10:54.124259Z","steps":["trace[479293280] 'process raft request' (duration: 271.942169ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.124366Z","caller":"traceutil/trace.go:171","msg":"trace[198848367] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"269.698513ms","start":"2026-04-07T00:10:53.854644Z","end":"2026-04-07T00:10:54.124342Z","steps":["trace[198848367] 'process raft request' (duration: 269.364744ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.124451Z","caller":"traceutil/trace.go:171","msg":"trace[1974461872] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"263.486347ms","start":"2026-04-07T00:10:53.860951Z","end":"2026-04-07T00:10:54.124437Z","steps":["trace[1974461872] 'process raft request' (duration: 263.220001ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.12453Z","caller":"traceutil/trace.go:171","msg":"trace[1682444677] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:526; }","duration":"279.55715ms","start":"2026-04-07T00:10:53.844952Z","end":"2026-04-07T00:10:54.124509Z","steps":["trace[1682444677] 'read index received' (duration: 47.767937ms)","trace[1682444677] 'applied index is now lower than readState.Index' (duration: 231.788363ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:54.124587Z","caller":"traceutil/trace.go:171","msg":"trace[1202533521] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"257.783045ms","start":"2026-04-07T00:10:53.866795Z","end":"2026-04-07T00:10:54.124578Z","steps":["trace[1202533521] 'process raft request' (duration: 257.645541ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.124591Z","caller":"traceutil/trace.go:171","msg":"trace[665608792] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"258.745669ms","start":"2026-04-07T00:10:53.86583Z","end":"2026-04-07T00:10:54.124576Z","steps":["trace[665608792] 'process raft request' (duration: 258.449841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:54.124641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.676314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:54.124673Z","caller":"traceutil/trace.go:171","msg":"trace[1133903313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"279.717645ms","start":"2026-04-07T00:10:53.844946Z","end":"2026-04-07T00:10:54.124663Z","steps":["trace[1133903313] 'agreement among raft nodes before linearized reading' (duration: 279.657143ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.366965Z","caller":"traceutil/trace.go:171","msg":"trace[883446888] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:536; }","duration":"242.355026ms","start":"2026-04-07T00:10:54.124588Z","end":"2026-04-07T00:10:54.366943Z","steps":["trace[883446888] 'read index received' (duration: 241.921246ms)","trace[883446888] 'applied index is now lower than readState.Index' (duration: 432.82µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:54.367006Z","caller":"traceutil/trace.go:171","msg":"trace[598170795] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"446.210369ms","start":"2026-04-07T00:10:53.920765Z","end":"2026-04-07T00:10:54.366975Z","steps":["trace[598170795] 'process raft request' (duration: 446.117157ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.367091Z","caller":"traceutil/trace.go:171","msg":"trace[1764281079] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"468.546779ms","start":"2026-04-07T00:10:53.898501Z","end":"2026-04-07T00:10:54.367048Z","steps":["trace[1764281079] 'process raft request' (duration: 467.996966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:54.36716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.920735Z","time spent":"446.365453ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:10:54.367169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.509101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"warn","ts":"2026-04-07T00:10:54.367691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.898486Z","time spent":"468.687463ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:54.367805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.325513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-04-07T00:10:54.367897Z","caller":"traceutil/trace.go:171","msg":"trace[33624641] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:516; }","duration":"513.262971ms","start":"2026-04-07T00:10:53.854613Z","end":"2026-04-07T00:10:54.367876Z","steps":["trace[33624641] 'agreement among raft nodes before linearized reading' (duration: 512.417289ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.367917Z","caller":"traceutil/trace.go:171","msg":"trace[879855507] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:516; }","duration":"474.440737ms","start":"2026-04-07T00:10:53.893428Z","end":"2026-04-07T00:10:54.367869Z","steps":["trace[879855507] 'agreement among raft nodes before linearized reading' (duration: 473.76979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:54.367962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.854598Z","time spent":"513.352793ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10101,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-07T00:10:54.367975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:53.893413Z","time spent":"474.546429ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1042,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-04-07T00:10:54.407025Z","caller":"traceutil/trace.go:171","msg":"trace[657436920] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"273.581791ms","start":"2026-04-07T00:10:54.133416Z","end":"2026-04-07T00:10:54.406998Z","steps":["trace[657436920] 'process raft request' (duration: 273.420546ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.866955Z","caller":"traceutil/trace.go:171","msg":"trace[2131182055] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"104.944721ms","start":"2026-04-07T00:10:54.761978Z","end":"2026-04-07T00:10:54.866923Z","steps":["trace[2131182055] 'read index received' (duration: 92.718585ms)","trace[2131182055] 'applied index is now lower than readState.Index' (duration: 12.224056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:54.867179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.181908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:54.867271Z","caller":"traceutil/trace.go:171","msg":"trace[1081526508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"105.30695ms","start":"2026-04-07T00:10:54.761949Z","end":"2026-04-07T00:10:54.867256Z","steps":["trace[1081526508] 'agreement among raft nodes before linearized reading' (duration: 105.172647ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:54.867312Z","caller":"traceutil/trace.go:171","msg":"trace[1660110926] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"118.730418ms","start":"2026-04-07T00:10:54.748548Z","end":"2026-04-07T00:10:54.867278Z","steps":["trace[1660110926] 'process raft request' (duration: 106.052579ms)","trace[1660110926] 'compare' (duration: 12.187386ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:55.091758Z","caller":"traceutil/trace.go:171","msg":"trace[535779959] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"129.410905ms","start":"2026-04-07T00:10:54.962326Z","end":"2026-04-07T00:10:55.091737Z","steps":["trace[535779959] 'process raft request' (duration: 129.23124ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:55.257863Z","caller":"traceutil/trace.go:171","msg":"trace[514837803] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"220.294654ms","start":"2026-04-07T00:10:55.037547Z","end":"2026-04-07T00:10:55.257842Z","steps":["trace[514837803] 'process raft request' (duration: 220.221262ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:55.258009Z","caller":"traceutil/trace.go:171","msg":"trace[1619871785] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"259.561388ms","start":"2026-04-07T00:10:54.998441Z","end":"2026-04-07T00:10:55.258002Z","steps":["trace[1619871785] 'process raft request' (duration: 214.53541ms)","trace[1619871785] 'compare' (duration: 44.645729ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:55.439838Z","caller":"traceutil/trace.go:171","msg":"trace[1102633470] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"150.615007ms","start":"2026-04-07T00:10:55.289201Z","end":"2026-04-07T00:10:55.439816Z","steps":["trace[1102633470] 'process raft request' (duration: 94.264463ms)","trace[1102633470] 'compare' (duration: 56.226081ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:55.444218Z","caller":"traceutil/trace.go:171","msg":"trace[1494677168] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"122.971204ms","start":"2026-04-07T00:10:55.321223Z","end":"2026-04-07T00:10:55.444194Z","steps":["trace[1494677168] 'process raft request' (duration: 122.569584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.483066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.061589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.483136Z","caller":"traceutil/trace.go:171","msg":"trace[897607487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:557; }","duration":"100.155702ms","start":"2026-04-07T00:10:55.382966Z","end":"2026-04-07T00:10:55.483122Z","steps":["trace[897607487] 'agreement among raft nodes before linearized reading' (duration: 100.060818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.483225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.01105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.483257Z","caller":"traceutil/trace.go:171","msg":"trace[898230198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:557; }","duration":"124.093892ms","start":"2026-04-07T00:10:55.359154Z","end":"2026-04-07T00:10:55.483248Z","steps":["trace[898230198] 'agreement among raft nodes before linearized reading' (duration: 124.04827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.483387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.265967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.48342Z","caller":"traceutil/trace.go:171","msg":"trace[1502147777] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:557; }","duration":"124.321257ms","start":"2026-04-07T00:10:55.35909Z","end":"2026-04-07T00:10:55.483412Z","steps":["trace[1502147777] 'agreement among raft nodes before linearized reading' (duration: 124.273896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.722664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.639967ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:10:55.723078Z","caller":"traceutil/trace.go:171","msg":"trace[391849207] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"162.130865ms","start":"2026-04-07T00:10:55.56093Z","end":"2026-04-07T00:10:55.723061Z","steps":["trace[391849207] 'process raft request' (duration: 161.876049ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:55.72329Z","caller":"traceutil/trace.go:171","msg":"trace[415400880] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"166.752922ms","start":"2026-04-07T00:10:55.556531Z","end":"2026-04-07T00:10:55.723284Z","steps":["trace[415400880] 'read index received' (duration: 21.567381ms)","trace[415400880] 'applied index is now lower than readState.Index' (duration: 145.184801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:55.723511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.972248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.723532Z","caller":"traceutil/trace.go:171","msg":"trace[109128848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:559; }","duration":"167.024768ms","start":"2026-04-07T00:10:55.556501Z","end":"2026-04-07T00:10:55.723525Z","steps":["trace[109128848] 'agreement among raft nodes before linearized reading' (duration: 166.985767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.702577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.799071Z","caller":"traceutil/trace.go:171","msg":"trace[11998155] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"191.786709ms","start":"2026-04-07T00:10:55.60727Z","end":"2026-04-07T00:10:55.799056Z","steps":["trace[11998155] 'agreement among raft nodes before linearized reading' (duration: 191.684957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.364974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.799187Z","caller":"traceutil/trace.go:171","msg":"trace[1252900941] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"192.497807ms","start":"2026-04-07T00:10:55.60666Z","end":"2026-04-07T00:10:55.799158Z","steps":["trace[1252900941] 'agreement among raft nodes before linearized reading' (duration: 192.345953ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:55.799223Z","caller":"traceutil/trace.go:171","msg":"trace[1089204591] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"146.882503ms","start":"2026-04-07T00:10:55.652332Z","end":"2026-04-07T00:10:55.799215Z","steps":["trace[1089204591] 'process raft request' (duration: 144.316788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.952378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.799503Z","caller":"traceutil/trace.go:171","msg":"trace[1209153042] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"193.029641ms","start":"2026-04-07T00:10:55.606463Z","end":"2026-04-07T00:10:55.799493Z","steps":["trace[1209153042] 'agreement among raft nodes before linearized reading' (duration: 192.949458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.059105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.79956Z","caller":"traceutil/trace.go:171","msg":"trace[103648873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:560; }","duration":"121.110847ms","start":"2026-04-07T00:10:55.678443Z","end":"2026-04-07T00:10:55.799554Z","steps":["trace[103648873] 'agreement among raft nodes before linearized reading' (duration: 121.075916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.719499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.799676Z","caller":"traceutil/trace.go:171","msg":"trace[1171802830] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:560; }","duration":"215.773291ms","start":"2026-04-07T00:10:55.583894Z","end":"2026-04-07T00:10:55.799667Z","steps":["trace[1171802830] 'agreement among raft nodes before linearized reading' (duration: 215.715549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.773436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-04-07T00:10:55.799771Z","caller":"traceutil/trace.go:171","msg":"trace[1185192954] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:560; }","duration":"151.829187ms","start":"2026-04-07T00:10:55.647936Z","end":"2026-04-07T00:10:55.799765Z","steps":["trace[1185192954] 'agreement among raft nodes before linearized reading' (duration: 151.719194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.59811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.799877Z","caller":"traceutil/trace.go:171","msg":"trace[1283877002] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:560; }","duration":"216.650772ms","start":"2026-04-07T00:10:55.583216Z","end":"2026-04-07T00:10:55.799866Z","steps":["trace[1283877002] 'agreement among raft nodes before linearized reading' (duration: 216.591391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.800021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.813216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.800058Z","caller":"traceutil/trace.go:171","msg":"trace[835363128] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:560; }","duration":"216.881138ms","start":"2026-04-07T00:10:55.583168Z","end":"2026-04-07T00:10:55.800049Z","steps":["trace[835363128] 'agreement among raft nodes before linearized reading' (duration: 216.830286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.800176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.524685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.800211Z","caller":"traceutil/trace.go:171","msg":"trace[957885365] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:560; }","duration":"243.561557ms","start":"2026-04-07T00:10:55.556641Z","end":"2026-04-07T00:10:55.800203Z","steps":["trace[957885365] 'agreement among raft nodes before linearized reading' (duration: 243.512805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:55.799643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.371494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:55.800705Z","caller":"traceutil/trace.go:171","msg":"trace[442308859] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:560; }","duration":"122.43128ms","start":"2026-04-07T00:10:55.678246Z","end":"2026-04-07T00:10:55.800677Z","steps":["trace[442308859] 'agreement among raft nodes before linearized reading' (duration: 121.378665ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:56.251271Z","caller":"traceutil/trace.go:171","msg":"trace[1534807940] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"107.397774ms","start":"2026-04-07T00:10:56.143856Z","end":"2026-04-07T00:10:56.251254Z","steps":["trace[1534807940] 'read index received' (duration: 77.128885ms)","trace[1534807940] 'applied index is now lower than readState.Index' (duration: 30.267999ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:56.251339Z","caller":"traceutil/trace.go:171","msg":"trace[1704166199] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"111.315731ms","start":"2026-04-07T00:10:56.140001Z","end":"2026-04-07T00:10:56.251317Z","steps":["trace[1704166199] 'process raft request' (duration: 111.131037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:56.251303Z","caller":"traceutil/trace.go:171","msg":"trace[1930579594] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"108.09057ms","start":"2026-04-07T00:10:56.1432Z","end":"2026-04-07T00:10:56.25129Z","steps":["trace[1930579594] 'process raft request' (duration: 107.982248ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:56.251277Z","caller":"traceutil/trace.go:171","msg":"trace[1432288718] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"111.357682ms","start":"2026-04-07T00:10:56.139888Z","end":"2026-04-07T00:10:56.251246Z","steps":["trace[1432288718] 'process raft request' (duration: 81.145905ms)","trace[1432288718] 'compare' (duration: 30.000281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:56.251441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.555847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-07T00:10:56.251518Z","caller":"traceutil/trace.go:171","msg":"trace[954164528] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:564; }","duration":"107.667849ms","start":"2026-04-07T00:10:56.14383Z","end":"2026-04-07T00:10:56.251498Z","steps":["trace[954164528] 'agreement among raft nodes before linearized reading' (duration: 107.518116ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:00.272226Z","caller":"traceutil/trace.go:171","msg":"trace[906491107] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:601; }","duration":"109.133977ms","start":"2026-04-07T00:11:00.163065Z","end":"2026-04-07T00:11:00.272199Z","steps":["trace[906491107] 'read index received' (duration: 109.126217ms)","trace[906491107] 'applied index is now lower than readState.Index' (duration: 6.6µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:00.272492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.398194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:11:00.272837Z","caller":"traceutil/trace.go:171","msg":"trace[1633349746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"109.589878ms","start":"2026-04-07T00:11:00.163038Z","end":"2026-04-07T00:11:00.272628Z","steps":["trace[1633349746] 'agreement among raft nodes before linearized reading' (duration: 109.28132ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:00.382594Z","caller":"traceutil/trace.go:171","msg":"trace[539090170] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"110.236214ms","start":"2026-04-07T00:11:00.272331Z","end":"2026-04-07T00:11:00.382567Z","steps":["trace[539090170] 'read index received' (duration: 108.804759ms)","trace[539090170] 'applied index is now lower than readState.Index' (duration: 1.430095ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:00.38267Z","caller":"traceutil/trace.go:171","msg":"trace[1831316438] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"206.533638ms","start":"2026-04-07T00:11:00.176105Z","end":"2026-04-07T00:11:00.382639Z","steps":["trace[1831316438] 'process raft request' (duration: 205.786809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:00.382841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.367432ms","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-04-07T00:11:00.3829Z","caller":"traceutil/trace.go:171","msg":"trace[776495983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"119.446654ms","start":"2026-04-07T00:11:00.26344Z","end":"2026-04-07T00:11:00.382886Z","steps":["trace[776495983] 'agreement among raft nodes before linearized reading' (duration: 119.224259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:00.38299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.771633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T00:11:00.383066Z","caller":"traceutil/trace.go:171","msg":"trace[1076998885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"119.898657ms","start":"2026-04-07T00:11:00.263148Z","end":"2026-04-07T00:11:00.383047Z","steps":["trace[1076998885] 'agreement among raft nodes before linearized reading' (duration: 119.524877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:00.388101Z","caller":"traceutil/trace.go:171","msg":"trace[1146972783] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"111.729492ms","start":"2026-04-07T00:11:00.276357Z","end":"2026-04-07T00:11:00.388086Z","steps":["trace[1146972783] 'process raft request' (duration: 111.690431ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:00.388128Z","caller":"traceutil/trace.go:171","msg":"trace[955497848] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"114.788919ms","start":"2026-04-07T00:11:00.273323Z","end":"2026-04-07T00:11:00.388112Z","steps":["trace[955497848] 'process raft request' (duration: 114.639145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:01.273945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.699948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:01.274177Z","caller":"traceutil/trace.go:171","msg":"trace[1866945982] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"887.160245ms","start":"2026-04-07T00:11:00.386989Z","end":"2026-04-07T00:11:01.274149Z","steps":["trace[1866945982] 'process raft request' (duration: 269.1641ms)","trace[1866945982] 'compare' (duration: 617.566634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:01.274315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:00.386964Z","time spent":"887.279748ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:01.274895Z","caller":"traceutil/trace.go:171","msg":"trace[2115880734] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"884.928239ms","start":"2026-04-07T00:11:00.389901Z","end":"2026-04-07T00:11:01.274829Z","steps":["trace[2115880734] 'process raft request' (duration: 884.163809ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:01.274955Z","caller":"traceutil/trace.go:171","msg":"trace[702274128] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"883.790959ms","start":"2026-04-07T00:11:00.391116Z","end":"2026-04-07T00:11:01.274907Z","steps":["trace[702274128] 'read index received' (duration: 265.016375ms)","trace[702274128] 'applied index is now lower than readState.Index' (duration: 618.771374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:01.275044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:00.38987Z","time spent":"885.106104ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:01.275267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.129139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T00:11:01.27547Z","caller":"traceutil/trace.go:171","msg":"trace[1887010958] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:582; }","duration":"884.346654ms","start":"2026-04-07T00:11:00.391077Z","end":"2026-04-07T00:11:01.275424Z","steps":["trace[1887010958] 'agreement among raft nodes before linearized reading' (duration: 883.991845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:01.275508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.460822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t8qxb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T00:11:01.275589Z","caller":"traceutil/trace.go:171","msg":"trace[306322357] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t8qxb; range_end:; response_count:1; response_revision:582; }","duration":"489.572115ms","start":"2026-04-07T00:11:00.785997Z","end":"2026-04-07T00:11:01.275569Z","steps":["trace[306322357] 'agreement among raft nodes before linearized reading' (duration: 489.3489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:01.275633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.211298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T00:11:01.275633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:00.785977Z","time spent":"489.648617ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-t8qxb\" "} {"level":"info","ts":"2026-04-07T00:11:01.275693Z","caller":"traceutil/trace.go:171","msg":"trace[169696022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"781.29304ms","start":"2026-04-07T00:11:00.494385Z","end":"2026-04-07T00:11:01.275678Z","steps":["trace[169696022] 'agreement among raft nodes before linearized reading' (duration: 781.136595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:01.275595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.068647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:11:01.275736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:00.494373Z","time spent":"781.354962ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:11:01.275605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:00.39106Z","time spent":"884.529359ms","remote":"127.0.0.1:55222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-07T00:11:01.275373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.341555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:01.275762Z","caller":"traceutil/trace.go:171","msg":"trace[652082652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"490.256063ms","start":"2026-04-07T00:11:00.78549Z","end":"2026-04-07T00:11:01.275746Z","steps":["trace[652082652] 'agreement among raft nodes before linearized reading' (duration: 490.019937ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:01.275967Z","caller":"traceutil/trace.go:171","msg":"trace[140658760] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:582; }","duration":"829.977671ms","start":"2026-04-07T00:11:00.44597Z","end":"2026-04-07T00:11:01.275948Z","steps":["trace[140658760] 'agreement among raft nodes before linearized reading' (duration: 829.342095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:01.276022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:00.44595Z","time spent":"830.051283ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-07T00:11:01.276026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:00.785454Z","time spent":"490.54177ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:11:02.263541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400042548744223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:11:02.764573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400042548744223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:11:03.265251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400042548744223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:11:03.272655Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.798587959s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T00:11:03.294443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.820220772s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:03.29479Z","caller":"traceutil/trace.go:171","msg":"trace[72467971] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.997441355s","start":"2026-04-07T00:11:01.297324Z","end":"2026-04-07T00:11:03.294765Z","steps":["trace[72467971] 'process raft request' (duration: 1.997314281s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:03.294816Z","caller":"traceutil/trace.go:171","msg":"trace[1756634900] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"2.013879258s","start":"2026-04-07T00:11:01.280904Z","end":"2026-04-07T00:11:03.294783Z","steps":["trace[1756634900] 'process raft request' (duration: 193.252706ms)","trace[1756634900] 'compare' (duration: 1.819974325s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:03.294897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:01.297287Z","time spent":"1.997560738s","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:03.294989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:01.280876Z","time spent":"2.014027351s","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:03.304522Z","caller":"traceutil/trace.go:171","msg":"trace[748984474] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:606; }","duration":"1.541781551s","start":"2026-04-07T00:11:01.762716Z","end":"2026-04-07T00:11:03.304497Z","steps":["trace[748984474] 'read index received' (duration: 1.510202468s)","trace[748984474] 'applied index is now lower than readState.Index' (duration: 31.577913ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:03.304565Z","caller":"traceutil/trace.go:171","msg":"trace[84477682] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.610657836s","start":"2026-04-07T00:11:01.693819Z","end":"2026-04-07T00:11:03.304477Z","steps":["trace[84477682] 'process raft request' (duration: 1.60998547s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:03.304689Z","caller":"traceutil/trace.go:171","msg":"trace[751852951] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"484.672573ms","start":"2026-04-07T00:11:02.820007Z","end":"2026-04-07T00:11:03.304679Z","steps":["trace[751852951] 'process raft request' (duration: 484.322904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:03.304799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:02.819979Z","time spent":"484.742885ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:03.304999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.895625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:11:03.305082Z","caller":"traceutil/trace.go:171","msg":"trace[1446142341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"912.014237ms","start":"2026-04-07T00:11:02.393044Z","end":"2026-04-07T00:11:03.305058Z","steps":["trace[1446142341] 'agreement among raft nodes before linearized reading' (duration: 911.820622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:03.305119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:02.392966Z","time spent":"912.14485ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:11:03.305448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:01.693796Z","time spent":"1.610907372s","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:03.30546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542712303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:03.305559Z","caller":"traceutil/trace.go:171","msg":"trace[964010315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.542856447s","start":"2026-04-07T00:11:01.762675Z","end":"2026-04-07T00:11:03.305531Z","steps":["trace[964010315] 'agreement among raft nodes before linearized reading' (duration: 1.542618571s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:03.305618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:01.762652Z","time spent":"1.542956889s","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:11:04.762453Z","caller":"traceutil/trace.go:171","msg":"trace[1132641735] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"762.150321ms","start":"2026-04-07T00:11:04.00027Z","end":"2026-04-07T00:11:04.76242Z","steps":["trace[1132641735] 'process raft request' (duration: 762.035848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:04.762621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:04.000245Z","time spent":"762.318195ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:11:04.894784Z","caller":"traceutil/trace.go:171","msg":"trace[1344179604] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"132.293677ms","start":"2026-04-07T00:11:04.762466Z","end":"2026-04-07T00:11:04.89476Z","steps":["trace[1344179604] 'read index received' (duration: 130.918172ms)","trace[1344179604] 'applied index is now lower than readState.Index' (duration: 1.374475ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:04.894858Z","caller":"traceutil/trace.go:171","msg":"trace[1389740493] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"516.252374ms","start":"2026-04-07T00:11:04.378565Z","end":"2026-04-07T00:11:04.894817Z","steps":["trace[1389740493] 'process raft request' (duration: 515.123206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:04.894958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.474992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:04.895008Z","caller":"traceutil/trace.go:171","msg":"trace[1181251116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"132.578574ms","start":"2026-04-07T00:11:04.762419Z","end":"2026-04-07T00:11:04.894997Z","steps":["trace[1181251116] 'agreement among raft nodes before linearized reading' (duration: 132.467981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:04.895033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:04.378543Z","time spent":"516.398318ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036189,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:05.632117Z","caller":"traceutil/trace.go:171","msg":"trace[1175088061] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"116.757628ms","start":"2026-04-07T00:11:05.515323Z","end":"2026-04-07T00:11:05.63208Z","steps":["trace[1175088061] 'process raft request' (duration: 66.703683ms)","trace[1175088061] 'compare' (duration: 49.661475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:06.216095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.426683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:06.216191Z","caller":"traceutil/trace.go:171","msg":"trace[258720499] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"298.826853ms","start":"2026-04-07T00:11:05.917346Z","end":"2026-04-07T00:11:06.216173Z","steps":["trace[258720499] 'process raft request' (duration: 189.251755ms)","trace[258720499] 'compare' (duration: 109.343121ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:06.234025Z","caller":"traceutil/trace.go:171","msg":"trace[493674201] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"294.640008ms","start":"2026-04-07T00:11:05.939351Z","end":"2026-04-07T00:11:06.233991Z","steps":["trace[493674201] 'process raft request' (duration: 294.474624ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:06.234042Z","caller":"traceutil/trace.go:171","msg":"trace[1503423745] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"126.883801ms","start":"2026-04-07T00:11:06.107065Z","end":"2026-04-07T00:11:06.233948Z","steps":["trace[1503423745] 'read index received' (duration: 126.82097ms)","trace[1503423745] 'applied index is now lower than readState.Index' (duration: 61.831µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:06.234198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.113476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:06.234255Z","caller":"traceutil/trace.go:171","msg":"trace[1148304884] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"127.231169ms","start":"2026-04-07T00:11:06.107005Z","end":"2026-04-07T00:11:06.234236Z","steps":["trace[1148304884] 'agreement among raft nodes before linearized reading' (duration: 127.102416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:20.645729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400042548744576,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:11:20.782423Z","caller":"traceutil/trace.go:171","msg":"trace[426946535] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"647.143627ms","start":"2026-04-07T00:11:20.135258Z","end":"2026-04-07T00:11:20.782402Z","steps":["trace[426946535] 'process raft request' (duration: 628.433948ms)","trace[426946535] 'compare' (duration: 18.36189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:20.782578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.110295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:11:20.782595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:20.135243Z","time spent":"647.244329ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:20.782628Z","caller":"traceutil/trace.go:171","msg":"trace[1080960442] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:767; }","duration":"637.191917ms","start":"2026-04-07T00:11:20.145424Z","end":"2026-04-07T00:11:20.782616Z","steps":["trace[1080960442] 'agreement among raft nodes before linearized reading' (duration: 637.040164ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:20.782419Z","caller":"traceutil/trace.go:171","msg":"trace[1285808179] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"636.943831ms","start":"2026-04-07T00:11:20.145452Z","end":"2026-04-07T00:11:20.782395Z","steps":["trace[1285808179] 'read index received' (duration: 618.221441ms)","trace[1285808179] 'applied index is now lower than readState.Index' (duration: 18.72109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:20.782661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:20.145414Z","time spent":"637.238258ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:11:28.961826Z","caller":"traceutil/trace.go:171","msg":"trace[867114145] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"188.496586ms","start":"2026-04-07T00:11:28.7733Z","end":"2026-04-07T00:11:28.961797Z","steps":["trace[867114145] 'read index received' (duration: 188.328952ms)","trace[867114145] 'applied index is now lower than readState.Index' (duration: 166.444µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:28.961902Z","caller":"traceutil/trace.go:171","msg":"trace[1567411969] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"366.590623ms","start":"2026-04-07T00:11:28.595272Z","end":"2026-04-07T00:11:28.961863Z","steps":["trace[1567411969] 'process raft request' (duration: 366.373527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:28.961947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.64523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:28.961985Z","caller":"traceutil/trace.go:171","msg":"trace[200979122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:851; }","duration":"188.746132ms","start":"2026-04-07T00:11:28.773228Z","end":"2026-04-07T00:11:28.961974Z","steps":["trace[200979122] 'agreement among raft nodes before linearized reading' (duration: 188.67351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:28.962098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:28.595249Z","time spent":"366.734265ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:57.525578Z","caller":"traceutil/trace.go:171","msg":"trace[717656529] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"128.480145ms","start":"2026-04-07T00:11:57.397056Z","end":"2026-04-07T00:11:57.525536Z","steps":["trace[717656529] 'process raft request' (duration: 128.194857ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:57.977626Z","caller":"traceutil/trace.go:171","msg":"trace[215951300] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"103.796401ms","start":"2026-04-07T00:11:57.873795Z","end":"2026-04-07T00:11:57.977591Z","steps":["trace[215951300] 'process raft request' (duration: 95.689854ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:57.986481Z","caller":"traceutil/trace.go:171","msg":"trace[1435218962] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"105.097264ms","start":"2026-04-07T00:11:57.881362Z","end":"2026-04-07T00:11:57.986459Z","steps":["trace[1435218962] 'process raft request' (duration: 104.996551ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:01.400826Z","caller":"traceutil/trace.go:171","msg":"trace[650688107] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"307.924395ms","start":"2026-04-07T00:12:01.092874Z","end":"2026-04-07T00:12:01.400798Z","steps":["trace[650688107] 'process raft request' (duration: 307.743871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:01.401012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:01.092842Z","time spent":"308.069539ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4332,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:12:01.93993Z","caller":"traceutil/trace.go:171","msg":"trace[888458670] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"100.532897ms","start":"2026-04-07T00:12:01.83936Z","end":"2026-04-07T00:12:01.939892Z","steps":["trace[888458670] 'compare' (duration: 92.588205ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:06.078346Z","caller":"traceutil/trace.go:171","msg":"trace[344373102] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"184.594972ms","start":"2026-04-07T00:12:05.893711Z","end":"2026-04-07T00:12:06.078306Z","steps":["trace[344373102] 'process raft request' (duration: 159.131579ms)","trace[344373102] 'compare' (duration: 25.249407ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:06.078931Z","caller":"traceutil/trace.go:171","msg":"trace[1385916328] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"184.296174ms","start":"2026-04-07T00:12:05.894619Z","end":"2026-04-07T00:12:06.078915Z","steps":["trace[1385916328] 'process raft request' (duration: 184.202182ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:06.078987Z","caller":"traceutil/trace.go:171","msg":"trace[951617885] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"185.034934ms","start":"2026-04-07T00:12:05.893894Z","end":"2026-04-07T00:12:06.078929Z","steps":["trace[951617885] 'process raft request' (duration: 184.821608ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:06.079147Z","caller":"traceutil/trace.go:171","msg":"trace[1546399830] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"184.274055ms","start":"2026-04-07T00:12:05.894836Z","end":"2026-04-07T00:12:06.07911Z","steps":["trace[1546399830] 'process raft request' (duration: 184.031758ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:06.079111Z","caller":"traceutil/trace.go:171","msg":"trace[723099668] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1430; }","duration":"126.495182ms","start":"2026-04-07T00:12:05.95259Z","end":"2026-04-07T00:12:06.079085Z","steps":["trace[723099668] 'read index received' (duration: 100.424054ms)","trace[723099668] 'applied index is now lower than readState.Index' (duration: 26.069028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:06.079409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.79011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T00:12:06.079485Z","caller":"traceutil/trace.go:171","msg":"trace[449874170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1390; }","duration":"126.932324ms","start":"2026-04-07T00:12:05.952532Z","end":"2026-04-07T00:12:06.079465Z","steps":["trace[449874170] 'agreement among raft nodes before linearized reading' (duration: 126.620776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:06.079525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.748287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T00:12:06.079586Z","caller":"traceutil/trace.go:171","msg":"trace[1889243275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1390; }","duration":"122.87536ms","start":"2026-04-07T00:12:05.956694Z","end":"2026-04-07T00:12:06.079569Z","steps":["trace[1889243275] 'agreement among raft nodes before linearized reading' (duration: 122.719626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:06.079754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.479691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:12:06.079928Z","caller":"traceutil/trace.go:171","msg":"trace[1977810551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1390; }","duration":"122.711907ms","start":"2026-04-07T00:12:05.957185Z","end":"2026-04-07T00:12:06.079897Z","steps":["trace[1977810551] 'agreement among raft nodes before linearized reading' (duration: 122.245035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:09.76931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.715273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:12:09.769415Z","caller":"traceutil/trace.go:171","msg":"trace[2049984598] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1411; }","duration":"242.883487ms","start":"2026-04-07T00:12:09.52651Z","end":"2026-04-07T00:12:09.769393Z","steps":["trace[2049984598] 'count revisions from in-memory index tree' (duration: 242.550098ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:09.797063Z","caller":"traceutil/trace.go:171","msg":"trace[1920736320] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"227.524963ms","start":"2026-04-07T00:12:09.569505Z","end":"2026-04-07T00:12:09.79703Z","steps":["trace[1920736320] 'process raft request' (duration: 227.176805ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:25.81056Z","caller":"traceutil/trace.go:171","msg":"trace[1498559641] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"133.182625ms","start":"2026-04-07T00:12:25.677307Z","end":"2026-04-07T00:12:25.810489Z","steps":["trace[1498559641] 'process raft request' (duration: 131.843891ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:30.070802Z","caller":"traceutil/trace.go:171","msg":"trace[801472661] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"135.551196ms","start":"2026-04-07T00:12:29.9352Z","end":"2026-04-07T00:12:30.070751Z","steps":["trace[801472661] 'process raft request' (duration: 111.508719ms)","trace[801472661] 'compare' (duration: 23.644296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:32.438271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400042548746599,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:12:32.7705Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020509076s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T00:12:32.846025Z","caller":"traceutil/trace.go:171","msg":"trace[1515550858] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"1.096107554s","start":"2026-04-07T00:12:31.749878Z","end":"2026-04-07T00:12:32.845985Z","steps":["trace[1515550858] 'process raft request' (duration: 1.020989757s)","trace[1515550858] 'compare' (duration: 74.957923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:32.846221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:31.749851Z","time spent":"1.096267358s","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:12:32.867976Z","caller":"traceutil/trace.go:171","msg":"trace[1743471158] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1872; }","duration":"930.767564ms","start":"2026-04-07T00:12:31.937164Z","end":"2026-04-07T00:12:32.867932Z","steps":["trace[1743471158] 'read index received' (duration: 833.718927ms)","trace[1743471158] 'applied index is now lower than readState.Index' (duration: 97.047347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:32.868224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.267562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:12:32.868274Z","caller":"traceutil/trace.go:171","msg":"trace[1969554622] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1802; }","duration":"829.391755ms","start":"2026-04-07T00:12:32.038868Z","end":"2026-04-07T00:12:32.86826Z","steps":["trace[1969554622] 'agreement among raft nodes before linearized reading' (duration: 829.275971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.868319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.038848Z","time spent":"829.460437ms","remote":"127.0.0.1:38568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:12:32.86833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.763069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:12:32.868417Z","caller":"traceutil/trace.go:171","msg":"trace[1617337793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1802; }","duration":"647.896852ms","start":"2026-04-07T00:12:32.220497Z","end":"2026-04-07T00:12:32.868394Z","steps":["trace[1617337793] 'agreement among raft nodes before linearized reading' (duration: 647.698687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.868471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.220477Z","time spent":"647.983564ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:12:32.86843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.063495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-07T00:12:32.868486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.067793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T00:12:32.868608Z","caller":"traceutil/trace.go:171","msg":"trace[1770397637] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f; range_end:; response_count:1; response_revision:1802; }","duration":"628.276098ms","start":"2026-04-07T00:12:32.240308Z","end":"2026-04-07T00:12:32.868584Z","steps":["trace[1770397637] 'agreement among raft nodes before linearized reading' (duration: 627.883297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.868635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.556729ms","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-04-07T00:12:32.868673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.240279Z","time spent":"628.383781ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f\" "} {"level":"warn","ts":"2026-04-07T00:12:32.868682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.798424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T00:12:32.868722Z","caller":"traceutil/trace.go:171","msg":"trace[2009803486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1802; }","duration":"662.892546ms","start":"2026-04-07T00:12:32.205821Z","end":"2026-04-07T00:12:32.868714Z","steps":["trace[2009803486] 'agreement among raft nodes before linearized reading' (duration: 662.788724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:32.868714Z","caller":"traceutil/trace.go:171","msg":"trace[1040420706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1802; }","duration":"767.648351ms","start":"2026-04-07T00:12:32.101042Z","end":"2026-04-07T00:12:32.868691Z","steps":["trace[1040420706] 'agreement among raft nodes before linearized reading' (duration: 767.498368ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:32.86853Z","caller":"traceutil/trace.go:171","msg":"trace[1791539440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1802; }","duration":"646.212188ms","start":"2026-04-07T00:12:32.2223Z","end":"2026-04-07T00:12:32.868513Z","steps":["trace[1791539440] 'agreement among raft nodes before linearized reading' (duration: 645.956012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.868766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.205795Z","time spent":"662.963628ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:12:32.86876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.666556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-07T00:12:32.868779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.101031Z","time spent":"767.737713ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:12:32.868484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.240924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:12:32.868922Z","caller":"traceutil/trace.go:171","msg":"trace[1593673922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1802; }","duration":"106.748088ms","start":"2026-04-07T00:12:32.762153Z","end":"2026-04-07T00:12:32.868901Z","steps":["trace[1593673922] 'agreement among raft nodes before linearized reading' (duration: 106.187813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.868794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.222278Z","time spent":"646.503306ms","remote":"127.0.0.1:55308","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-04-07T00:12:32.868963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.501062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-07T00:12:32.868424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.432879ms","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-04-07T00:12:32.869011Z","caller":"traceutil/trace.go:171","msg":"trace[1400305019] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1802; }","duration":"507.586324ms","start":"2026-04-07T00:12:32.361409Z","end":"2026-04-07T00:12:32.868995Z","steps":["trace[1400305019] 'agreement among raft nodes before linearized reading' (duration: 507.161973ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:32.869052Z","caller":"traceutil/trace.go:171","msg":"trace[1544795056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1802; }","duration":"646.122456ms","start":"2026-04-07T00:12:32.222918Z","end":"2026-04-07T00:12:32.86904Z","steps":["trace[1544795056] 'agreement among raft nodes before linearized reading' (duration: 645.301945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.869073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.361383Z","time spent":"507.677506ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T00:12:32.868857Z","caller":"traceutil/trace.go:171","msg":"trace[154736507] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f; range_end:; response_count:1; response_revision:1802; }","duration":"560.799989ms","start":"2026-04-07T00:12:32.308026Z","end":"2026-04-07T00:12:32.868826Z","steps":["trace[154736507] 'agreement among raft nodes before linearized reading' (duration: 560.551283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.869227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.308002Z","time spent":"561.212439ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f\" "} {"level":"warn","ts":"2026-04-07T00:12:32.869458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.291973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T00:12:32.869529Z","caller":"traceutil/trace.go:171","msg":"trace[1103705757] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1802; }","duration":"932.401055ms","start":"2026-04-07T00:12:31.937103Z","end":"2026-04-07T00:12:32.869504Z","steps":["trace[1103705757] 'agreement among raft nodes before linearized reading' (duration: 930.998829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.869593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:31.937074Z","time spent":"932.487558ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:12:32.869906Z","caller":"traceutil/trace.go:171","msg":"trace[1379766474] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"993.41381ms","start":"2026-04-07T00:12:31.876465Z","end":"2026-04-07T00:12:32.869879Z","steps":["trace[1379766474] 'process raft request' (duration: 991.280596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:32.87006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:31.876434Z","time spent":"993.536723ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:12:32.869095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:32.222904Z","time spent":"646.180378ms","remote":"127.0.0.1:55308","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-04-07T00:12:33.024271Z","caller":"traceutil/trace.go:171","msg":"trace[388814908] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"149.955824ms","start":"2026-04-07T00:12:32.874273Z","end":"2026-04-07T00:12:33.024229Z","steps":["trace[388814908] 'process raft request' (duration: 76.984723ms)","trace[388814908] 'compare' (duration: 72.690494ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:33.024435Z","caller":"traceutil/trace.go:171","msg":"trace[1759732782] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"150.123809ms","start":"2026-04-07T00:12:32.874273Z","end":"2026-04-07T00:12:33.024397Z","steps":["trace[1759732782] 'process raft request' (duration: 149.892713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.063826Z","caller":"traceutil/trace.go:171","msg":"trace[1328731313] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"189.281423ms","start":"2026-04-07T00:12:32.874506Z","end":"2026-04-07T00:12:33.063788Z","steps":["trace[1328731313] 'process raft request' (duration: 188.989625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.063891Z","caller":"traceutil/trace.go:171","msg":"trace[1731120502] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"189.125999ms","start":"2026-04-07T00:12:32.874717Z","end":"2026-04-07T00:12:33.063843Z","steps":["trace[1731120502] 'process raft request' (duration: 188.952325ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.064047Z","caller":"traceutil/trace.go:171","msg":"trace[347669359] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"188.912234ms","start":"2026-04-07T00:12:32.875072Z","end":"2026-04-07T00:12:33.063984Z","steps":["trace[347669359] 'process raft request' (duration: 188.642107ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.064427Z","caller":"traceutil/trace.go:171","msg":"trace[1455811441] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"188.690668ms","start":"2026-04-07T00:12:32.875718Z","end":"2026-04-07T00:12:33.064409Z","steps":["trace[1455811441] 'process raft request' (duration: 188.435141ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.064603Z","caller":"traceutil/trace.go:171","msg":"trace[1698686553] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"186.511181ms","start":"2026-04-07T00:12:32.878073Z","end":"2026-04-07T00:12:33.064584Z","steps":["trace[1698686553] 'process raft request' (duration: 186.366458ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:34.105223Z","caller":"traceutil/trace.go:171","msg":"trace[1794215805] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"342.201853ms","start":"2026-04-07T00:12:33.762993Z","end":"2026-04-07T00:12:34.105195Z","steps":["trace[1794215805] 'read index received' (duration: 341.993398ms)","trace[1794215805] 'applied index is now lower than readState.Index' (duration: 206.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:34.105286Z","caller":"traceutil/trace.go:171","msg":"trace[443776288] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"627.073966ms","start":"2026-04-07T00:12:33.478174Z","end":"2026-04-07T00:12:34.105248Z","steps":["trace[443776288] 'process raft request' (duration: 626.82095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.105472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.44576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:12:34.105607Z","caller":"traceutil/trace.go:171","msg":"trace[293757782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1810; }","duration":"342.642574ms","start":"2026-04-07T00:12:33.762936Z","end":"2026-04-07T00:12:34.105579Z","steps":["trace[293757782] 'agreement among raft nodes before linearized reading' (duration: 342.422759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.105681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:33.762912Z","time spent":"342.754158ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:12:34.105704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.462367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T00:12:34.105788Z","caller":"traceutil/trace.go:171","msg":"trace[1499466309] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1810; }","duration":"228.567039ms","start":"2026-04-07T00:12:33.877201Z","end":"2026-04-07T00:12:34.105768Z","steps":["trace[1499466309] 'agreement among raft nodes before linearized reading' (duration: 228.320643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.105512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.211188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-07T00:12:34.105951Z","caller":"traceutil/trace.go:171","msg":"trace[784783567] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e93bcb3-adaf-4d82-a252-75ee599de04f; range_end:; response_count:1; response_revision:1810; }","duration":"231.69275ms","start":"2026-04-07T00:12:33.87421Z","end":"2026-04-07T00:12:34.105903Z","steps":["trace[784783567] 'agreement among raft nodes before linearized reading' (duration: 231.154627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.10616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:33.478141Z","time spent":"627.231971ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:12:39.023128Z","caller":"traceutil/trace.go:171","msg":"trace[1222640490] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"154.938322ms","start":"2026-04-07T00:12:38.86816Z","end":"2026-04-07T00:12:39.023099Z","steps":["trace[1222640490] 'process raft request' (duration: 154.723186ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:39.147376Z","caller":"traceutil/trace.go:171","msg":"trace[1872126415] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"244.281214ms","start":"2026-04-07T00:12:38.90306Z","end":"2026-04-07T00:12:39.147341Z","steps":["trace[1872126415] 'process raft request' (duration: 166.13784ms)","trace[1872126415] 'compare' (duration: 77.882616ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:39.405946Z","caller":"traceutil/trace.go:171","msg":"trace[2049469056] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1931; }","duration":"245.105504ms","start":"2026-04-07T00:12:39.160812Z","end":"2026-04-07T00:12:39.405917Z","steps":["trace[2049469056] 'read index received' (duration: 66.006552ms)","trace[2049469056] 'applied index is now lower than readState.Index' (duration: 179.097602ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:39.406083Z","caller":"traceutil/trace.go:171","msg":"trace[884371305] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"253.978212ms","start":"2026-04-07T00:12:39.152062Z","end":"2026-04-07T00:12:39.40604Z","steps":["trace[884371305] 'process raft request' (duration: 253.771687ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:39.406083Z","caller":"traceutil/trace.go:171","msg":"trace[241272855] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"254.078514ms","start":"2026-04-07T00:12:39.151962Z","end":"2026-04-07T00:12:39.40604Z","steps":["trace[241272855] 'process raft request' (duration: 169.569147ms)","trace[241272855] 'compare' (duration: 84.099386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:39.406276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.929834ms","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":"info","ts":"2026-04-07T00:12:39.406319Z","caller":"traceutil/trace.go:171","msg":"trace[405694666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"244.017796ms","start":"2026-04-07T00:12:39.16229Z","end":"2026-04-07T00:12:39.406307Z","steps":["trace[405694666] 'agreement among raft nodes before linearized reading' (duration: 243.809231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:39.406326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.65119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-07T00:12:39.406327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.111054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11041"} {"level":"warn","ts":"2026-04-07T00:12:39.406309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.463373ms","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-04-07T00:12:39.406386Z","caller":"traceutil/trace.go:171","msg":"trace[1642631467] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"233.734172ms","start":"2026-04-07T00:12:39.172633Z","end":"2026-04-07T00:12:39.406367Z","steps":["trace[1642631467] 'agreement among raft nodes before linearized reading' (duration: 233.555988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:39.406391Z","caller":"traceutil/trace.go:171","msg":"trace[1776429885] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1862; }","duration":"112.227658ms","start":"2026-04-07T00:12:39.294149Z","end":"2026-04-07T00:12:39.406377Z","steps":["trace[1776429885] 'agreement among raft nodes before linearized reading' (duration: 111.946351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:39.406418Z","caller":"traceutil/trace.go:171","msg":"trace[110634401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"245.605017ms","start":"2026-04-07T00:12:39.160793Z","end":"2026-04-07T00:12:39.406398Z","steps":["trace[110634401] 'agreement among raft nodes before linearized reading' (duration: 245.262458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:46.60448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.983258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T00:12:46.604561Z","caller":"traceutil/trace.go:171","msg":"trace[719980869] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1914; }","duration":"147.110451ms","start":"2026-04-07T00:12:46.457432Z","end":"2026-04-07T00:12:46.604542Z","steps":["trace[719980869] 'range keys from in-memory index tree' (duration: 146.753053ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:49.975238Z","caller":"traceutil/trace.go:171","msg":"trace[295748709] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"485.814956ms","start":"2026-04-07T00:12:49.489391Z","end":"2026-04-07T00:12:49.975206Z","steps":["trace[295748709] 'process raft request' (duration: 485.562589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:49.975591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:49.489369Z","time spent":"486.088472ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:12:50.046173Z","caller":"traceutil/trace.go:171","msg":"trace[2072512068] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"556.101427ms","start":"2026-04-07T00:12:49.490032Z","end":"2026-04-07T00:12:50.046133Z","steps":["trace[2072512068] 'process raft request' (duration: 555.860372ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:50.04635Z","caller":"traceutil/trace.go:171","msg":"trace[877002702] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2014; }","duration":"536.874104ms","start":"2026-04-07T00:12:49.509435Z","end":"2026-04-07T00:12:50.046309Z","steps":["trace[877002702] 'read index received' (duration: 465.611067ms)","trace[877002702] 'applied index is now lower than readState.Index' (duration: 71.260487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:50.046403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:49.490015Z","time spent":"556.272612ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:12:50.046443Z","caller":"traceutil/trace.go:171","msg":"trace[1225531978] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"487.967491ms","start":"2026-04-07T00:12:49.55843Z","end":"2026-04-07T00:12:50.046398Z","steps":["trace[1225531978] 'process raft request' (duration: 487.654213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:50.046618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.148681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T00:12:50.046697Z","caller":"traceutil/trace.go:171","msg":"trace[571148053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1942; }","duration":"537.267906ms","start":"2026-04-07T00:12:49.509411Z","end":"2026-04-07T00:12:50.046679Z","steps":["trace[571148053] 'agreement among raft nodes before linearized reading' (duration: 537.009049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:50.046688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:49.558396Z","time spent":"488.169897ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:12:50.046723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.15432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:12:50.04674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:49.509399Z","time spent":"537.331687ms","remote":"127.0.0.1:55308","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-04-07T00:12:50.046778Z","caller":"traceutil/trace.go:171","msg":"trace[55147722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1942; }","duration":"283.234443ms","start":"2026-04-07T00:12:49.76353Z","end":"2026-04-07T00:12:50.046764Z","steps":["trace[55147722] 'agreement among raft nodes before linearized reading' (duration: 283.10681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:50.046802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.364321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T00:12:50.046891Z","caller":"traceutil/trace.go:171","msg":"trace[1711271510] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1942; }","duration":"417.517694ms","start":"2026-04-07T00:12:49.62935Z","end":"2026-04-07T00:12:50.046867Z","steps":["trace[1711271510] 'agreement among raft nodes before linearized reading' (duration: 417.298379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:50.046932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:49.629329Z","time spent":"417.593296ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T00:12:50.046927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.625369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T00:12:50.046998Z","caller":"traceutil/trace.go:171","msg":"trace[642498481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1942; }","duration":"481.741132ms","start":"2026-04-07T00:12:49.565237Z","end":"2026-04-07T00:12:50.046978Z","steps":["trace[642498481] 'agreement among raft nodes before linearized reading' (duration: 481.26958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:50.047046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:49.565218Z","time spent":"481.815053ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T00:12:57.413093Z","caller":"traceutil/trace.go:171","msg":"trace[510975795] linearizableReadLoop","detail":"{readStateIndex:2069; appliedIndex:2068; }","duration":"103.372711ms","start":"2026-04-07T00:12:57.309687Z","end":"2026-04-07T00:12:57.41306Z","steps":["trace[510975795] 'read index received' (duration: 56.413727ms)","trace[510975795] 'applied index is now lower than readState.Index' (duration: 46.957664ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:57.413136Z","caller":"traceutil/trace.go:171","msg":"trace[1993281950] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"199.856865ms","start":"2026-04-07T00:12:57.213254Z","end":"2026-04-07T00:12:57.413111Z","steps":["trace[1993281950] 'process raft request' (duration: 152.85596ms)","trace[1993281950] 'compare' (duration: 46.79916ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:57.413711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.998118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:12:57.413781Z","caller":"traceutil/trace.go:171","msg":"trace[1526860997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1992; }","duration":"104.08259ms","start":"2026-04-07T00:12:57.30968Z","end":"2026-04-07T00:12:57.413763Z","steps":["trace[1526860997] 'agreement among raft nodes before linearized reading' (duration: 103.560646ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:02.416979Z","caller":"traceutil/trace.go:171","msg":"trace[990595323] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"117.880308ms","start":"2026-04-07T00:13:02.299052Z","end":"2026-04-07T00:13:02.416932Z","steps":["trace[990595323] 'process raft request' (duration: 42.418607ms)","trace[990595323] 'compare' (duration: 75.186314ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:02.611004Z","caller":"traceutil/trace.go:171","msg":"trace[709882967] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"311.823963ms","start":"2026-04-07T00:13:02.299152Z","end":"2026-04-07T00:13:02.610976Z","steps":["trace[709882967] 'process raft request' (duration: 266.225526ms)","trace[709882967] 'compare' (duration: 45.397892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:02.611168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:02.299127Z","time spent":"311.956516ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:13:02.61118Z","caller":"traceutil/trace.go:171","msg":"trace[187070878] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"259.530544ms","start":"2026-04-07T00:13:02.351617Z","end":"2026-04-07T00:13:02.611148Z","steps":["trace[187070878] 'process raft request' (duration: 259.310698ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:14.501564Z","caller":"traceutil/trace.go:171","msg":"trace[1879310481] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"126.057297ms","start":"2026-04-07T00:13:14.375486Z","end":"2026-04-07T00:13:14.501543Z","steps":["trace[1879310481] 'read index received' (duration: 125.845421ms)","trace[1879310481] 'applied index is now lower than readState.Index' (duration: 210.996µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:14.501602Z","caller":"traceutil/trace.go:171","msg":"trace[414240518] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"145.668399ms","start":"2026-04-07T00:13:14.355912Z","end":"2026-04-07T00:13:14.50158Z","steps":["trace[414240518] 'process raft request' (duration: 145.499015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:14.501854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.359675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T00:13:14.501928Z","caller":"traceutil/trace.go:171","msg":"trace[447073359] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2081; }","duration":"126.489828ms","start":"2026-04-07T00:13:14.37542Z","end":"2026-04-07T00:13:14.50191Z","steps":["trace[447073359] 'agreement among raft nodes before linearized reading' (duration: 126.272163ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:19.621478Z","caller":"traceutil/trace.go:171","msg":"trace[1556999265] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"121.075718ms","start":"2026-04-07T00:13:19.500378Z","end":"2026-04-07T00:13:19.621454Z","steps":["trace[1556999265] 'process raft request' (duration: 120.885714ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:19.759055Z","caller":"traceutil/trace.go:171","msg":"trace[782504320] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"133.239611ms","start":"2026-04-07T00:13:19.625787Z","end":"2026-04-07T00:13:19.759027Z","steps":["trace[782504320] 'process raft request' (duration: 81.022265ms)","trace[782504320] 'compare' (duration: 51.936779ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:19.759321Z","caller":"traceutil/trace.go:171","msg":"trace[207244530] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"132.636946ms","start":"2026-04-07T00:13:19.626653Z","end":"2026-04-07T00:13:19.75929Z","steps":["trace[207244530] 'process raft request' (duration: 132.324397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:20.322491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.018701ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-07T00:13:20.322855Z","caller":"traceutil/trace.go:171","msg":"trace[223553624] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"138.446664ms","start":"2026-04-07T00:13:20.184385Z","end":"2026-04-07T00:13:20.322831Z","steps":["trace[223553624] 'process raft request' (duration: 138.246508ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:26.098196Z","caller":"traceutil/trace.go:171","msg":"trace[824956699] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"434.526923ms","start":"2026-04-07T00:13:25.663642Z","end":"2026-04-07T00:13:26.098169Z","steps":["trace[824956699] 'read index received' (duration: 434.475032ms)","trace[824956699] 'applied index is now lower than readState.Index' (duration: 50.281µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:26.09827Z","caller":"traceutil/trace.go:171","msg":"trace[1281578861] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"449.131496ms","start":"2026-04-07T00:13:25.649108Z","end":"2026-04-07T00:13:26.09824Z","steps":["trace[1281578861] 'process raft request' (duration: 448.795628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.098666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:25.649087Z","time spent":"449.241969ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:13:26.098719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.043596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T00:13:26.098871Z","caller":"traceutil/trace.go:171","msg":"trace[633116670] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"435.237081ms","start":"2026-04-07T00:13:25.663602Z","end":"2026-04-07T00:13:26.098839Z","steps":["trace[633116670] 'agreement among raft nodes before linearized reading' (duration: 434.712898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.098961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:25.663593Z","time spent":"435.354834ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T00:13:26.364026Z","caller":"traceutil/trace.go:171","msg":"trace[1529118869] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2223; }","duration":"265.704271ms","start":"2026-04-07T00:13:26.098292Z","end":"2026-04-07T00:13:26.363997Z","steps":["trace[1529118869] 'read index received' (duration: 178.463298ms)","trace[1529118869] 'applied index is now lower than readState.Index' (duration: 87.239423ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:26.364322Z","caller":"traceutil/trace.go:171","msg":"trace[169062962] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"436.7478ms","start":"2026-04-07T00:13:25.927559Z","end":"2026-04-07T00:13:26.364306Z","steps":["trace[169062962] 'process raft request' (duration: 349.277081ms)","trace[169062962] 'compare' (duration: 87.023468ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:26.364414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.247965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:13:26.364433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:25.927525Z","time spent":"436.828872ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:13:26.364482Z","caller":"traceutil/trace.go:171","msg":"trace[1084709944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2141; }","duration":"602.362257ms","start":"2026-04-07T00:13:25.762102Z","end":"2026-04-07T00:13:26.364464Z","steps":["trace[1084709944] 'agreement among raft nodes before linearized reading' (duration: 602.250815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.36452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:25.762081Z","time spent":"602.431321ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:13:26.364569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.030031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:13:26.364652Z","caller":"traceutil/trace.go:171","msg":"trace[1225161680] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2141; }","duration":"100.200585ms","start":"2026-04-07T00:13:26.26443Z","end":"2026-04-07T00:13:26.36463Z","steps":["trace[1225161680] 'agreement among raft nodes before linearized reading' (duration: 100.010599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.36477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.45528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a3eabf6dc2808b\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-07T00:13:26.364805Z","caller":"traceutil/trace.go:171","msg":"trace[2017857779] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a3eabf6dc2808b; range_end:; response_count:1; response_revision:2141; }","duration":"282.542431ms","start":"2026-04-07T00:13:26.082251Z","end":"2026-04-07T00:13:26.364793Z","steps":["trace[2017857779] 'agreement among raft nodes before linearized reading' (duration: 282.422129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.364948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.209729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T00:13:26.364955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.651037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:13:26.364991Z","caller":"traceutil/trace.go:171","msg":"trace[258791804] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2141; }","duration":"669.297181ms","start":"2026-04-07T00:13:25.695684Z","end":"2026-04-07T00:13:26.364982Z","steps":["trace[258791804] 'agreement among raft nodes before linearized reading' (duration: 669.212159ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:26.365Z","caller":"traceutil/trace.go:171","msg":"trace[1117998826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2141; }","duration":"579.717558ms","start":"2026-04-07T00:13:25.78527Z","end":"2026-04-07T00:13:26.364988Z","steps":["trace[1117998826] 'agreement among raft nodes before linearized reading' (duration: 579.572425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.365023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:25.695662Z","time spent":"669.352173ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":22,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:13:26.365035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:25.785262Z","time spent":"579.765979ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:13:26.3651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.384706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T00:13:26.365208Z","caller":"traceutil/trace.go:171","msg":"trace[406756120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2141; }","duration":"580.57279ms","start":"2026-04-07T00:13:25.784609Z","end":"2026-04-07T00:13:26.365182Z","steps":["trace[406756120] 'agreement among raft nodes before linearized reading' (duration: 580.219451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.36527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:25.784581Z","time spent":"580.678124ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:13:26.832507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.312031ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:13:26.832647Z","caller":"traceutil/trace.go:171","msg":"trace[1610274312] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2141; }","duration":"288.472565ms","start":"2026-04-07T00:13:26.544153Z","end":"2026-04-07T00:13:26.832626Z","steps":["trace[1610274312] 'range keys from in-memory index tree' (duration: 288.29093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.833026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.05215ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-07T00:13:26.833532Z","caller":"traceutil/trace.go:171","msg":"trace[472391980] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"464.0991ms","start":"2026-04-07T00:13:26.369406Z","end":"2026-04-07T00:13:26.833505Z","steps":["trace[472391980] 'process raft request' (duration: 463.744211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.833541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.368206Z","time spent":"465.313811ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T00:13:26.833565Z","caller":"traceutil/trace.go:171","msg":"trace[465217904] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"463.985497ms","start":"2026-04-07T00:13:26.369553Z","end":"2026-04-07T00:13:26.833538Z","steps":["trace[465217904] 'process raft request' (duration: 463.854434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.833681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.369386Z","time spent":"464.208433ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:13:26.83371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.369517Z","time spent":"464.105711ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:13:26.833778Z","caller":"traceutil/trace.go:171","msg":"trace[111095650] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2224; }","duration":"404.932147ms","start":"2026-04-07T00:13:26.428826Z","end":"2026-04-07T00:13:26.833758Z","steps":["trace[111095650] 'read index received' (duration: 256.141028ms)","trace[111095650] 'applied index is now lower than readState.Index' (duration: 148.789949ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:26.833772Z","caller":"traceutil/trace.go:171","msg":"trace[1752506949] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"463.920627ms","start":"2026-04-07T00:13:26.369826Z","end":"2026-04-07T00:13:26.833746Z","steps":["trace[1752506949] 'process raft request' (duration: 463.657089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.833991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.149682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T00:13:26.834043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.369817Z","time spent":"464.165592ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:13:26.834038Z","caller":"traceutil/trace.go:171","msg":"trace[43648394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2145; }","duration":"405.240444ms","start":"2026-04-07T00:13:26.428785Z","end":"2026-04-07T00:13:26.834026Z","steps":["trace[43648394] 'agreement among raft nodes before linearized reading' (duration: 405.031219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:26.833863Z","caller":"traceutil/trace.go:171","msg":"trace[1096978086] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"140.351033ms","start":"2026-04-07T00:13:26.693501Z","end":"2026-04-07T00:13:26.833852Z","steps":["trace[1096978086] 'process raft request' (duration: 140.189279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.834122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.428768Z","time spent":"405.341137ms","remote":"127.0.0.1:36900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:13:26.834147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.835696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"warn","ts":"2026-04-07T00:13:26.834202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.379698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T00:13:26.834215Z","caller":"traceutil/trace.go:171","msg":"trace[1152873811] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2145; }","duration":"335.925708ms","start":"2026-04-07T00:13:26.498258Z","end":"2026-04-07T00:13:26.834183Z","steps":["trace[1152873811] 'agreement among raft nodes before linearized reading' (duration: 335.748294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.834205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.472619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T00:13:26.834249Z","caller":"traceutil/trace.go:171","msg":"trace[818529963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2145; }","duration":"123.468311ms","start":"2026-04-07T00:13:26.710768Z","end":"2026-04-07T00:13:26.834236Z","steps":["trace[818529963] 'agreement among raft nodes before linearized reading' (duration: 123.252525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.834252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.498236Z","time spent":"336.008761ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-07T00:13:26.834272Z","caller":"traceutil/trace.go:171","msg":"trace[973649788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2145; }","duration":"196.578412ms","start":"2026-04-07T00:13:26.637669Z","end":"2026-04-07T00:13:26.834247Z","steps":["trace[973649788] 'agreement among raft nodes before linearized reading' (duration: 196.353385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.834281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.937449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:13:26.834303Z","caller":"traceutil/trace.go:171","msg":"trace[1043579864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2145; }","duration":"200.00083ms","start":"2026-04-07T00:13:26.634295Z","end":"2026-04-07T00:13:26.834296Z","steps":["trace[1043579864] 'agreement among raft nodes before linearized reading' (duration: 199.929057ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:39.159569Z","caller":"traceutil/trace.go:171","msg":"trace[1551126741] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"138.561177ms","start":"2026-04-07T00:13:39.020982Z","end":"2026-04-07T00:13:39.159543Z","steps":["trace[1551126741] 'process raft request' (duration: 107.934603ms)","trace[1551126741] 'compare' (duration: 30.406278ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:39.159649Z","caller":"traceutil/trace.go:171","msg":"trace[1705536680] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2291; }","duration":"117.704724ms","start":"2026-04-07T00:13:39.041914Z","end":"2026-04-07T00:13:39.159618Z","steps":["trace[1705536680] 'read index received' (duration: 86.938616ms)","trace[1705536680] 'applied index is now lower than readState.Index' (duration: 30.764788ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:39.159609Z","caller":"traceutil/trace.go:171","msg":"trace[535453446] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"138.517386ms","start":"2026-04-07T00:13:39.021071Z","end":"2026-04-07T00:13:39.159589Z","steps":["trace[535453446] 'process raft request' (duration: 138.376473ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:39.159745Z","caller":"traceutil/trace.go:171","msg":"trace[1745971357] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"138.64914ms","start":"2026-04-07T00:13:39.021087Z","end":"2026-04-07T00:13:39.159737Z","steps":["trace[1745971357] 'process raft request' (duration: 138.407464ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:39.159701Z","caller":"traceutil/trace.go:171","msg":"trace[1239238836] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"138.505966ms","start":"2026-04-07T00:13:39.021177Z","end":"2026-04-07T00:13:39.159683Z","steps":["trace[1239238836] 'process raft request' (duration: 138.352583ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:39.159782Z","caller":"traceutil/trace.go:171","msg":"trace[855955037] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"138.376162ms","start":"2026-04-07T00:13:39.021391Z","end":"2026-04-07T00:13:39.159768Z","steps":["trace[855955037] 'process raft request' (duration: 138.176008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:39.159983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.052472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-04-07T00:13:39.160016Z","caller":"traceutil/trace.go:171","msg":"trace[2080013400] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2210; }","duration":"118.097923ms","start":"2026-04-07T00:13:39.041909Z","end":"2026-04-07T00:13:39.160007Z","steps":["trace[2080013400] 'agreement among raft nodes before linearized reading' (duration: 117.94871ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:39.326582Z","caller":"traceutil/trace.go:171","msg":"trace[143353267] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"105.366187ms","start":"2026-04-07T00:13:39.221189Z","end":"2026-04-07T00:13:39.326555Z","steps":["trace[143353267] 'process raft request' (duration: 75.972774ms)","trace[143353267] 'compare' (duration: 29.250599ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:14:11.339998Z","caller":"traceutil/trace.go:171","msg":"trace[774840035] linearizableReadLoop","detail":"{readStateIndex:2617; appliedIndex:2616; }","duration":"225.116923ms","start":"2026-04-07T00:14:11.114847Z","end":"2026-04-07T00:14:11.339964Z","steps":["trace[774840035] 'read index received' (duration: 224.853705ms)","trace[774840035] 'applied index is now lower than readState.Index' (duration: 262.038µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:14:11.340566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.054857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:14:11.340657Z","caller":"traceutil/trace.go:171","msg":"trace[1932309143] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2516; }","duration":"134.196541ms","start":"2026-04-07T00:14:11.206447Z","end":"2026-04-07T00:14:11.340643Z","steps":["trace[1932309143] 'agreement among raft nodes before linearized reading' (duration: 134.023126ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:11.340723Z","caller":"traceutil/trace.go:171","msg":"trace[964090755] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"331.761777ms","start":"2026-04-07T00:14:11.008934Z","end":"2026-04-07T00:14:11.340696Z","steps":["trace[964090755] 'process raft request' (duration: 330.843922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:11.34073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.858951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/\" range_end:\"/registry/secrets/auth-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:14:11.340832Z","caller":"traceutil/trace.go:171","msg":"trace[2113293412] range","detail":"{range_begin:/registry/secrets/auth-system/; range_end:/registry/secrets/auth-system0; response_count:0; response_revision:2516; }","duration":"226.023835ms","start":"2026-04-07T00:14:11.114784Z","end":"2026-04-07T00:14:11.340808Z","steps":["trace[2113293412] 'agreement among raft nodes before linearized reading' (duration: 225.862451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:11.340887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:14:11.008904Z","time spent":"331.88587ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:14:11.91764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.829331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:14:11.91794Z","caller":"traceutil/trace.go:171","msg":"trace[935293758] linearizableReadLoop","detail":"{readStateIndex:2624; appliedIndex:2623; }","duration":"387.150186ms","start":"2026-04-07T00:14:11.530764Z","end":"2026-04-07T00:14:11.917914Z","steps":["trace[935293758] 'read index received' (duration: 55.664028ms)","trace[935293758] 'applied index is now lower than readState.Index' (duration: 331.484048ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:14:11.918107Z","caller":"traceutil/trace.go:171","msg":"trace[458800025] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"428.782523ms","start":"2026-04-07T00:14:11.489307Z","end":"2026-04-07T00:14:11.91809Z","steps":["trace[458800025] 'process raft request' (duration: 97.281914ms)","trace[458800025] 'compare' (duration: 330.610026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:14:11.918289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.133499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:14:11.918273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.983446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:14:11.918223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:14:11.489278Z","time spent":"428.885175ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:14:11.918409Z","caller":"traceutil/trace.go:171","msg":"trace[1920576833] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak; range_end:; response_count:0; response_revision:2523; }","duration":"331.160141ms","start":"2026-04-07T00:14:11.587222Z","end":"2026-04-07T00:14:11.918383Z","steps":["trace[1920576833] 'agreement among raft nodes before linearized reading' (duration: 330.960875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:11.918265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.461854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:14:11.918745Z","caller":"traceutil/trace.go:171","msg":"trace[1344818695] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2523; }","duration":"387.982597ms","start":"2026-04-07T00:14:11.530745Z","end":"2026-04-07T00:14:11.918728Z","steps":["trace[1344818695] 'agreement among raft nodes before linearized reading' (duration: 387.403192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:11.918281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.410909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:14:11.918818Z","caller":"traceutil/trace.go:171","msg":"trace[278275180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2523; }","duration":"155.010834ms","start":"2026-04-07T00:14:11.76379Z","end":"2026-04-07T00:14:11.918801Z","steps":["trace[278275180] 'agreement among raft nodes before linearized reading' (duration: 154.406268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:11.91882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:14:11.530718Z","time spent":"388.07918ms","remote":"127.0.0.1:36870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:14:11.918376Z","caller":"traceutil/trace.go:171","msg":"trace[216816861] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2523; }","duration":"187.239481ms","start":"2026-04-07T00:14:11.73112Z","end":"2026-04-07T00:14:11.91836Z","steps":["trace[216816861] 'agreement among raft nodes before linearized reading' (duration: 187.069196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:11.918738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:14:11.587196Z","time spent":"331.52778ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/auth-system/keycloak\" "} {"level":"info","ts":"2026-04-07T00:14:15.4862Z","caller":"traceutil/trace.go:171","msg":"trace[26654187] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"103.422392ms","start":"2026-04-07T00:14:15.382753Z","end":"2026-04-07T00:14:15.486176Z","steps":["trace[26654187] 'process raft request' (duration: 103.268719ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:16.359396Z","caller":"traceutil/trace.go:171","msg":"trace[76907524] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"411.694546ms","start":"2026-04-07T00:14:15.947676Z","end":"2026-04-07T00:14:16.359371Z","steps":["trace[76907524] 'process raft request' (duration: 411.502931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:16.359602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:14:15.94765Z","time spent":"411.824799ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:14:16.38693Z","caller":"traceutil/trace.go:171","msg":"trace[1338819039] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"107.432424ms","start":"2026-04-07T00:14:16.279443Z","end":"2026-04-07T00:14:16.386875Z","steps":["trace[1338819039] 'process raft request' (duration: 106.925041ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:27.149236Z","caller":"traceutil/trace.go:171","msg":"trace[1956352779] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"250.80984ms","start":"2026-04-07T00:14:26.898395Z","end":"2026-04-07T00:14:27.149205Z","steps":["trace[1956352779] 'process raft request' (duration: 250.607155ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:48.623023Z","caller":"traceutil/trace.go:171","msg":"trace[775205725] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"106.689547ms","start":"2026-04-07T00:14:48.516286Z","end":"2026-04-07T00:14:48.622975Z","steps":["trace[775205725] 'process raft request' (duration: 106.494232ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:49.344378Z","caller":"traceutil/trace.go:171","msg":"trace[502429019] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"173.875838ms","start":"2026-04-07T00:14:49.170477Z","end":"2026-04-07T00:14:49.344353Z","steps":["trace[502429019] 'process raft request' (duration: 173.734244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:25.416932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.626653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:17:25.417053Z","caller":"traceutil/trace.go:171","msg":"trace[447724342] linearizableReadLoop","detail":"{readStateIndex:3949; appliedIndex:3948; }","duration":"528.387343ms","start":"2026-04-07T00:17:24.888646Z","end":"2026-04-07T00:17:25.417034Z","steps":["trace[447724342] 'read index received' (duration: 336.593996ms)","trace[447724342] 'applied index is now lower than readState.Index' (duration: 191.791937ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:25.417128Z","caller":"traceutil/trace.go:171","msg":"trace[709354036] transaction","detail":"{read_only:false; response_revision:3802; number_of_response:1; }","duration":"531.285297ms","start":"2026-04-07T00:17:24.885832Z","end":"2026-04-07T00:17:25.417117Z","steps":["trace[709354036] 'process raft request' (duration: 339.377647ms)","trace[709354036] 'compare' (duration: 191.250033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:17:25.417191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:17:24.885799Z","time spent":"531.360619ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:17:25.417568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.892296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:17:25.417666Z","caller":"traceutil/trace.go:171","msg":"trace[201237441] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3802; }","duration":"529.004429ms","start":"2026-04-07T00:17:24.888633Z","end":"2026-04-07T00:17:25.417638Z","steps":["trace[201237441] 'agreement among raft nodes before linearized reading' (duration: 528.617669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:25.41772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:17:24.888616Z","time spent":"529.09317ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:17:25.480841Z","caller":"traceutil/trace.go:171","msg":"trace[146454882] transaction","detail":"{read_only:false; response_revision:3803; number_of_response:1; }","duration":"591.369573ms","start":"2026-04-07T00:17:24.889443Z","end":"2026-04-07T00:17:25.480813Z","steps":["trace[146454882] 'process raft request' (duration: 591.169048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:25.48104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:17:24.889431Z","time spent":"591.520588ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:17:25.4811Z","caller":"traceutil/trace.go:171","msg":"trace[552617115] transaction","detail":"{read_only:false; response_revision:3804; number_of_response:1; }","duration":"294.777608ms","start":"2026-04-07T00:17:25.186287Z","end":"2026-04-07T00:17:25.481064Z","steps":["trace[552617115] 'process raft request' (duration: 294.47413ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:25.597243Z","caller":"traceutil/trace.go:171","msg":"trace[757179033] linearizableReadLoop","detail":"{readStateIndex:3952; appliedIndex:3950; }","duration":"180.134248ms","start":"2026-04-07T00:17:25.417079Z","end":"2026-04-07T00:17:25.597213Z","steps":["trace[757179033] 'read index received' (duration: 63.68523ms)","trace[757179033] 'applied index is now lower than readState.Index' (duration: 116.447798ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:25.597273Z","caller":"traceutil/trace.go:171","msg":"trace[838921027] transaction","detail":"{read_only:false; response_revision:3806; number_of_response:1; }","duration":"174.518004ms","start":"2026-04-07T00:17:25.422729Z","end":"2026-04-07T00:17:25.597247Z","steps":["trace[838921027] 'process raft request' (duration: 174.41754ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:25.597414Z","caller":"traceutil/trace.go:171","msg":"trace[1840289803] transaction","detail":"{read_only:false; response_revision:3805; number_of_response:1; }","duration":"303.73037ms","start":"2026-04-07T00:17:25.293672Z","end":"2026-04-07T00:17:25.597402Z","steps":["trace[1840289803] 'process raft request' (duration: 291.210957ms)","trace[1840289803] 'compare' (duration: 12.03279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:17:25.597465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.23897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kfcpn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:17:25.597554Z","caller":"traceutil/trace.go:171","msg":"trace[1962211441] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kfcpn; range_end:; response_count:1; response_revision:3806; }","duration":"396.383384ms","start":"2026-04-07T00:17:25.201147Z","end":"2026-04-07T00:17:25.597531Z","steps":["trace[1962211441] 'agreement among raft nodes before linearized reading' (duration: 396.106237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:25.597558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:17:25.293644Z","time spent":"303.819841ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:17:25.597617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:17:25.201126Z","time spent":"396.475276ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-kfcpn\" "} {"level":"warn","ts":"2026-04-07T00:17:25.597715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.15017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T00:17:25.597793Z","caller":"traceutil/trace.go:171","msg":"trace[1614511453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3806; }","duration":"708.294073ms","start":"2026-04-07T00:17:24.889484Z","end":"2026-04-07T00:17:25.597778Z","steps":["trace[1614511453] 'agreement among raft nodes before linearized reading' (duration: 707.874903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:25.597853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:17:24.889468Z","time spent":"708.360245ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T00:18:39.798734Z","caller":"traceutil/trace.go:171","msg":"trace[1240161995] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"128.795646ms","start":"2026-04-07T00:18:39.669914Z","end":"2026-04-07T00:18:39.79871Z","steps":["trace[1240161995] 'process raft request' (duration: 128.628862ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:39.804738Z","caller":"traceutil/trace.go:171","msg":"trace[1225066649] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"127.541274ms","start":"2026-04-07T00:18:39.677139Z","end":"2026-04-07T00:18:39.80468Z","steps":["trace[1225066649] 'process raft request' (duration: 127.353749ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:40.100065Z","caller":"traceutil/trace.go:171","msg":"trace[755104371] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4848; }","duration":"115.563226ms","start":"2026-04-07T00:18:39.98447Z","end":"2026-04-07T00:18:40.100034Z","steps":["trace[755104371] 'read index received' (duration: 41.974011ms)","trace[755104371] 'applied index is now lower than readState.Index' (duration: 73.584644ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:40.100094Z","caller":"traceutil/trace.go:171","msg":"trace[853284351] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"118.448689ms","start":"2026-04-07T00:18:39.98161Z","end":"2026-04-07T00:18:40.100059Z","steps":["trace[853284351] 'process raft request' (duration: 44.748142ms)","trace[853284351] 'compare' (duration: 73.522803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:18:40.100442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.942975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62879"} {"level":"info","ts":"2026-04-07T00:18:40.100486Z","caller":"traceutil/trace.go:171","msg":"trace[1643552626] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4665; }","duration":"116.033026ms","start":"2026-04-07T00:18:39.984442Z","end":"2026-04-07T00:18:40.100476Z","steps":["trace[1643552626] 'agreement among raft nodes before linearized reading' (duration: 115.745649ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:44.436129Z","caller":"traceutil/trace.go:171","msg":"trace[1328598987] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"141.37618ms","start":"2026-04-07T00:18:44.294727Z","end":"2026-04-07T00:18:44.436103Z","steps":["trace[1328598987] 'process raft request' (duration: 141.073263ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:46.471594Z","caller":"traceutil/trace.go:171","msg":"trace[810823394] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"129.620267ms","start":"2026-04-07T00:18:46.341951Z","end":"2026-04-07T00:18:46.471571Z","steps":["trace[810823394] 'process raft request' (duration: 129.482773ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:46.67721Z","caller":"traceutil/trace.go:171","msg":"trace[2078890411] linearizableReadLoop","detail":"{readStateIndex:5033; appliedIndex:5032; }","duration":"113.735839ms","start":"2026-04-07T00:18:46.563445Z","end":"2026-04-07T00:18:46.677181Z","steps":["trace[2078890411] 'read index received' (duration: 113.560714ms)","trace[2078890411] 'applied index is now lower than readState.Index' (duration: 173.855µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:46.677351Z","caller":"traceutil/trace.go:171","msg":"trace[1244906144] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"192.355963ms","start":"2026-04-07T00:18:46.484984Z","end":"2026-04-07T00:18:46.67734Z","steps":["trace[1244906144] 'process raft request' (duration: 192.014864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:46.677703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.246391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-07T00:18:46.67778Z","caller":"traceutil/trace.go:171","msg":"trace[2963613] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4845; }","duration":"114.361074ms","start":"2026-04-07T00:18:46.563402Z","end":"2026-04-07T00:18:46.677763Z","steps":["trace[2963613] 'agreement among raft nodes before linearized reading' (duration: 114.208741ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:46.864219Z","caller":"traceutil/trace.go:171","msg":"trace[1368732708] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"173.679532ms","start":"2026-04-07T00:18:46.69051Z","end":"2026-04-07T00:18:46.864189Z","steps":["trace[1368732708] 'read index received' (duration: 104.281995ms)","trace[1368732708] 'applied index is now lower than readState.Index' (duration: 69.396187ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:46.864293Z","caller":"traceutil/trace.go:171","msg":"trace[837531568] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"175.592722ms","start":"2026-04-07T00:18:46.688681Z","end":"2026-04-07T00:18:46.864274Z","steps":["trace[837531568] 'process raft request' (duration: 106.133213ms)","trace[837531568] 'compare' (duration: 69.201761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:18:46.86459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.045361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-07T00:18:46.864711Z","caller":"traceutil/trace.go:171","msg":"trace[667154095] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4846; }","duration":"174.195625ms","start":"2026-04-07T00:18:46.690495Z","end":"2026-04-07T00:18:46.864691Z","steps":["trace[667154095] 'agreement among raft nodes before linearized reading' (duration: 173.843376ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:46.867015Z","caller":"traceutil/trace.go:171","msg":"trace[1450882642] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"172.313927ms","start":"2026-04-07T00:18:46.694679Z","end":"2026-04-07T00:18:46.866993Z","steps":["trace[1450882642] 'process raft request' (duration: 172.171412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:46.867683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.538386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196043"} {"level":"info","ts":"2026-04-07T00:18:46.867738Z","caller":"traceutil/trace.go:171","msg":"trace[194541915] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4847; }","duration":"150.642478ms","start":"2026-04-07T00:18:46.717083Z","end":"2026-04-07T00:18:46.867725Z","steps":["trace[194541915] 'agreement among raft nodes before linearized reading' (duration: 149.839678ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:48.332268Z","caller":"traceutil/trace.go:171","msg":"trace[2140787213] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"278.099351ms","start":"2026-04-07T00:18:48.054136Z","end":"2026-04-07T00:18:48.332235Z","steps":["trace[2140787213] 'process raft request' (duration: 277.912336ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:48.33267Z","caller":"traceutil/trace.go:171","msg":"trace[99999681] linearizableReadLoop","detail":"{readStateIndex:5070; appliedIndex:5070; }","duration":"187.857286ms","start":"2026-04-07T00:18:48.144779Z","end":"2026-04-07T00:18:48.332636Z","steps":["trace[99999681] 'read index received' (duration: 187.845396ms)","trace[99999681] 'applied index is now lower than readState.Index' (duration: 9.2µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:18:48.332979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.150335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kfcpn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:18:48.333026Z","caller":"traceutil/trace.go:171","msg":"trace[1331123951] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kfcpn; range_end:; response_count:1; response_revision:4881; }","duration":"188.257997ms","start":"2026-04-07T00:18:48.144757Z","end":"2026-04-07T00:18:48.333015Z","steps":["trace[1331123951] 'agreement among raft nodes before linearized reading' (duration: 188.037771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:48.33315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.137496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-whg9x.18a3eb0edb60b2d7\" ","response":"range_response_count:1 size:962"} {"level":"warn","ts":"2026-04-07T00:18:48.333183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.38934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T00:18:48.333214Z","caller":"traceutil/trace.go:171","msg":"trace[374921050] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-whg9x.18a3eb0edb60b2d7; range_end:; response_count:1; response_revision:4881; }","duration":"164.25681ms","start":"2026-04-07T00:18:48.168941Z","end":"2026-04-07T00:18:48.333198Z","steps":["trace[374921050] 'agreement among raft nodes before linearized reading' (duration: 164.078706ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:48.333266Z","caller":"traceutil/trace.go:171","msg":"trace[658877802] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4881; }","duration":"188.497542ms","start":"2026-04-07T00:18:48.144723Z","end":"2026-04-07T00:18:48.333221Z","steps":["trace[658877802] 'agreement among raft nodes before linearized reading' (duration: 188.322688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:48.334443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.147749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-whg9x\" ","response":"range_response_count:1 size:9830"} {"level":"info","ts":"2026-04-07T00:18:48.339734Z","caller":"traceutil/trace.go:171","msg":"trace[1995716975] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-whg9x; range_end:; response_count:1; response_revision:4881; }","duration":"171.323471ms","start":"2026-04-07T00:18:48.168217Z","end":"2026-04-07T00:18:48.33954Z","steps":["trace[1995716975] 'agreement among raft nodes before linearized reading' (duration: 165.175883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:51.964285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400042548754297,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:18:51.97301Z","caller":"traceutil/trace.go:171","msg":"trace[538116458] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"509.829177ms","start":"2026-04-07T00:18:51.463161Z","end":"2026-04-07T00:18:51.97299Z","steps":["trace[538116458] 'process raft request' (duration: 509.014827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:51.973117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:51.46314Z","time spent":"509.912799ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:18:51.987658Z","caller":"traceutil/trace.go:171","msg":"trace[33249946] linearizableReadLoop","detail":"{readStateIndex:5213; appliedIndex:5209; }","duration":"523.730916ms","start":"2026-04-07T00:18:51.463902Z","end":"2026-04-07T00:18:51.987633Z","steps":["trace[33249946] 'read index received' (duration: 508.291588ms)","trace[33249946] 'applied index is now lower than readState.Index' (duration: 15.437808ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:51.987665Z","caller":"traceutil/trace.go:171","msg":"trace[443025681] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"524.340231ms","start":"2026-04-07T00:18:51.463283Z","end":"2026-04-07T00:18:51.987623Z","steps":["trace[443025681] 'process raft request' (duration: 524.077724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:51.987718Z","caller":"traceutil/trace.go:171","msg":"trace[620987960] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"144.154452ms","start":"2026-04-07T00:18:51.843546Z","end":"2026-04-07T00:18:51.9877Z","steps":["trace[620987960] 'process raft request' (duration: 143.947707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:51.987845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:51.463263Z","time spent":"524.494345ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:18:51.987994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.576046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-07T00:18:51.988042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.118615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-58zt2\" ","response":"range_response_count:1 size:5852"} {"level":"warn","ts":"2026-04-07T00:18:51.988157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.450078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-07T00:18:51.98813Z","caller":"traceutil/trace.go:171","msg":"trace[639419833] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"105.615669ms","start":"2026-04-07T00:18:51.882133Z","end":"2026-04-07T00:18:51.987748Z","steps":["trace[639419833] 'process raft request' (duration: 105.403024ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:51.988175Z","caller":"traceutil/trace.go:171","msg":"trace[490490535] range","detail":"{range_begin:/registry/pods/monitoring/vector-58zt2; range_end:; response_count:1; response_revision:5020; }","duration":"524.30103ms","start":"2026-04-07T00:18:51.463858Z","end":"2026-04-07T00:18:51.988159Z","steps":["trace[490490535] 'agreement among raft nodes before linearized reading' (duration: 523.91362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:51.988194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.532551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777453"} {"level":"info","ts":"2026-04-07T00:18:51.988203Z","caller":"traceutil/trace.go:171","msg":"trace[1906685013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38; range_end:; response_count:1; response_revision:5020; }","duration":"335.52393ms","start":"2026-04-07T00:18:51.652667Z","end":"2026-04-07T00:18:51.988191Z","steps":["trace[1906685013] 'agreement among raft nodes before linearized reading' (duration: 335.430488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:51.988227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:51.463836Z","time spent":"524.378352ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":5875,"request content":"key:\"/registry/pods/monitoring/vector-58zt2\" "} {"level":"warn","ts":"2026-04-07T00:18:51.988247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:51.652649Z","time spent":"335.590041ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38\" "} {"level":"info","ts":"2026-04-07T00:18:51.988243Z","caller":"traceutil/trace.go:171","msg":"trace[1018902699] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5020; }","duration":"490.616963ms","start":"2026-04-07T00:18:51.497615Z","end":"2026-04-07T00:18:51.988232Z","steps":["trace[1018902699] 'agreement among raft nodes before linearized reading' (duration: 490.225083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:51.988281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:51.497593Z","time spent":"490.682184ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":777476,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-07T00:18:51.988056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.238223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T00:18:51.988416Z","caller":"traceutil/trace.go:171","msg":"trace[956721836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5020; }","duration":"102.623922ms","start":"2026-04-07T00:18:51.885781Z","end":"2026-04-07T00:18:51.988405Z","steps":["trace[956721836] 'agreement among raft nodes before linearized reading' (duration: 102.188451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:51.988066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.545759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-07T00:18:51.988608Z","caller":"traceutil/trace.go:171","msg":"trace[1811392947] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5020; }","duration":"221.110384ms","start":"2026-04-07T00:18:51.767475Z","end":"2026-04-07T00:18:51.988585Z","steps":["trace[1811392947] 'agreement among raft nodes before linearized reading' (duration: 220.431546ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:51.988049Z","caller":"traceutil/trace.go:171","msg":"trace[557607005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5020; }","duration":"108.680998ms","start":"2026-04-07T00:18:51.879357Z","end":"2026-04-07T00:18:51.988038Z","steps":["trace[557607005] 'agreement among raft nodes before linearized reading' (duration: 108.503364ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:52.476667Z","caller":"traceutil/trace.go:171","msg":"trace[1584173998] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"273.599824ms","start":"2026-04-07T00:18:52.203046Z","end":"2026-04-07T00:18:52.476646Z","steps":["trace[1584173998] 'read index received' (duration: 273.527362ms)","trace[1584173998] 'applied index is now lower than readState.Index' (duration: 71.562µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:52.476958Z","caller":"traceutil/trace.go:171","msg":"trace[2128718176] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"481.477107ms","start":"2026-04-07T00:18:51.995469Z","end":"2026-04-07T00:18:52.476946Z","steps":["trace[2128718176] 'process raft request' (duration: 481.048136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:52.477059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:51.99545Z","time spent":"481.540118ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:18:52.477342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.267441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-07T00:18:52.477432Z","caller":"traceutil/trace.go:171","msg":"trace[2138149958] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38; range_end:; response_count:1; response_revision:5022; }","duration":"274.405285ms","start":"2026-04-07T00:18:52.203007Z","end":"2026-04-07T00:18:52.477413Z","steps":["trace[2138149958] 'agreement among raft nodes before linearized reading' (duration: 274.155988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:52.519544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.945181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T00:18:52.519606Z","caller":"traceutil/trace.go:171","msg":"trace[1752607874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5024; }","duration":"176.020403ms","start":"2026-04-07T00:18:52.343571Z","end":"2026-04-07T00:18:52.519591Z","steps":["trace[1752607874] 'agreement among raft nodes before linearized reading' (duration: 175.767036ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:52.520156Z","caller":"traceutil/trace.go:171","msg":"trace[1071197804] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"107.302383ms","start":"2026-04-07T00:18:52.412847Z","end":"2026-04-07T00:18:52.520149Z","steps":["trace[1071197804] 'process raft request' (duration: 103.796383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:52.5205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.093246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:18:52.520529Z","caller":"traceutil/trace.go:171","msg":"trace[754137525] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5024; }","duration":"152.153187ms","start":"2026-04-07T00:18:52.368368Z","end":"2026-04-07T00:18:52.520521Z","steps":["trace[754137525] 'agreement among raft nodes before linearized reading' (duration: 152.086876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:52.520642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.773679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kfcpn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:18:52.520666Z","caller":"traceutil/trace.go:171","msg":"trace[109924397] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kfcpn; range_end:; response_count:1; response_revision:5024; }","duration":"153.82403ms","start":"2026-04-07T00:18:52.366837Z","end":"2026-04-07T00:18:52.520661Z","steps":["trace[109924397] 'agreement among raft nodes before linearized reading' (duration: 153.760148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:52.975922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.045236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:18:52.976079Z","caller":"traceutil/trace.go:171","msg":"trace[1706961782] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"301.731289ms","start":"2026-04-07T00:18:52.674316Z","end":"2026-04-07T00:18:52.976047Z","steps":["trace[1706961782] 'process raft request' (duration: 154.483647ms)","trace[1706961782] 'compare' (duration: 146.803811ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:52.976254Z","caller":"traceutil/trace.go:171","msg":"trace[1311781575] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5220; }","duration":"254.809111ms","start":"2026-04-07T00:18:52.72142Z","end":"2026-04-07T00:18:52.97623Z","steps":["trace[1311781575] 'read index received' (duration: 107.391535ms)","trace[1311781575] 'applied index is now lower than readState.Index' (duration: 147.415186ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:18:52.976285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:52.674295Z","time spent":"301.859712ms","remote":"127.0.0.1:35670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:18:52.976397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.960554ms","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-04-07T00:18:52.976435Z","caller":"traceutil/trace.go:171","msg":"trace[1937632047] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5029; }","duration":"255.047567ms","start":"2026-04-07T00:18:52.721378Z","end":"2026-04-07T00:18:52.976425Z","steps":["trace[1937632047] 'agreement among raft nodes before linearized reading' (duration: 254.934194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:52.97653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.57409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:18:52.976605Z","caller":"traceutil/trace.go:171","msg":"trace[1559499072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5030; }","duration":"215.686064ms","start":"2026-04-07T00:18:52.760902Z","end":"2026-04-07T00:18:52.976588Z","steps":["trace[1559499072] 'agreement among raft nodes before linearized reading' (duration: 215.52383ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:52.976946Z","caller":"traceutil/trace.go:171","msg":"trace[1686978882] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"192.772494ms","start":"2026-04-07T00:18:52.783656Z","end":"2026-04-07T00:18:52.976428Z","steps":["trace[1686978882] 'process raft request' (duration: 192.393634ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:53.332737Z","caller":"traceutil/trace.go:171","msg":"trace[1099887210] linearizableReadLoop","detail":"{readStateIndex:5224; appliedIndex:5223; }","duration":"342.078887ms","start":"2026-04-07T00:18:52.99064Z","end":"2026-04-07T00:18:53.332719Z","steps":["trace[1099887210] 'read index received' (duration: 342.019986ms)","trace[1099887210] 'applied index is now lower than readState.Index' (duration: 58.141µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:53.332765Z","caller":"traceutil/trace.go:171","msg":"trace[2123911387] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"348.237346ms","start":"2026-04-07T00:18:52.984508Z","end":"2026-04-07T00:18:53.332746Z","steps":["trace[2123911387] 'process raft request' (duration: 348.095593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:53.332947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.309183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"warn","ts":"2026-04-07T00:18:53.332945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.144061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:18:53.332988Z","caller":"traceutil/trace.go:171","msg":"trace[253103605] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5031; }","duration":"342.378207ms","start":"2026-04-07T00:18:52.990598Z","end":"2026-04-07T00:18:53.332977Z","steps":["trace[253103605] 'agreement among raft nodes before linearized reading' (duration: 342.234582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:53.333016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:52.990585Z","time spent":"342.423307ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T00:18:53.333012Z","caller":"traceutil/trace.go:171","msg":"trace[1645580368] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5031; }","duration":"210.258735ms","start":"2026-04-07T00:18:53.122732Z","end":"2026-04-07T00:18:53.33299Z","steps":["trace[1645580368] 'agreement among raft nodes before linearized reading' (duration: 210.139841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:53.333036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.174297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-04-07T00:18:53.333118Z","caller":"traceutil/trace.go:171","msg":"trace[1044965964] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38; range_end:; response_count:1; response_revision:5031; }","duration":"338.303732ms","start":"2026-04-07T00:18:52.994796Z","end":"2026-04-07T00:18:53.3331Z","steps":["trace[1044965964] 'agreement among raft nodes before linearized reading' (duration: 338.072485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:53.333166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:52.994779Z","time spent":"338.377063ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3ca0a0-0a24-47af-afc4-da1be5b76f38\" "} {"level":"warn","ts":"2026-04-07T00:18:53.33342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:18:52.9845Z","time spent":"348.311328ms","remote":"127.0.0.1:35636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2501,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:18:56.669004Z","caller":"traceutil/trace.go:171","msg":"trace[2127622056] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"161.522409ms","start":"2026-04-07T00:18:56.50747Z","end":"2026-04-07T00:18:56.668992Z","steps":["trace[2127622056] 'process raft request' (duration: 161.338274ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:56.788617Z","caller":"traceutil/trace.go:171","msg":"trace[335976761] linearizableReadLoop","detail":"{readStateIndex:5309; appliedIndex:5308; }","duration":"119.701002ms","start":"2026-04-07T00:18:56.66889Z","end":"2026-04-07T00:18:56.788591Z","steps":["trace[335976761] 'read index received' (duration: 103.066474ms)","trace[335976761] 'applied index is now lower than readState.Index' (duration: 16.633678ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:56.788632Z","caller":"traceutil/trace.go:171","msg":"trace[1747347419] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"168.149649ms","start":"2026-04-07T00:18:56.620441Z","end":"2026-04-07T00:18:56.788591Z","steps":["trace[1747347419] 'process raft request' (duration: 151.508601ms)","trace[1747347419] 'compare' (duration: 16.455253ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:56.788758Z","caller":"traceutil/trace.go:171","msg":"trace[1674763905] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"115.621588ms","start":"2026-04-07T00:18:56.673104Z","end":"2026-04-07T00:18:56.788726Z","steps":["trace[1674763905] 'process raft request' (duration: 115.431933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:56.788838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.746273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/prometheus-pushgateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:18:56.788878Z","caller":"traceutil/trace.go:171","msg":"trace[2114007298] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/prometheus-pushgateway; range_end:; response_count:0; response_revision:5114; }","duration":"166.794485ms","start":"2026-04-07T00:18:56.622069Z","end":"2026-04-07T00:18:56.788864Z","steps":["trace[2114007298] 'agreement among raft nodes before linearized reading' (duration: 166.568049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:56.788883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.885399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kfcpn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:18:56.788922Z","caller":"traceutil/trace.go:171","msg":"trace[1871904454] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kfcpn; range_end:; response_count:1; response_revision:5114; }","duration":"208.975152ms","start":"2026-04-07T00:18:56.579936Z","end":"2026-04-07T00:18:56.788911Z","steps":["trace[1871904454] 'agreement among raft nodes before linearized reading' (duration: 208.789277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:18:56.789087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.49903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:18:56.789127Z","caller":"traceutil/trace.go:171","msg":"trace[1735888168] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5114; }","duration":"203.568902ms","start":"2026-04-07T00:18:56.585544Z","end":"2026-04-07T00:18:56.789113Z","steps":["trace[1735888168] 'agreement among raft nodes before linearized reading' (duration: 203.10171ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:56.979898Z","caller":"traceutil/trace.go:171","msg":"trace[1850106632] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"117.22975ms","start":"2026-04-07T00:18:56.862633Z","end":"2026-04-07T00:18:56.979863Z","steps":["trace[1850106632] 'process raft request' (duration: 79.837076ms)","trace[1850106632] 'compare' (duration: 37.163397ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:57.355179Z","caller":"traceutil/trace.go:171","msg":"trace[524883690] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"142.817918ms","start":"2026-04-07T00:18:57.212337Z","end":"2026-04-07T00:18:57.355155Z","steps":["trace[524883690] 'process raft request' (duration: 139.316207ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:03.580736Z","caller":"traceutil/trace.go:171","msg":"trace[602741058] linearizableReadLoop","detail":"{readStateIndex:5440; appliedIndex:5439; }","duration":"124.243339ms","start":"2026-04-07T00:19:03.456457Z","end":"2026-04-07T00:19:03.5807Z","steps":["trace[602741058] 'read index received' (duration: 117.564257ms)","trace[602741058] 'applied index is now lower than readState.Index' (duration: 6.672741ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:19:03.580909Z","caller":"traceutil/trace.go:171","msg":"trace[1007908003] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"214.861212ms","start":"2026-04-07T00:19:03.366017Z","end":"2026-04-07T00:19:03.580878Z","steps":["trace[1007908003] 'process raft request' (duration: 208.033597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:03.581031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.553728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:19:03.581086Z","caller":"traceutil/trace.go:171","msg":"trace[1495999642] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5241; }","duration":"124.674321ms","start":"2026-04-07T00:19:03.456394Z","end":"2026-04-07T00:19:03.581068Z","steps":["trace[1495999642] 'agreement among raft nodes before linearized reading' (duration: 124.450984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:07.91582Z","caller":"traceutil/trace.go:171","msg":"trace[1285229562] linearizableReadLoop","detail":"{readStateIndex:5480; appliedIndex:5479; }","duration":"153.019499ms","start":"2026-04-07T00:19:07.762775Z","end":"2026-04-07T00:19:07.915795Z","steps":["trace[1285229562] 'read index received' (duration: 152.718852ms)","trace[1285229562] 'applied index is now lower than readState.Index' (duration: 297.547µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:19:07.915874Z","caller":"traceutil/trace.go:171","msg":"trace[166206377] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"212.5068ms","start":"2026-04-07T00:19:07.703346Z","end":"2026-04-07T00:19:07.915853Z","steps":["trace[166206377] 'process raft request' (duration: 212.275175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:07.915992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.518716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:19:07.916028Z","caller":"traceutil/trace.go:171","msg":"trace[260796069] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:5280; }","duration":"124.604598ms","start":"2026-04-07T00:19:07.791412Z","end":"2026-04-07T00:19:07.916017Z","steps":["trace[260796069] 'agreement among raft nodes before linearized reading' (duration: 124.526997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:07.916148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.222575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:19:07.916282Z","caller":"traceutil/trace.go:171","msg":"trace[1454381104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5280; }","duration":"153.498182ms","start":"2026-04-07T00:19:07.762729Z","end":"2026-04-07T00:19:07.916227Z","steps":["trace[1454381104] 'agreement among raft nodes before linearized reading' (duration: 153.170943ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:08.029754Z","caller":"traceutil/trace.go:171","msg":"trace[1424510671] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"107.97702ms","start":"2026-04-07T00:19:07.921747Z","end":"2026-04-07T00:19:08.029724Z","steps":["trace[1424510671] 'process raft request' (duration: 106.761389ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:08.032413Z","caller":"traceutil/trace.go:171","msg":"trace[618035997] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"104.978263ms","start":"2026-04-07T00:19:07.927409Z","end":"2026-04-07T00:19:08.032387Z","steps":["trace[618035997] 'process raft request' (duration: 104.677955ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:08.032453Z","caller":"traceutil/trace.go:171","msg":"trace[1385217901] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"110.306461ms","start":"2026-04-07T00:19:07.922111Z","end":"2026-04-07T00:19:08.032418Z","steps":["trace[1385217901] 'process raft request' (duration: 109.708815ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:10.519101Z","caller":"traceutil/trace.go:171","msg":"trace[543296497] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"113.255756ms","start":"2026-04-07T00:19:10.405782Z","end":"2026-04-07T00:19:10.519038Z","steps":["trace[543296497] 'process raft request' (duration: 67.857497ms)","trace[543296497] 'compare' (duration: 45.148952ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:19:16.929354Z","caller":"traceutil/trace.go:171","msg":"trace[673436749] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"140.897878ms","start":"2026-04-07T00:19:16.788421Z","end":"2026-04-07T00:19:16.929319Z","steps":["trace[673436749] 'process raft request' (duration: 140.631371ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:31.237428Z","caller":"traceutil/trace.go:171","msg":"trace[1971044785] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"101.15746ms","start":"2026-04-07T00:19:31.136245Z","end":"2026-04-07T00:19:31.237403Z","steps":["trace[1971044785] 'compare' (duration: 94.828417ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:31.237624Z","caller":"traceutil/trace.go:171","msg":"trace[531329468] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"100.718619ms","start":"2026-04-07T00:19:31.136866Z","end":"2026-04-07T00:19:31.237585Z","steps":["trace[531329468] 'process raft request' (duration: 100.489413ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:39.429805Z","caller":"traceutil/trace.go:171","msg":"trace[193995243] linearizableReadLoop","detail":"{readStateIndex:5785; appliedIndex:5784; }","duration":"404.5951ms","start":"2026-04-07T00:19:39.025182Z","end":"2026-04-07T00:19:39.429777Z","steps":["trace[193995243] 'read index received' (duration: 404.396995ms)","trace[193995243] 'applied index is now lower than readState.Index' (duration: 196.745µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:19:39.430195Z","caller":"traceutil/trace.go:171","msg":"trace[831549224] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"407.374512ms","start":"2026-04-07T00:19:39.022801Z","end":"2026-04-07T00:19:39.430175Z","steps":["trace[831549224] 'process raft request' (duration: 406.802707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.430334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.022778Z","time spent":"407.467004ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:19:39.430464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.262717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1823"} {"level":"info","ts":"2026-04-07T00:19:39.432177Z","caller":"traceutil/trace.go:171","msg":"trace[2094217313] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:5577; }","duration":"407.013113ms","start":"2026-04-07T00:19:39.025142Z","end":"2026-04-07T00:19:39.432155Z","steps":["trace[2094217313] 'agreement among raft nodes before linearized reading' (duration: 405.143474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.432235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.025123Z","time spent":"407.095925ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1846,"request content":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" "} {"level":"warn","ts":"2026-04-07T00:19:39.455336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.296941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:19:39.455392Z","caller":"traceutil/trace.go:171","msg":"trace[1562565079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5578; }","duration":"395.374244ms","start":"2026-04-07T00:19:39.060006Z","end":"2026-04-07T00:19:39.45538Z","steps":["trace[1562565079] 'agreement among raft nodes before linearized reading' (duration: 395.21615ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:39.455407Z","caller":"traceutil/trace.go:171","msg":"trace[941722123] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"188.175267ms","start":"2026-04-07T00:19:39.26721Z","end":"2026-04-07T00:19:39.455385Z","steps":["trace[941722123] 'process raft request' (duration: 187.766696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.455478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.215247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kfcpn\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-07T00:19:39.455592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.601631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/\" range_end:\"/registry/serviceaccounts/monitoring0\" limit:500 ","response":"range_response_count:14 size:12978"} {"level":"warn","ts":"2026-04-07T00:19:39.455609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.86462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/\" range_end:\"/registry/configmaps/openstack0\" limit:500 ","response":"range_response_count:8 size:30303"} {"level":"warn","ts":"2026-04-07T00:19:39.45559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.720858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:19:39.455651Z","caller":"traceutil/trace.go:171","msg":"trace[1349692841] range","detail":"{range_begin:/registry/configmaps/openstack/; range_end:/registry/configmaps/openstack0; response_count:8; response_revision:5578; }","duration":"422.943282ms","start":"2026-04-07T00:19:39.032696Z","end":"2026-04-07T00:19:39.455639Z","steps":["trace[1349692841] 'agreement among raft nodes before linearized reading' (duration: 422.501391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.455419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.059996Z","time spent":"395.417254ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T00:19:39.455671Z","caller":"traceutil/trace.go:171","msg":"trace[1742366363] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5578; }","duration":"219.851761ms","start":"2026-04-07T00:19:39.235803Z","end":"2026-04-07T00:19:39.455655Z","steps":["trace[1742366363] 'agreement among raft nodes before linearized reading' (duration: 219.623046ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:39.455591Z","caller":"traceutil/trace.go:171","msg":"trace[1838581520] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kfcpn; range_end:; response_count:1; response_revision:5578; }","duration":"321.445683ms","start":"2026-04-07T00:19:39.134126Z","end":"2026-04-07T00:19:39.455571Z","steps":["trace[1838581520] 'agreement among raft nodes before linearized reading' (duration: 321.053644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.45551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.857051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T00:19:39.455746Z","caller":"traceutil/trace.go:171","msg":"trace[1858256090] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5578; }","duration":"185.101507ms","start":"2026-04-07T00:19:39.270636Z","end":"2026-04-07T00:19:39.455737Z","steps":["trace[1858256090] 'agreement among raft nodes before linearized reading' (duration: 184.758319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.455749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.980006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T00:19:39.455628Z","caller":"traceutil/trace.go:171","msg":"trace[180565200] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/; range_end:/registry/serviceaccounts/monitoring0; response_count:14; response_revision:5578; }","duration":"285.669593ms","start":"2026-04-07T00:19:39.169949Z","end":"2026-04-07T00:19:39.455619Z","steps":["trace[180565200] 'agreement among raft nodes before linearized reading' (duration: 285.495369ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:39.455807Z","caller":"traceutil/trace.go:171","msg":"trace[829474516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5578; }","duration":"187.060948ms","start":"2026-04-07T00:19:39.268736Z","end":"2026-04-07T00:19:39.455797Z","steps":["trace[829474516] 'agreement among raft nodes before linearized reading' (duration: 186.543235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.455497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.859801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1\" ","response":"range_response_count:1 size:105435"} {"level":"info","ts":"2026-04-07T00:19:39.455878Z","caller":"traceutil/trace.go:171","msg":"trace[878289455] range","detail":"{range_begin:/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1; range_end:; response_count:1; response_revision:5578; }","duration":"418.271512ms","start":"2026-04-07T00:19:39.037596Z","end":"2026-04-07T00:19:39.455868Z","steps":["trace[878289455] 'agreement among raft nodes before linearized reading' (duration: 417.750789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.455693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.032674Z","time spent":"423.008463ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":8,"response size":30326,"request content":"key:\"/registry/configmaps/openstack/\" range_end:\"/registry/configmaps/openstack0\" limit:500 "} {"level":"warn","ts":"2026-04-07T00:19:39.455843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.134111Z","time spent":"321.685169ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-kfcpn\" "} {"level":"warn","ts":"2026-04-07T00:19:39.455917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.037585Z","time spent":"418.320353ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":105458,"request content":"key:\"/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1\" "} {"level":"warn","ts":"2026-04-07T00:19:39.764232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.280632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:19:39.764351Z","caller":"traceutil/trace.go:171","msg":"trace[883179934] linearizableReadLoop","detail":"{readStateIndex:5787; appliedIndex:5786; }","duration":"301.90009ms","start":"2026-04-07T00:19:39.462433Z","end":"2026-04-07T00:19:39.764333Z","steps":["trace[883179934] 'read index received' (duration: 153.455364ms)","trace[883179934] 'applied index is now lower than readState.Index' (duration: 148.443106ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:19:39.764568Z","caller":"traceutil/trace.go:171","msg":"trace[1705376889] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"303.565503ms","start":"2026-04-07T00:19:39.460972Z","end":"2026-04-07T00:19:39.764537Z","steps":["trace[1705376889] 'process raft request' (duration: 154.894842ms)","trace[1705376889] 'compare' (duration: 147.915711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:19:39.764677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.224118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19670"} {"level":"warn","ts":"2026-04-07T00:19:39.764707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.460942Z","time spent":"303.701197ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:19:39.764745Z","caller":"traceutil/trace.go:171","msg":"trace[1226483329] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5579; }","duration":"302.344741ms","start":"2026-04-07T00:19:39.462384Z","end":"2026-04-07T00:19:39.764729Z","steps":["trace[1226483329] 'agreement among raft nodes before linearized reading' (duration: 302.119455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.764786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.46236Z","time spent":"302.417453ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19693,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-07T00:19:39.765253Z","caller":"traceutil/trace.go:171","msg":"trace[887995907] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"302.484945ms","start":"2026-04-07T00:19:39.462755Z","end":"2026-04-07T00:19:39.76524Z","steps":["trace[887995907] 'process raft request' (duration: 302.374912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.765331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.462737Z","time spent":"302.557037ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:19:39.76563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.965188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:71123"} {"level":"info","ts":"2026-04-07T00:19:39.76571Z","caller":"traceutil/trace.go:171","msg":"trace[1749616867] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5581; }","duration":"303.05689ms","start":"2026-04-07T00:19:39.462641Z","end":"2026-04-07T00:19:39.765698Z","steps":["trace[1749616867] 'agreement among raft nodes before linearized reading' (duration: 302.763013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.76576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.327118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/\" range_end:\"/registry/services/specs/monitoring0\" limit:500 ","response":"range_response_count:17 size:24129"} {"level":"info","ts":"2026-04-07T00:19:39.765832Z","caller":"traceutil/trace.go:171","msg":"trace[553868893] range","detail":"{range_begin:/registry/services/specs/monitoring/; range_end:/registry/services/specs/monitoring0; response_count:17; response_revision:5581; }","duration":"163.445821ms","start":"2026-04-07T00:19:39.602367Z","end":"2026-04-07T00:19:39.765812Z","steps":["trace[553868893] 'agreement among raft nodes before linearized reading' (duration: 163.080012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.765743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.462635Z","time spent":"303.099501ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":71146,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-07T00:19:39.765913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.090702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1\" ","response":"range_response_count:1 size:105435"} {"level":"warn","ts":"2026-04-07T00:19:39.765889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.668046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/openstack/\" range_end:\"/registry/cronjobs/openstack0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:19:39.76594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.85798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/default\" ","response":"range_response_count:1 size:183"} {"level":"info","ts":"2026-04-07T00:19:39.765987Z","caller":"traceutil/trace.go:171","msg":"trace[1609004007] range","detail":"{range_begin:/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1; range_end:; response_count:1; response_revision:5581; }","duration":"200.278328ms","start":"2026-04-07T00:19:39.56569Z","end":"2026-04-07T00:19:39.765969Z","steps":["trace[1609004007] 'agreement among raft nodes before linearized reading' (duration: 200.039663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:39.766023Z","caller":"traceutil/trace.go:171","msg":"trace[2129693537] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/default; range_end:; response_count:1; response_revision:5581; }","duration":"211.030884ms","start":"2026-04-07T00:19:39.554975Z","end":"2026-04-07T00:19:39.766006Z","steps":["trace[2129693537] 'agreement among raft nodes before linearized reading' (duration: 210.793198ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:39.766109Z","caller":"traceutil/trace.go:171","msg":"trace[1775440918] range","detail":"{range_begin:/registry/cronjobs/openstack/; range_end:/registry/cronjobs/openstack0; response_count:0; response_revision:5581; }","duration":"177.836761ms","start":"2026-04-07T00:19:39.588146Z","end":"2026-04-07T00:19:39.765983Z","steps":["trace[1775440918] 'agreement among raft nodes before linearized reading' (duration: 177.676537ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:19:39.766367Z","caller":"traceutil/trace.go:171","msg":"trace[288654987] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"300.34947ms","start":"2026-04-07T00:19:39.465332Z","end":"2026-04-07T00:19:39.765681Z","steps":["trace[288654987] 'process raft request' (duration: 299.874498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:19:39.766655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:19:39.465316Z","time spent":"301.192051ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:20:15.535342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2926} {"level":"info","ts":"2026-04-07T00:20:15.602889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2926,"took":"66.424208ms","hash":1521045471,"current-db-size-bytes":55193600,"current-db-size":"55 MB","current-db-size-in-use-bytes":31596544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T00:20:15.602946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1521045471,"revision":2926,"compact-revision":-1}