{"level":"warn","ts":"2026-07-29T06:24:06.68131Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T06:24:06.681418Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-29T06:24:06.68151Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T06:24:06.681535Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-29T06:24:06.681553Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-29T06:24:06.684132Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-29T06:24:06.684355Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-29T06:24:06.68898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.308355ms"} {"level":"info","ts":"2026-07-29T06:24:06.696627Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-29T06:24:06.696736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-29T06:24:06.69677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-29T06:24:06.696851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-29T06:24:06.696942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-29T06:24:06.697055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-29T06:24:06.703603Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-29T06:24:06.705102Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-29T06:24:06.706393Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-29T06:24:06.708957Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-29T06:24:06.709282Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-29T06:24:06.709455Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-29T06:24:06.709547Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-29T06:24:06.709562Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-29T06:24:06.710387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-29T06:24:06.710797Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-29T06:24:06.715192Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-29T06:24:06.715382Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-29T06:24:06.71542Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-29T06:24:06.715487Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-29T06:24:06.715503Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-29T06:24:07.19786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-29T06:24:07.197916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-29T06:24:07.197963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-29T06:24:07.197989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-29T06:24:07.198005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-29T06:24:07.198025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-29T06:24:07.198044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-29T06:24:07.199111Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-29T06:24:07.199269Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T06:24:07.199312Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T06:24:07.199406Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T06:24:07.199422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-29T06:24:07.199503Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-29T06:24:07.200889Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T06:24:07.20098Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T06:24:07.20101Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T06:24:07.202938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-29T06:24:07.203065Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-29T06:24:21.62929Z","caller":"traceutil/trace.go:171","msg":"trace[801799755] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"199.2942ms","start":"2026-07-29T06:24:21.429961Z","end":"2026-07-29T06:24:21.629255Z","steps":["trace[801799755] 'process raft request' (duration: 199.082415ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:24:22.014722Z","caller":"traceutil/trace.go:171","msg":"trace[1845186279] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"377.304989ms","start":"2026-07-29T06:24:21.637393Z","end":"2026-07-29T06:24:22.014698Z","steps":["trace[1845186279] 'process raft request' (duration: 377.011597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:22.01536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:21.637368Z","time spent":"377.499432ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:24:22.461947Z","caller":"traceutil/trace.go:171","msg":"trace[870028800] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"326.311361ms","start":"2026-07-29T06:24:22.13561Z","end":"2026-07-29T06:24:22.461921Z","steps":["trace[870028800] 'read index received' (duration: 324.424168ms)","trace[870028800] 'applied index is now lower than readState.Index' (duration: 1.886113ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:24:22.462056Z","caller":"traceutil/trace.go:171","msg":"trace[1116698713] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"496.163378ms","start":"2026-07-29T06:24:21.965863Z","end":"2026-07-29T06:24:22.462026Z","steps":["trace[1116698713] 'process raft request' (duration: 494.162798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:22.46213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.516015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-29T06:24:22.462204Z","caller":"traceutil/trace.go:171","msg":"trace[203411809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"326.650574ms","start":"2026-07-29T06:24:22.135541Z","end":"2026-07-29T06:24:22.462192Z","steps":["trace[203411809] 'agreement among raft nodes before linearized reading' (duration: 326.497023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:22.462271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:22.135516Z","time spent":"326.750161ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T06:24:22.462277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:21.965837Z","time spent":"496.288027ms","remote":"127.0.0.1:57022","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-07-29T06:24:44.431596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.549131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:24:44.43179Z","caller":"traceutil/trace.go:171","msg":"trace[1498154571] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"289.451433ms","start":"2026-07-29T06:24:44.142276Z","end":"2026-07-29T06:24:44.431727Z","steps":["trace[1498154571] 'process raft request' (duration: 42.423441ms)","trace[1498154571] 'compare' (duration: 246.338875ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:24:44.434735Z","caller":"traceutil/trace.go:171","msg":"trace[977634038] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"261.387562ms","start":"2026-07-29T06:24:44.173317Z","end":"2026-07-29T06:24:44.434705Z","steps":["trace[977634038] 'process raft request' (duration: 261.159926ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:24:44.434915Z","caller":"traceutil/trace.go:171","msg":"trace[1469325846] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"257.845834ms","start":"2026-07-29T06:24:44.17705Z","end":"2026-07-29T06:24:44.434896Z","steps":["trace[1469325846] 'process raft request' (duration: 257.547649ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:24:44.434953Z","caller":"traceutil/trace.go:171","msg":"trace[144778523] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"269.447921ms","start":"2026-07-29T06:24:44.165499Z","end":"2026-07-29T06:24:44.434947Z","steps":["trace[144778523] 'process raft request' (duration: 268.823202ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:24:50.329795Z","caller":"traceutil/trace.go:171","msg":"trace[1802434717] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"212.800828ms","start":"2026-07-29T06:24:50.116956Z","end":"2026-07-29T06:24:50.329757Z","steps":["trace[1802434717] 'read index received' (duration: 211.489682ms)","trace[1802434717] 'applied index is now lower than readState.Index' (duration: 1.310066ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:24:50.32991Z","caller":"traceutil/trace.go:171","msg":"trace[936871897] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"321.422079ms","start":"2026-07-29T06:24:50.00845Z","end":"2026-07-29T06:24:50.329872Z","steps":["trace[936871897] 'process raft request' (duration: 320.41433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:50.32998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.001136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-29T06:24:50.330024Z","caller":"traceutil/trace.go:171","msg":"trace[1070458781] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:590; }","duration":"213.130954ms","start":"2026-07-29T06:24:50.116879Z","end":"2026-07-29T06:24:50.33001Z","steps":["trace[1070458781] 'agreement among raft nodes before linearized reading' (duration: 213.013416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:50.330111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:50.008398Z","time spent":"321.595927ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:24:50.77991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.926796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-29T06:24:50.779972Z","caller":"traceutil/trace.go:171","msg":"trace[785254070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"165.037615ms","start":"2026-07-29T06:24:50.61492Z","end":"2026-07-29T06:24:50.779958Z","steps":["trace[785254070] 'range keys from in-memory index tree' (duration: 164.771548ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:24:51.188404Z","caller":"traceutil/trace.go:171","msg":"trace[1961841251] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"404.750157ms","start":"2026-07-29T06:24:50.78362Z","end":"2026-07-29T06:24:51.18837Z","steps":["trace[1961841251] 'process raft request' (duration: 404.398481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:51.188715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:50.783602Z","time spent":"404.889026ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:24:51.570665Z","caller":"traceutil/trace.go:171","msg":"trace[1935315531] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"730.0089ms","start":"2026-07-29T06:24:50.840627Z","end":"2026-07-29T06:24:51.570636Z","steps":["trace[1935315531] 'read index received' (duration: 347.340263ms)","trace[1935315531] 'applied index is now lower than readState.Index' (duration: 382.667557ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:24:51.570726Z","caller":"traceutil/trace.go:171","msg":"trace[373180479] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"757.908301ms","start":"2026-07-29T06:24:50.812778Z","end":"2026-07-29T06:24:51.570687Z","steps":["trace[373180479] 'process raft request' (duration: 756.271527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:51.571566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.907912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-29T06:24:51.571623Z","caller":"traceutil/trace.go:171","msg":"trace[1931009304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"516.030741ms","start":"2026-07-29T06:24:51.055577Z","end":"2026-07-29T06:24:51.571608Z","steps":["trace[1931009304] 'agreement among raft nodes before linearized reading' (duration: 515.701253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:51.571625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.550305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T06:24:51.571678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:51.055548Z","time spent":"516.120149ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-29T06:24:51.571714Z","caller":"traceutil/trace.go:171","msg":"trace[836532641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"181.668183ms","start":"2026-07-29T06:24:51.390007Z","end":"2026-07-29T06:24:51.571675Z","steps":["trace[836532641] 'agreement among raft nodes before linearized reading' (duration: 181.429816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:51.571564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:50.812751Z","time spent":"758.517404ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:24:51.572669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.041642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T06:24:51.572758Z","caller":"traceutil/trace.go:171","msg":"trace[1711771708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"732.091211ms","start":"2026-07-29T06:24:50.840614Z","end":"2026-07-29T06:24:51.572705Z","steps":["trace[1711771708] 'agreement among raft nodes before linearized reading' (duration: 730.160289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:51.572823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:50.84056Z","time spent":"732.22772ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-29T06:24:52.218804Z","caller":"traceutil/trace.go:171","msg":"trace[278268572] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"151.48607ms","start":"2026-07-29T06:24:52.06729Z","end":"2026-07-29T06:24:52.218776Z","steps":["trace[278268572] 'process raft request' (duration: 150.900704ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:24:52.596401Z","caller":"traceutil/trace.go:171","msg":"trace[1596086924] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"316.665367ms","start":"2026-07-29T06:24:52.279698Z","end":"2026-07-29T06:24:52.596364Z","steps":["trace[1596086924] 'process raft request' (duration: 315.624816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:52.596761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:24:52.279674Z","time spent":"316.820575ms","remote":"127.0.0.1:56996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T06:24:55.724933Z","caller":"traceutil/trace.go:171","msg":"trace[2141617789] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"118.333787ms","start":"2026-07-29T06:24:55.606568Z","end":"2026-07-29T06:24:55.724902Z","steps":["trace[2141617789] 'process raft request' (duration: 118.088838ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:24:55.725785Z","caller":"traceutil/trace.go:171","msg":"trace[1363763262] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"117.201213ms","start":"2026-07-29T06:24:55.608553Z","end":"2026-07-29T06:24:55.725754Z","steps":["trace[1363763262] 'process raft request' (duration: 117.051593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:24:56.383715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.493685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-29T06:24:56.383812Z","caller":"traceutil/trace.go:171","msg":"trace[481311090] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:669; }","duration":"199.660654ms","start":"2026-07-29T06:24:56.184128Z","end":"2026-07-29T06:24:56.383788Z","steps":["trace[481311090] 'range keys from in-memory index tree' (duration: 199.292056ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:25:07.114705Z","caller":"traceutil/trace.go:171","msg":"trace[652793832] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"301.584325ms","start":"2026-07-29T06:25:06.813096Z","end":"2026-07-29T06:25:07.114681Z","steps":["trace[652793832] 'process raft request' (duration: 301.437364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:25:07.114925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:25:06.813064Z","time spent":"301.790326ms","remote":"127.0.0.1:56996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:25:37.565634Z","caller":"traceutil/trace.go:171","msg":"trace[1577028241] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"111.336022ms","start":"2026-07-29T06:25:37.454276Z","end":"2026-07-29T06:25:37.565612Z","steps":["trace[1577028241] 'read index received' (duration: 111.096425ms)","trace[1577028241] 'applied index is now lower than readState.Index' (duration: 238.367µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:25:37.5657Z","caller":"traceutil/trace.go:171","msg":"trace[51536217] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"123.524767ms","start":"2026-07-29T06:25:37.442143Z","end":"2026-07-29T06:25:37.565668Z","steps":["trace[51536217] 'process raft request' (duration: 123.311962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:25:37.570611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.079319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:25:37.570738Z","caller":"traceutil/trace.go:171","msg":"trace[1413010482] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1160; }","duration":"116.457829ms","start":"2026-07-29T06:25:37.454246Z","end":"2026-07-29T06:25:37.570704Z","steps":["trace[1413010482] 'agreement among raft nodes before linearized reading' (duration: 111.463675ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:22.143367Z","caller":"traceutil/trace.go:171","msg":"trace[22790534] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"283.073576ms","start":"2026-07-29T06:26:21.860263Z","end":"2026-07-29T06:26:22.143336Z","steps":["trace[22790534] 'read index received' (duration: 253.254284ms)","trace[22790534] 'applied index is now lower than readState.Index' (duration: 29.817882ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:26:22.143404Z","caller":"traceutil/trace.go:171","msg":"trace[409079727] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"324.270607ms","start":"2026-07-29T06:26:21.819094Z","end":"2026-07-29T06:26:22.143364Z","steps":["trace[409079727] 'process raft request' (duration: 294.485956ms)","trace[409079727] 'compare' (duration: 29.577442ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:26:22.1436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:21.819074Z","time spent":"324.423574ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:26:22.14369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.072411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-29T06:26:22.143755Z","caller":"traceutil/trace.go:171","msg":"trace[1159958561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1819; }","duration":"177.153144ms","start":"2026-07-29T06:26:21.966585Z","end":"2026-07-29T06:26:22.143738Z","steps":["trace[1159958561] 'agreement among raft nodes before linearized reading' (duration: 176.942495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:22.144191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.911532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7dfd7564-b9dc-4918-b034-b3e0a551e4c7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-29T06:26:22.144328Z","caller":"traceutil/trace.go:171","msg":"trace[228992874] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7dfd7564-b9dc-4918-b034-b3e0a551e4c7; range_end:; response_count:1; response_revision:1819; }","duration":"284.055818ms","start":"2026-07-29T06:26:21.860253Z","end":"2026-07-29T06:26:22.144309Z","steps":["trace[228992874] 'agreement among raft nodes before linearized reading' (duration: 283.16671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:22.582579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.979673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:26:22.582701Z","caller":"traceutil/trace.go:171","msg":"trace[1049023751] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"417.516418ms","start":"2026-07-29T06:26:22.165165Z","end":"2026-07-29T06:26:22.582682Z","steps":["trace[1049023751] 'read index received' (duration: 168.43797ms)","trace[1049023751] 'applied index is now lower than readState.Index' (duration: 249.076547ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:26:22.582771Z","caller":"traceutil/trace.go:171","msg":"trace[2025935100] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"434.114708ms","start":"2026-07-29T06:26:22.148615Z","end":"2026-07-29T06:26:22.58273Z","steps":["trace[2025935100] 'process raft request' (duration: 184.887904ms)","trace[2025935100] 'compare' (duration: 248.880688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:26:22.582902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.727097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7dfd7564-b9dc-4918-b034-b3e0a551e4c7\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-07-29T06:26:22.582946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:22.148592Z","time spent":"434.263164ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:26:22.582946Z","caller":"traceutil/trace.go:171","msg":"trace[499824549] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7dfd7564-b9dc-4918-b034-b3e0a551e4c7; range_end:; response_count:1; response_revision:1820; }","duration":"417.79532ms","start":"2026-07-29T06:26:22.165139Z","end":"2026-07-29T06:26:22.582934Z","steps":["trace[499824549] 'agreement among raft nodes before linearized reading' (duration: 417.627332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:22.583121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:22.165124Z","time spent":"417.955296ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7dfd7564-b9dc-4918-b034-b3e0a551e4c7\" "} {"level":"info","ts":"2026-07-29T06:26:22.645861Z","caller":"traceutil/trace.go:171","msg":"trace[181506858] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"309.495304ms","start":"2026-07-29T06:26:22.33634Z","end":"2026-07-29T06:26:22.645835Z","steps":["trace[181506858] 'process raft request' (duration: 309.324767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:22.64601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.607506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-29T06:26:22.646047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.672209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-29T06:26:22.646057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:22.336318Z","time spent":"309.635771ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:26:22.646103Z","caller":"traceutil/trace.go:171","msg":"trace[1084991404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1821; }","duration":"303.767752ms","start":"2026-07-29T06:26:22.342322Z","end":"2026-07-29T06:26:22.646089Z","steps":["trace[1084991404] 'agreement among raft nodes before linearized reading' (duration: 303.585064ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:22.64607Z","caller":"traceutil/trace.go:171","msg":"trace[1139187787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1821; }","duration":"466.708231ms","start":"2026-07-29T06:26:22.179347Z","end":"2026-07-29T06:26:22.646056Z","steps":["trace[1139187787] 'agreement among raft nodes before linearized reading' (duration: 466.523803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:22.646148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:22.342304Z","time spent":"303.832605ms","remote":"127.0.0.1:57022","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-07-29T06:26:22.646184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:22.179331Z","time spent":"466.842036ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T06:26:22.646289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.941868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T06:26:22.646289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.907924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-29T06:26:22.646345Z","caller":"traceutil/trace.go:171","msg":"trace[1332479820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1821; }","duration":"258.027311ms","start":"2026-07-29T06:26:22.388302Z","end":"2026-07-29T06:26:22.646329Z","steps":["trace[1332479820] 'agreement among raft nodes before linearized reading' (duration: 257.951138ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:22.646363Z","caller":"traceutil/trace.go:171","msg":"trace[1254110225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1821; }","duration":"443.007918ms","start":"2026-07-29T06:26:22.203332Z","end":"2026-07-29T06:26:22.64634Z","steps":["trace[1254110225] 'agreement among raft nodes before linearized reading' (duration: 442.8072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:22.646447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:22.203305Z","time spent":"443.110084ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-29T06:26:22.918785Z","caller":"traceutil/trace.go:171","msg":"trace[589308495] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"327.590092ms","start":"2026-07-29T06:26:22.59118Z","end":"2026-07-29T06:26:22.91877Z","steps":["trace[589308495] 'process raft request' (duration: 327.33849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:22.918925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:22.591161Z","time spent":"327.680956ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:26:22.918705Z","caller":"traceutil/trace.go:171","msg":"trace[1694236343] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"270.95439ms","start":"2026-07-29T06:26:22.647727Z","end":"2026-07-29T06:26:22.918682Z","steps":["trace[1694236343] 'read index received' (duration: 270.730409ms)","trace[1694236343] 'applied index is now lower than readState.Index' (duration: 222.751µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:26:22.919137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.39043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:26:22.919224Z","caller":"traceutil/trace.go:171","msg":"trace[427217617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1822; }","duration":"271.523045ms","start":"2026-07-29T06:26:22.647682Z","end":"2026-07-29T06:26:22.919205Z","steps":["trace[427217617] 'agreement among raft nodes before linearized reading' (duration: 271.345747ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:22.926568Z","caller":"traceutil/trace.go:171","msg":"trace[556176307] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"274.985387ms","start":"2026-07-29T06:26:22.651557Z","end":"2026-07-29T06:26:22.926543Z","steps":["trace[556176307] 'process raft request' (duration: 274.051586ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:22.926814Z","caller":"traceutil/trace.go:171","msg":"trace[430586349] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"276.252182ms","start":"2026-07-29T06:26:22.650538Z","end":"2026-07-29T06:26:22.926791Z","steps":["trace[430586349] 'process raft request' (duration: 274.83708ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:22.926836Z","caller":"traceutil/trace.go:171","msg":"trace[834952595] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"276.219801ms","start":"2026-07-29T06:26:22.650589Z","end":"2026-07-29T06:26:22.926809Z","steps":["trace[834952595] 'process raft request' (duration: 274.971696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:44.740558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.16665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:26:44.740648Z","caller":"traceutil/trace.go:171","msg":"trace[966237652] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"324.696131ms","start":"2026-07-29T06:26:44.415938Z","end":"2026-07-29T06:26:44.740634Z","steps":["trace[966237652] 'read index received' (duration: 165.484849ms)","trace[966237652] 'applied index is now lower than readState.Index' (duration: 159.210382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:26:44.741024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.0831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-29T06:26:44.74107Z","caller":"traceutil/trace.go:171","msg":"trace[340021333] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1998; }","duration":"325.125972ms","start":"2026-07-29T06:26:44.415929Z","end":"2026-07-29T06:26:44.741055Z","steps":["trace[340021333] 'agreement among raft nodes before linearized reading' (duration: 324.981915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:44.741118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:44.415899Z","time spent":"325.210356ms","remote":"127.0.0.1:56902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-29T06:26:44.741897Z","caller":"traceutil/trace.go:171","msg":"trace[408099018] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"201.477528ms","start":"2026-07-29T06:26:44.540397Z","end":"2026-07-29T06:26:44.741875Z","steps":["trace[408099018] 'process raft request' (duration: 201.31456ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:44.742181Z","caller":"traceutil/trace.go:171","msg":"trace[1418660183] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"326.434206ms","start":"2026-07-29T06:26:44.414457Z","end":"2026-07-29T06:26:44.740892Z","steps":["trace[1418660183] 'process raft request' (duration: 166.860906ms)","trace[1418660183] 'compare' (duration: 158.642295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:26:44.742486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:44.414442Z","time spent":"327.966451ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12364,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:26:44.971068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.679889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:26:44.971198Z","caller":"traceutil/trace.go:171","msg":"trace[1747248749] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"183.409695ms","start":"2026-07-29T06:26:44.787769Z","end":"2026-07-29T06:26:44.971178Z","steps":["trace[1747248749] 'process raft request' (duration: 81.522325ms)","trace[1747248749] 'compare' (duration: 101.534903ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:26:56.954779Z","caller":"traceutil/trace.go:171","msg":"trace[1288733779] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"132.261373ms","start":"2026-07-29T06:26:56.822494Z","end":"2026-07-29T06:26:56.954755Z","steps":["trace[1288733779] 'process raft request' (duration: 131.354787ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:57.389704Z","caller":"traceutil/trace.go:171","msg":"trace[483299485] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2140; }","duration":"175.320912ms","start":"2026-07-29T06:26:57.214359Z","end":"2026-07-29T06:26:57.38968Z","steps":["trace[483299485] 'read index received' (duration: 98.69578ms)","trace[483299485] 'applied index is now lower than readState.Index' (duration: 76.624122ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:26:57.389794Z","caller":"traceutil/trace.go:171","msg":"trace[880822912] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"205.786038ms","start":"2026-07-29T06:26:57.183973Z","end":"2026-07-29T06:26:57.389759Z","steps":["trace[880822912] 'process raft request' (duration: 205.642211ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:57.389855Z","caller":"traceutil/trace.go:171","msg":"trace[270167176] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"208.386101ms","start":"2026-07-29T06:26:57.181422Z","end":"2026-07-29T06:26:57.389808Z","steps":["trace[270167176] 'process raft request' (duration: 131.722476ms)","trace[270167176] 'compare' (duration: 76.278915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:26:57.389981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.086205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-29T06:26:57.390046Z","caller":"traceutil/trace.go:171","msg":"trace[83054031] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2067; }","duration":"114.152368ms","start":"2026-07-29T06:26:57.275861Z","end":"2026-07-29T06:26:57.390014Z","steps":["trace[83054031] 'agreement among raft nodes before linearized reading' (duration: 113.966879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:57.390533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.162396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-29T06:26:57.390657Z","caller":"traceutil/trace.go:171","msg":"trace[103362522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2067; }","duration":"176.301402ms","start":"2026-07-29T06:26:57.214312Z","end":"2026-07-29T06:26:57.390614Z","steps":["trace[103362522] 'agreement among raft nodes before linearized reading' (duration: 175.502141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:57.906125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.453129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:26:57.906317Z","caller":"traceutil/trace.go:171","msg":"trace[379457457] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"432.716227ms","start":"2026-07-29T06:26:57.473584Z","end":"2026-07-29T06:26:57.9063Z","steps":["trace[379457457] 'process raft request' (duration: 432.640823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:57.90639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:57.473566Z","time spent":"432.792261ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:26:57.906373Z","caller":"traceutil/trace.go:171","msg":"trace[1928156060] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"434.565331ms","start":"2026-07-29T06:26:57.471775Z","end":"2026-07-29T06:26:57.906341Z","steps":["trace[1928156060] 'process raft request' (duration: 24.787965ms)","trace[1928156060] 'compare' (duration: 408.954923ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:26:57.906546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:26:57.471757Z","time spent":"434.702158ms","remote":"127.0.0.1:57022","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-07-29T06:26:58.520493Z","caller":"traceutil/trace.go:171","msg":"trace[918526175] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"170.226078ms","start":"2026-07-29T06:26:58.350233Z","end":"2026-07-29T06:26:58.520459Z","steps":["trace[918526175] 'read index received' (duration: 170.028828ms)","trace[918526175] 'applied index is now lower than readState.Index' (duration: 196.59µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:26:58.520719Z","caller":"traceutil/trace.go:171","msg":"trace[1121453343] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"186.330083ms","start":"2026-07-29T06:26:58.334382Z","end":"2026-07-29T06:26:58.520712Z","steps":["trace[1121453343] 'process raft request' (duration: 185.960735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:58.520964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.718353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-29T06:26:58.520991Z","caller":"traceutil/trace.go:171","msg":"trace[295720885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2072; }","duration":"170.808378ms","start":"2026-07-29T06:26:58.350176Z","end":"2026-07-29T06:26:58.520984Z","steps":["trace[295720885] 'agreement among raft nodes before linearized reading' (duration: 170.6438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:58.521202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.223589ms","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-07-29T06:26:58.521224Z","caller":"traceutil/trace.go:171","msg":"trace[880118376] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2072; }","duration":"122.264551ms","start":"2026-07-29T06:26:58.398953Z","end":"2026-07-29T06:26:58.521218Z","steps":["trace[880118376] 'agreement among raft nodes before linearized reading' (duration: 122.211048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:26:58.521214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.616841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:26:58.521417Z","caller":"traceutil/trace.go:171","msg":"trace[1986350274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2072; }","duration":"132.857354ms","start":"2026-07-29T06:26:58.38854Z","end":"2026-07-29T06:26:58.521397Z","steps":["trace[1986350274] 'agreement among raft nodes before linearized reading' (duration: 132.568599ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:26:59.157374Z","caller":"traceutil/trace.go:171","msg":"trace[753281537] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"197.348739ms","start":"2026-07-29T06:26:58.959988Z","end":"2026-07-29T06:26:59.157336Z","steps":["trace[753281537] 'process raft request' (duration: 197.079195ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:28:02.017119Z","caller":"traceutil/trace.go:171","msg":"trace[1836674593] linearizableReadLoop","detail":"{readStateIndex:2605; appliedIndex:2603; }","duration":"245.402451ms","start":"2026-07-29T06:28:01.771699Z","end":"2026-07-29T06:28:02.017101Z","steps":["trace[1836674593] 'read index received' (duration: 224.640273ms)","trace[1836674593] 'applied index is now lower than readState.Index' (duration: 20.761468ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:28:02.017377Z","caller":"traceutil/trace.go:171","msg":"trace[49045022] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"274.839336ms","start":"2026-07-29T06:28:01.742522Z","end":"2026-07-29T06:28:02.017362Z","steps":["trace[49045022] 'process raft request' (duration: 253.854924ms)","trace[49045022] 'compare' (duration: 20.278961ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:28:02.01757Z","caller":"traceutil/trace.go:171","msg":"trace[475794051] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"273.294756ms","start":"2026-07-29T06:28:01.744264Z","end":"2026-07-29T06:28:02.017559Z","steps":["trace[475794051] 'process raft request' (duration: 272.770655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:28:02.017721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.018487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:28:02.017753Z","caller":"traceutil/trace.go:171","msg":"trace[1321088811] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2515; }","duration":"246.085181ms","start":"2026-07-29T06:28:01.771659Z","end":"2026-07-29T06:28:02.017745Z","steps":["trace[1321088811] 'agreement among raft nodes before linearized reading' (duration: 246.018377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:28:02.017903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.973887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:28:02.017948Z","caller":"traceutil/trace.go:171","msg":"trace[413584257] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2515; }","duration":"245.039012ms","start":"2026-07-29T06:28:01.772901Z","end":"2026-07-29T06:28:02.01794Z","steps":["trace[413584257] 'agreement among raft nodes before linearized reading' (duration: 244.976128ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:28:33.682468Z","caller":"traceutil/trace.go:171","msg":"trace[1623499879] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"124.222465ms","start":"2026-07-29T06:28:33.558225Z","end":"2026-07-29T06:28:33.682448Z","steps":["trace[1623499879] 'process raft request' (duration: 123.880544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:28:34.037662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.612202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-29T06:28:34.037716Z","caller":"traceutil/trace.go:171","msg":"trace[2065857944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2689; }","duration":"198.710527ms","start":"2026-07-29T06:28:33.838993Z","end":"2026-07-29T06:28:34.037703Z","steps":["trace[2065857944] 'range keys from in-memory index tree' (duration: 198.464253ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:28:39.433467Z","caller":"traceutil/trace.go:171","msg":"trace[221201450] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"120.489819ms","start":"2026-07-29T06:28:39.312961Z","end":"2026-07-29T06:28:39.43345Z","steps":["trace[221201450] 'process raft request' (duration: 120.359291ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:06.6572Z","caller":"traceutil/trace.go:171","msg":"trace[518102033] transaction","detail":"{read_only:false; response_revision:3998; number_of_response:1; }","duration":"140.701411ms","start":"2026-07-29T06:32:06.516473Z","end":"2026-07-29T06:32:06.657174Z","steps":["trace[518102033] 'process raft request' (duration: 140.523834ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:10.271463Z","caller":"traceutil/trace.go:171","msg":"trace[738815041] linearizableReadLoop","detail":"{readStateIndex:4195; appliedIndex:4189; }","duration":"116.720993ms","start":"2026-07-29T06:32:10.154716Z","end":"2026-07-29T06:32:10.271437Z","steps":["trace[738815041] 'read index received' (duration: 17.827761ms)","trace[738815041] 'applied index is now lower than readState.Index' (duration: 98.892142ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:10.271683Z","caller":"traceutil/trace.go:171","msg":"trace[1738328045] transaction","detail":"{read_only:false; response_revision:4035; number_of_response:1; }","duration":"117.284424ms","start":"2026-07-29T06:32:10.154391Z","end":"2026-07-29T06:32:10.271675Z","steps":["trace[1738328045] 'process raft request' (duration: 117.005583ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:10.271652Z","caller":"traceutil/trace.go:171","msg":"trace[1478466396] transaction","detail":"{read_only:false; response_revision:4032; number_of_response:1; }","duration":"117.799583ms","start":"2026-07-29T06:32:10.153815Z","end":"2026-07-29T06:32:10.271614Z","steps":["trace[1478466396] 'process raft request' (duration: 90.096531ms)","trace[1478466396] 'compare' (duration: 27.341589ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:10.271814Z","caller":"traceutil/trace.go:171","msg":"trace[263358431] transaction","detail":"{read_only:false; response_revision:4033; number_of_response:1; }","duration":"117.564644ms","start":"2026-07-29T06:32:10.154227Z","end":"2026-07-29T06:32:10.271792Z","steps":["trace[263358431] 'process raft request' (duration: 117.129208ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:10.271974Z","caller":"traceutil/trace.go:171","msg":"trace[1244934902] transaction","detail":"{read_only:false; response_revision:4036; number_of_response:1; }","duration":"117.404938ms","start":"2026-07-29T06:32:10.154554Z","end":"2026-07-29T06:32:10.271959Z","steps":["trace[1244934902] 'process raft request' (duration: 116.860058ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:10.271683Z","caller":"traceutil/trace.go:171","msg":"trace[668090725] transaction","detail":"{read_only:false; response_revision:4034; number_of_response:1; }","duration":"117.285544ms","start":"2026-07-29T06:32:10.154308Z","end":"2026-07-29T06:32:10.271594Z","steps":["trace[668090725] 'process raft request' (duration: 117.068075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:10.272147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.421039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-29T06:32:10.27217Z","caller":"traceutil/trace.go:171","msg":"trace[1038864541] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:4036; }","duration":"117.492412ms","start":"2026-07-29T06:32:10.15467Z","end":"2026-07-29T06:32:10.272162Z","steps":["trace[1038864541] 'agreement among raft nodes before linearized reading' (duration: 117.359237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:10.272346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.61726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-j9rk4\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-07-29T06:32:10.272367Z","caller":"traceutil/trace.go:171","msg":"trace[2088940966] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-j9rk4; range_end:; response_count:1; response_revision:4036; }","duration":"115.666182ms","start":"2026-07-29T06:32:10.156695Z","end":"2026-07-29T06:32:10.272361Z","steps":["trace[2088940966] 'agreement among raft nodes before linearized reading' (duration: 115.59885ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:16.19747Z","caller":"traceutil/trace.go:171","msg":"trace[342232632] transaction","detail":"{read_only:false; response_revision:4425; number_of_response:1; }","duration":"310.9004ms","start":"2026-07-29T06:32:15.886514Z","end":"2026-07-29T06:32:16.197415Z","steps":["trace[342232632] 'process raft request' (duration: 310.696713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:16.197836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:15.886496Z","time spent":"311.180523ms","remote":"127.0.0.1:57028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:32:16.221468Z","caller":"traceutil/trace.go:171","msg":"trace[904979289] transaction","detail":"{read_only:false; response_revision:4426; number_of_response:1; }","duration":"331.433387ms","start":"2026-07-29T06:32:15.889995Z","end":"2026-07-29T06:32:16.221428Z","steps":["trace[904979289] 'process raft request' (duration: 331.234279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:16.22163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:15.889975Z","time spent":"331.564592ms","remote":"127.0.0.1:57254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:32:16.22177Z","caller":"traceutil/trace.go:171","msg":"trace[1595653048] linearizableReadLoop","detail":"{readStateIndex:4595; appliedIndex:4591; }","duration":"321.574841ms","start":"2026-07-29T06:32:15.900169Z","end":"2026-07-29T06:32:16.221743Z","steps":["trace[1595653048] 'read index received' (duration: 297.100534ms)","trace[1595653048] 'applied index is now lower than readState.Index' (duration: 24.472117ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:16.221771Z","caller":"traceutil/trace.go:171","msg":"trace[1929283348] transaction","detail":"{read_only:false; response_revision:4427; number_of_response:1; }","duration":"329.991383ms","start":"2026-07-29T06:32:15.89173Z","end":"2026-07-29T06:32:16.221721Z","steps":["trace[1929283348] 'process raft request' (duration: 329.64421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:16.222052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.880663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-29T06:32:16.222084Z","caller":"traceutil/trace.go:171","msg":"trace[323060212] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4428; }","duration":"321.941356ms","start":"2026-07-29T06:32:15.900134Z","end":"2026-07-29T06:32:16.222075Z","steps":["trace[323060212] 'agreement among raft nodes before linearized reading' (duration: 321.80747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:16.222106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:15.90012Z","time spent":"321.982097ms","remote":"127.0.0.1:56952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-07-29T06:32:16.222125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:15.891649Z","time spent":"330.331806ms","remote":"127.0.0.1:56914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1329,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:32:16.222187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.402724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"warn","ts":"2026-07-29T06:32:16.222181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.091449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:32:16.222214Z","caller":"traceutil/trace.go:171","msg":"trace[1094440897] transaction","detail":"{read_only:false; response_revision:4428; number_of_response:1; }","duration":"322.08305ms","start":"2026-07-29T06:32:15.90012Z","end":"2026-07-29T06:32:16.222203Z","steps":["trace[1094440897] 'process raft request' (duration: 321.498718ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:16.222234Z","caller":"traceutil/trace.go:171","msg":"trace[2052754403] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4428; }","duration":"321.486307ms","start":"2026-07-29T06:32:15.900731Z","end":"2026-07-29T06:32:16.222217Z","steps":["trace[2052754403] 'agreement among raft nodes before linearized reading' (duration: 321.317651ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:16.222244Z","caller":"traceutil/trace.go:171","msg":"trace[1552672771] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:0; response_revision:4428; }","duration":"317.182493ms","start":"2026-07-29T06:32:15.905046Z","end":"2026-07-29T06:32:16.222229Z","steps":["trace[1552672771] 'agreement among raft nodes before linearized reading' (duration: 317.066309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:16.222257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:15.900103Z","time spent":"322.128722ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:32:16.222244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.039524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"warn","ts":"2026-07-29T06:32:16.22227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:15.900717Z","time spent":"321.54392ms","remote":"127.0.0.1:54128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4759,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-07-29T06:32:16.222292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:15.90504Z","time spent":"317.239035ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-07-29T06:32:16.222315Z","caller":"traceutil/trace.go:171","msg":"trace[1332529311] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4428; }","duration":"185.149499ms","start":"2026-07-29T06:32:16.037144Z","end":"2026-07-29T06:32:16.222294Z","steps":["trace[1332529311] 'agreement among raft nodes before linearized reading' (duration: 184.942521ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:18.111561Z","caller":"traceutil/trace.go:171","msg":"trace[1933847464] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"246.336719ms","start":"2026-07-29T06:32:17.865196Z","end":"2026-07-29T06:32:18.111533Z","steps":["trace[1933847464] 'process raft request' (duration: 206.984053ms)","trace[1933847464] 'compare' (duration: 39.244491ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:18.357892Z","caller":"traceutil/trace.go:171","msg":"trace[1029047556] linearizableReadLoop","detail":"{readStateIndex:4645; appliedIndex:4643; }","duration":"468.654981ms","start":"2026-07-29T06:32:17.889205Z","end":"2026-07-29T06:32:18.35786Z","steps":["trace[1029047556] 'read index received' (duration: 182.992123ms)","trace[1029047556] 'applied index is now lower than readState.Index' (duration: 285.661908ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:18.357947Z","caller":"traceutil/trace.go:171","msg":"trace[1378776171] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"490.670315ms","start":"2026-07-29T06:32:17.867229Z","end":"2026-07-29T06:32:18.357899Z","steps":["trace[1378776171] 'process raft request' (duration: 446.797636ms)","trace[1378776171] 'compare' (duration: 43.662941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:32:18.358171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:17.867216Z","time spent":"490.832901ms","remote":"127.0.0.1:56996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3932,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:32:18.358258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.033975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2f373bc-3a68-4248-9034-ae8352b34fe1\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-07-29T06:32:18.358309Z","caller":"traceutil/trace.go:171","msg":"trace[2127511154] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2f373bc-3a68-4248-9034-ae8352b34fe1; range_end:; response_count:1; response_revision:4478; }","duration":"469.15036ms","start":"2026-07-29T06:32:17.889147Z","end":"2026-07-29T06:32:18.358297Z","steps":["trace[2127511154] 'agreement among raft nodes before linearized reading' (duration: 468.944782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:18.358355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:17.889081Z","time spent":"469.264174ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3796,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2f373bc-3a68-4248-9034-ae8352b34fe1\" "} {"level":"warn","ts":"2026-07-29T06:32:18.358375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.756896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T06:32:18.358476Z","caller":"traceutil/trace.go:171","msg":"trace[432917351] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4478; }","duration":"467.902152ms","start":"2026-07-29T06:32:17.890554Z","end":"2026-07-29T06:32:18.358456Z","steps":["trace[432917351] 'agreement among raft nodes before linearized reading' (duration: 467.735805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:18.358544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:17.890535Z","time spent":"467.989924ms","remote":"127.0.0.1:57926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-07-29T06:32:18.358702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.512825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2f373bc-3a68-4248-9034-ae8352b34fe1\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-07-29T06:32:18.358745Z","caller":"traceutil/trace.go:171","msg":"trace[351308443] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2f373bc-3a68-4248-9034-ae8352b34fe1; range_end:; response_count:1; response_revision:4478; }","duration":"243.592938ms","start":"2026-07-29T06:32:18.11514Z","end":"2026-07-29T06:32:18.358733Z","steps":["trace[351308443] 'agreement among raft nodes before linearized reading' (duration: 243.429332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:18.358852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.463672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-29T06:32:18.358942Z","caller":"traceutil/trace.go:171","msg":"trace[33485859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4478; }","duration":"111.063327ms","start":"2026-07-29T06:32:18.247863Z","end":"2026-07-29T06:32:18.358926Z","steps":["trace[33485859] 'agreement among raft nodes before linearized reading' (duration: 110.703693ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:18.563013Z","caller":"traceutil/trace.go:171","msg":"trace[2060711348] linearizableReadLoop","detail":"{readStateIndex:4648; appliedIndex:4645; }","duration":"175.934384ms","start":"2026-07-29T06:32:18.387042Z","end":"2026-07-29T06:32:18.562976Z","steps":["trace[2060711348] 'read index received' (duration: 46.710429ms)","trace[2060711348] 'applied index is now lower than readState.Index' (duration: 129.222795ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:18.563037Z","caller":"traceutil/trace.go:171","msg":"trace[1285161360] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"196.643321ms","start":"2026-07-29T06:32:18.36634Z","end":"2026-07-29T06:32:18.562983Z","steps":["trace[1285161360] 'process raft request' (duration: 195.32819ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:18.563044Z","caller":"traceutil/trace.go:171","msg":"trace[1484918397] transaction","detail":"{read_only:false; response_revision:4481; number_of_response:1; }","duration":"196.216164ms","start":"2026-07-29T06:32:18.366793Z","end":"2026-07-29T06:32:18.563009Z","steps":["trace[1484918397] 'process raft request' (duration: 196.077339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:18.563203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.130912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:32:18.563741Z","caller":"traceutil/trace.go:171","msg":"trace[1701015150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4481; }","duration":"176.737544ms","start":"2026-07-29T06:32:18.386987Z","end":"2026-07-29T06:32:18.563724Z","steps":["trace[1701015150] 'agreement among raft nodes before linearized reading' (duration: 176.11087ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:18.946948Z","caller":"traceutil/trace.go:171","msg":"trace[412494753] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"147.186047ms","start":"2026-07-29T06:32:18.799731Z","end":"2026-07-29T06:32:18.946917Z","steps":["trace[412494753] 'process raft request' (duration: 93.030533ms)","trace[412494753] 'compare' (duration: 54.002118ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:19.009333Z","caller":"traceutil/trace.go:171","msg":"trace[1845360537] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4493; }","duration":"158.1925ms","start":"2026-07-29T06:32:18.851115Z","end":"2026-07-29T06:32:19.009308Z","steps":["trace[1845360537] 'process raft request' (duration: 157.953921ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:19.009639Z","caller":"traceutil/trace.go:171","msg":"trace[725866859] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"120.049303ms","start":"2026-07-29T06:32:18.889562Z","end":"2026-07-29T06:32:19.009611Z","steps":["trace[725866859] 'process raft request' (duration: 119.70783ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:19.00963Z","caller":"traceutil/trace.go:171","msg":"trace[1917975047] linearizableReadLoop","detail":"{readStateIndex:4660; appliedIndex:4658; }","duration":"153.706668ms","start":"2026-07-29T06:32:18.855883Z","end":"2026-07-29T06:32:19.00959Z","steps":["trace[1917975047] 'read index received' (duration: 36.947873ms)","trace[1917975047] 'applied index is now lower than readState.Index' (duration: 116.758005ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:32:19.009865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.863528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-acb21601-4437-4b58-ab46-a8720be7a92a\" ","response":"range_response_count:1 size:3879"} {"level":"info","ts":"2026-07-29T06:32:19.009894Z","caller":"traceutil/trace.go:171","msg":"trace[103364232] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-acb21601-4437-4b58-ab46-a8720be7a92a; range_end:; response_count:1; response_revision:4494; }","duration":"137.943531ms","start":"2026-07-29T06:32:18.871943Z","end":"2026-07-29T06:32:19.009887Z","steps":["trace[103364232] 'agreement among raft nodes before linearized reading' (duration: 137.831327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:19.010127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.242569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-29T06:32:19.01017Z","caller":"traceutil/trace.go:171","msg":"trace[339475481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4494; }","duration":"154.310732ms","start":"2026-07-29T06:32:18.855854Z","end":"2026-07-29T06:32:19.010165Z","steps":["trace[339475481] 'agreement among raft nodes before linearized reading' (duration: 153.900405ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:19.136096Z","caller":"traceutil/trace.go:171","msg":"trace[432200287] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"115.204206ms","start":"2026-07-29T06:32:19.020867Z","end":"2026-07-29T06:32:19.136072Z","steps":["trace[432200287] 'process raft request' (duration: 52.990305ms)","trace[432200287] 'compare' (duration: 61.955892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:32:20.390951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.431444ms","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-07-29T06:32:20.391088Z","caller":"traceutil/trace.go:171","msg":"trace[1467903594] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4512; }","duration":"126.59533ms","start":"2026-07-29T06:32:20.264473Z","end":"2026-07-29T06:32:20.391069Z","steps":["trace[1467903594] 'range keys from in-memory index tree' (duration: 126.408903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:20.391242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.181332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:32:20.391415Z","caller":"traceutil/trace.go:171","msg":"trace[314323658] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"222.873478ms","start":"2026-07-29T06:32:20.168526Z","end":"2026-07-29T06:32:20.3914Z","steps":["trace[314323658] 'process raft request' (duration: 18.446085ms)","trace[314323658] 'compare' (duration: 204.017636ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:20.518272Z","caller":"traceutil/trace.go:171","msg":"trace[1010554612] transaction","detail":"{read_only:false; response_revision:4514; number_of_response:1; }","duration":"121.383398ms","start":"2026-07-29T06:32:20.396845Z","end":"2026-07-29T06:32:20.518229Z","steps":["trace[1010554612] 'process raft request' (duration: 121.056975ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:23.344975Z","caller":"traceutil/trace.go:171","msg":"trace[345798667] linearizableReadLoop","detail":"{readStateIndex:4763; appliedIndex:4762; }","duration":"119.069026ms","start":"2026-07-29T06:32:23.225843Z","end":"2026-07-29T06:32:23.344912Z","steps":["trace[345798667] 'read index received' (duration: 74.558809ms)","trace[345798667] 'applied index is now lower than readState.Index' (duration: 44.509137ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:23.345017Z","caller":"traceutil/trace.go:171","msg":"trace[107332617] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"131.567963ms","start":"2026-07-29T06:32:23.213422Z","end":"2026-07-29T06:32:23.34499Z","steps":["trace[107332617] 'process raft request' (duration: 87.028535ms)","trace[107332617] 'compare' (duration: 44.225536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:32:23.345177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.313765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-29T06:32:23.345217Z","caller":"traceutil/trace.go:171","msg":"trace[1203939211] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4593; }","duration":"119.368077ms","start":"2026-07-29T06:32:23.225838Z","end":"2026-07-29T06:32:23.345206Z","steps":["trace[1203939211] 'agreement among raft nodes before linearized reading' (duration: 119.221932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:23.770912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.269714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:32:23.770989Z","caller":"traceutil/trace.go:171","msg":"trace[198231675] linearizableReadLoop","detail":"{readStateIndex:4764; appliedIndex:4763; }","duration":"337.998327ms","start":"2026-07-29T06:32:23.432979Z","end":"2026-07-29T06:32:23.770978Z","steps":["trace[198231675] 'read index received' (duration: 199.513305ms)","trace[198231675] 'applied index is now lower than readState.Index' (duration: 138.484072ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:23.771053Z","caller":"traceutil/trace.go:171","msg":"trace[640467315] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"367.143384ms","start":"2026-07-29T06:32:23.403902Z","end":"2026-07-29T06:32:23.771045Z","steps":["trace[640467315] 'process raft request' (duration: 228.668632ms)","trace[640467315] 'compare' (duration: 138.016254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:32:23.771093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:23.403889Z","time spent":"367.184015ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:32:23.771299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.929662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-29T06:32:23.771392Z","caller":"traceutil/trace.go:171","msg":"trace[812447998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4594; }","duration":"279.058797ms","start":"2026-07-29T06:32:23.492314Z","end":"2026-07-29T06:32:23.771373Z","steps":["trace[812447998] 'agreement among raft nodes before linearized reading' (duration: 278.849989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:23.771488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.638776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-29T06:32:23.771532Z","caller":"traceutil/trace.go:171","msg":"trace[1959007222] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4594; }","duration":"140.72101ms","start":"2026-07-29T06:32:23.630798Z","end":"2026-07-29T06:32:23.771519Z","steps":["trace[1959007222] 'agreement among raft nodes before linearized reading' (duration: 140.591025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:23.771601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.274701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T06:32:23.771644Z","caller":"traceutil/trace.go:171","msg":"trace[769281879] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4594; }","duration":"273.364664ms","start":"2026-07-29T06:32:23.498266Z","end":"2026-07-29T06:32:23.771631Z","steps":["trace[769281879] 'agreement among raft nodes before linearized reading' (duration: 273.302402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:23.771736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.747746ms","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-07-29T06:32:23.771793Z","caller":"traceutil/trace.go:171","msg":"trace[940206209] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4594; }","duration":"338.84479ms","start":"2026-07-29T06:32:23.432933Z","end":"2026-07-29T06:32:23.771778Z","steps":["trace[940206209] 'agreement among raft nodes before linearized reading' (duration: 338.710745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:23.771832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:23.432905Z","time spent":"338.918703ms","remote":"127.0.0.1:57022","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":"warn","ts":"2026-07-29T06:32:24.103503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.799722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:32:24.103738Z","caller":"traceutil/trace.go:171","msg":"trace[336737656] linearizableReadLoop","detail":"{readStateIndex:4767; appliedIndex:4765; }","duration":"171.336804ms","start":"2026-07-29T06:32:23.932385Z","end":"2026-07-29T06:32:24.103721Z","steps":["trace[336737656] 'read index received' (duration: 48.141µs)","trace[336737656] 'applied index is now lower than readState.Index' (duration: 171.287523ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:24.103829Z","caller":"traceutil/trace.go:171","msg":"trace[1327926560] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"328.035047ms","start":"2026-07-29T06:32:23.775776Z","end":"2026-07-29T06:32:24.103811Z","steps":["trace[1327926560] 'process raft request' (duration: 124.808808ms)","trace[1327926560] 'compare' (duration: 202.403796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:32:24.103895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:23.775753Z","time spent":"328.10311ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T06:32:24.103923Z","caller":"traceutil/trace.go:171","msg":"trace[1145294983] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"326.260299ms","start":"2026-07-29T06:32:23.777625Z","end":"2026-07-29T06:32:24.103885Z","steps":["trace[1145294983] 'process raft request' (duration: 325.988648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:24.104707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:23.77759Z","time spent":"327.030458ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:32:24.104016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.610475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-lhd8k\" ","response":"range_response_count:1 size:9752"} {"level":"info","ts":"2026-07-29T06:32:24.10501Z","caller":"traceutil/trace.go:171","msg":"trace[328286495] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-lhd8k; range_end:; response_count:1; response_revision:4597; }","duration":"172.654615ms","start":"2026-07-29T06:32:23.932335Z","end":"2026-07-29T06:32:24.10499Z","steps":["trace[328286495] 'agreement among raft nodes before linearized reading' (duration: 171.480979ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:28.418173Z","caller":"traceutil/trace.go:171","msg":"trace[1599708342] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4849; }","duration":"329.419255ms","start":"2026-07-29T06:32:28.088734Z","end":"2026-07-29T06:32:28.418153Z","steps":["trace[1599708342] 'read index received' (duration: 329.228288ms)","trace[1599708342] 'applied index is now lower than readState.Index' (duration: 189.727µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:28.418234Z","caller":"traceutil/trace.go:171","msg":"trace[1668665364] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"441.335784ms","start":"2026-07-29T06:32:27.976864Z","end":"2026-07-29T06:32:28.418199Z","steps":["trace[1668665364] 'process raft request' (duration: 441.139116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:28.418399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:27.976852Z","time spent":"441.463178ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T06:32:28.418497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.757151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T06:32:28.418574Z","caller":"traceutil/trace.go:171","msg":"trace[1936064915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4678; }","duration":"302.872166ms","start":"2026-07-29T06:32:28.115685Z","end":"2026-07-29T06:32:28.418557Z","steps":["trace[1936064915] 'agreement among raft nodes before linearized reading' (duration: 302.636177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:28.418623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:28.115671Z","time spent":"302.943839ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T06:32:28.418845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.219581ms","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-07-29T06:32:28.418893Z","caller":"traceutil/trace.go:171","msg":"trace[1791477809] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4678; }","duration":"302.282082ms","start":"2026-07-29T06:32:28.116598Z","end":"2026-07-29T06:32:28.418881Z","steps":["trace[1791477809] 'agreement among raft nodes before linearized reading' (duration: 302.142967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:28.418923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:28.116591Z","time spent":"302.325275ms","remote":"127.0.0.1:57022","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":"warn","ts":"2026-07-29T06:32:28.418943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.205075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54e44c79-ce96-4174-8d4f-e9879013accf\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-07-29T06:32:28.418983Z","caller":"traceutil/trace.go:171","msg":"trace[1005801035] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54e44c79-ce96-4174-8d4f-e9879013accf; range_end:; response_count:1; response_revision:4678; }","duration":"330.279379ms","start":"2026-07-29T06:32:28.088692Z","end":"2026-07-29T06:32:28.418971Z","steps":["trace[1005801035] 'agreement among raft nodes before linearized reading' (duration: 329.574171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:28.419011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T06:32:28.088673Z","time spent":"330.332001ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54e44c79-ce96-4174-8d4f-e9879013accf\" "} {"level":"info","ts":"2026-07-29T06:32:28.548326Z","caller":"traceutil/trace.go:171","msg":"trace[1854751761] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"123.420236ms","start":"2026-07-29T06:32:28.424874Z","end":"2026-07-29T06:32:28.548294Z","steps":["trace[1854751761] 'process raft request' (duration: 86.30297ms)","trace[1854751761] 'compare' (duration: 36.931369ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:28.548937Z","caller":"traceutil/trace.go:171","msg":"trace[28000145] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"120.212768ms","start":"2026-07-29T06:32:28.428697Z","end":"2026-07-29T06:32:28.54891Z","steps":["trace[28000145] 'process raft request' (duration: 119.545732ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:28.92248Z","caller":"traceutil/trace.go:171","msg":"trace[2000733597] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"102.23957ms","start":"2026-07-29T06:32:28.820218Z","end":"2026-07-29T06:32:28.922457Z","steps":["trace[2000733597] 'process raft request' (duration: 102.105885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:31.945586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.30995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-07-29T06:32:31.945679Z","caller":"traceutil/trace.go:171","msg":"trace[142414115] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4731; }","duration":"157.456706ms","start":"2026-07-29T06:32:31.7882Z","end":"2026-07-29T06:32:31.945657Z","steps":["trace[142414115] 'range keys from in-memory index tree' (duration: 157.086771ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:36.863662Z","caller":"traceutil/trace.go:171","msg":"trace[1092258058] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"264.185137ms","start":"2026-07-29T06:32:36.599451Z","end":"2026-07-29T06:32:36.863636Z","steps":["trace[1092258058] 'process raft request' (duration: 264.03508ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:36.88074Z","caller":"traceutil/trace.go:171","msg":"trace[617138721] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4985; }","duration":"273.796293ms","start":"2026-07-29T06:32:36.606915Z","end":"2026-07-29T06:32:36.880712Z","steps":["trace[617138721] 'read index received' (duration: 256.625071ms)","trace[617138721] 'applied index is now lower than readState.Index' (duration: 17.169992ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:36.880822Z","caller":"traceutil/trace.go:171","msg":"trace[1461528450] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"279.65592ms","start":"2026-07-29T06:32:36.601145Z","end":"2026-07-29T06:32:36.880801Z","steps":["trace[1461528450] 'process raft request' (duration: 279.4187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:36.880962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.032983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T06:32:36.88101Z","caller":"traceutil/trace.go:171","msg":"trace[647427840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4810; }","duration":"274.128936ms","start":"2026-07-29T06:32:36.606858Z","end":"2026-07-29T06:32:36.880987Z","steps":["trace[647427840] 'agreement among raft nodes before linearized reading' (duration: 273.953259ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.121957Z","caller":"traceutil/trace.go:171","msg":"trace[1620838885] linearizableReadLoop","detail":"{readStateIndex:5011; appliedIndex:5010; }","duration":"226.162327ms","start":"2026-07-29T06:32:40.895779Z","end":"2026-07-29T06:32:41.121941Z","steps":["trace[1620838885] 'read index received' (duration: 226.097515ms)","trace[1620838885] 'applied index is now lower than readState.Index' (duration: 64.122µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:41.122178Z","caller":"traceutil/trace.go:171","msg":"trace[71716114] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"240.569932ms","start":"2026-07-29T06:32:40.881598Z","end":"2026-07-29T06:32:41.122168Z","steps":["trace[71716114] 'process raft request' (duration: 240.244549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:41.122394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.616026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T06:32:41.122417Z","caller":"traceutil/trace.go:171","msg":"trace[1184001536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4833; }","duration":"226.701109ms","start":"2026-07-29T06:32:40.89571Z","end":"2026-07-29T06:32:41.122411Z","steps":["trace[1184001536] 'agreement among raft nodes before linearized reading' (duration: 226.592335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:41.123203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.900716ms","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-07-29T06:32:41.123267Z","caller":"traceutil/trace.go:171","msg":"trace[1682897163] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"157.872773ms","start":"2026-07-29T06:32:40.96538Z","end":"2026-07-29T06:32:41.123253Z","steps":["trace[1682897163] 'process raft request' (duration: 157.468907ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.123303Z","caller":"traceutil/trace.go:171","msg":"trace[1051442090] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4834; }","duration":"225.034891ms","start":"2026-07-29T06:32:40.898243Z","end":"2026-07-29T06:32:41.123278Z","steps":["trace[1051442090] 'agreement among raft nodes before linearized reading' (duration: 224.76378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:32:41.123631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.362473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-55994fbccf-cdkfb\" ","response":"range_response_count:1 size:11844"} {"level":"info","ts":"2026-07-29T06:32:41.123668Z","caller":"traceutil/trace.go:171","msg":"trace[1540296334] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-55994fbccf-cdkfb; range_end:; response_count:1; response_revision:4834; }","duration":"190.440566ms","start":"2026-07-29T06:32:40.933217Z","end":"2026-07-29T06:32:41.123657Z","steps":["trace[1540296334] 'agreement among raft nodes before linearized reading' (duration: 190.362663ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.269383Z","caller":"traceutil/trace.go:171","msg":"trace[1007568180] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"138.482907ms","start":"2026-07-29T06:32:41.130883Z","end":"2026-07-29T06:32:41.269366Z","steps":["trace[1007568180] 'process raft request' (duration: 100.076624ms)","trace[1007568180] 'compare' (duration: 38.0966ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:41.296412Z","caller":"traceutil/trace.go:171","msg":"trace[1339246948] linearizableReadLoop","detail":"{readStateIndex:5016; appliedIndex:5012; }","duration":"121.683004ms","start":"2026-07-29T06:32:41.174699Z","end":"2026-07-29T06:32:41.296382Z","steps":["trace[1339246948] 'read index received' (duration: 56.232969ms)","trace[1339246948] 'applied index is now lower than readState.Index' (duration: 65.449045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:32:41.296702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.924673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-29T06:32:41.296747Z","caller":"traceutil/trace.go:171","msg":"trace[1713498917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4838; }","duration":"122.08667ms","start":"2026-07-29T06:32:41.174648Z","end":"2026-07-29T06:32:41.296735Z","steps":["trace[1713498917] 'agreement among raft nodes before linearized reading' (duration: 121.84107ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.29698Z","caller":"traceutil/trace.go:171","msg":"trace[1507153125] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"165.670454ms","start":"2026-07-29T06:32:41.13128Z","end":"2026-07-29T06:32:41.296951Z","steps":["trace[1507153125] 'process raft request' (duration: 164.951645ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.297152Z","caller":"traceutil/trace.go:171","msg":"trace[1764361332] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"162.315967ms","start":"2026-07-29T06:32:41.134821Z","end":"2026-07-29T06:32:41.297137Z","steps":["trace[1764361332] 'process raft request' (duration: 161.470453ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.297524Z","caller":"traceutil/trace.go:171","msg":"trace[1809361074] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"166.544779ms","start":"2026-07-29T06:32:41.130965Z","end":"2026-07-29T06:32:41.297509Z","steps":["trace[1809361074] 'process raft request' (duration: 165.111831ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.465074Z","caller":"traceutil/trace.go:171","msg":"trace[1344211936] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"159.863247ms","start":"2026-07-29T06:32:41.305185Z","end":"2026-07-29T06:32:41.465048Z","steps":["trace[1344211936] 'process raft request' (duration: 69.169886ms)","trace[1344211936] 'compare' (duration: 90.487533ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:32:41.465498Z","caller":"traceutil/trace.go:171","msg":"trace[1149413431] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"160.032684ms","start":"2026-07-29T06:32:41.305429Z","end":"2026-07-29T06:32:41.465461Z","steps":["trace[1149413431] 'process raft request' (duration: 159.784594ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.465667Z","caller":"traceutil/trace.go:171","msg":"trace[1760454805] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"159.563876ms","start":"2026-07-29T06:32:41.306087Z","end":"2026-07-29T06:32:41.465651Z","steps":["trace[1760454805] 'process raft request' (duration: 159.239783ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.465609Z","caller":"traceutil/trace.go:171","msg":"trace[2013715878] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"160.150109ms","start":"2026-07-29T06:32:41.305361Z","end":"2026-07-29T06:32:41.465511Z","steps":["trace[2013715878] 'process raft request' (duration: 159.629128ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.467048Z","caller":"traceutil/trace.go:171","msg":"trace[1922129659] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"158.376427ms","start":"2026-07-29T06:32:41.308655Z","end":"2026-07-29T06:32:41.467032Z","steps":["trace[1922129659] 'process raft request' (duration: 157.774403ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:32:41.467487Z","caller":"traceutil/trace.go:171","msg":"trace[1224424950] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"156.469229ms","start":"2026-07-29T06:32:41.310937Z","end":"2026-07-29T06:32:41.467406Z","steps":["trace[1224424950] 'process raft request' (duration: 155.680138ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:33:02.163168Z","caller":"traceutil/trace.go:171","msg":"trace[520753924] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"156.013885ms","start":"2026-07-29T06:33:02.007128Z","end":"2026-07-29T06:33:02.163141Z","steps":["trace[520753924] 'process raft request' (duration: 141.937498ms)","trace[520753924] 'compare' (duration: 13.891159ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:33:02.163457Z","caller":"traceutil/trace.go:171","msg":"trace[191690485] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"154.644758ms","start":"2026-07-29T06:33:02.0088Z","end":"2026-07-29T06:33:02.163445Z","steps":["trace[191690485] 'process raft request' (duration: 154.298392ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:33:07.408779Z","caller":"traceutil/trace.go:171","msg":"trace[1482834445] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5316; }","duration":"148.259431ms","start":"2026-07-29T06:33:07.260497Z","end":"2026-07-29T06:33:07.408756Z","steps":["trace[1482834445] 'read index received' (duration: 148.045201ms)","trace[1482834445] 'applied index is now lower than readState.Index' (duration: 213.03µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:33:07.408862Z","caller":"traceutil/trace.go:171","msg":"trace[1997662632] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"162.539321ms","start":"2026-07-29T06:33:07.246293Z","end":"2026-07-29T06:33:07.408833Z","steps":["trace[1997662632] 'process raft request' (duration: 162.272979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:33:07.409047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.522231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-29T06:33:07.409134Z","caller":"traceutil/trace.go:171","msg":"trace[1280842711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5133; }","duration":"148.662638ms","start":"2026-07-29T06:33:07.260455Z","end":"2026-07-29T06:33:07.409118Z","steps":["trace[1280842711] 'agreement among raft nodes before linearized reading' (duration: 148.393686ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:33:15.530664Z","caller":"traceutil/trace.go:171","msg":"trace[2001554268] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5365; }","duration":"143.085329ms","start":"2026-07-29T06:33:15.387529Z","end":"2026-07-29T06:33:15.530614Z","steps":["trace[2001554268] 'read index received' (duration: 107.544579ms)","trace[2001554268] 'applied index is now lower than readState.Index' (duration: 35.533679ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:33:15.531448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.902023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T06:33:15.53306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.057437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362732"} {"level":"info","ts":"2026-07-29T06:33:15.533177Z","caller":"traceutil/trace.go:171","msg":"trace[1556496185] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5181; }","duration":"107.242575ms","start":"2026-07-29T06:33:15.425914Z","end":"2026-07-29T06:33:15.533157Z","steps":["trace[1556496185] 'agreement among raft nodes before linearized reading' (duration: 104.877792ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:33:15.531498Z","caller":"traceutil/trace.go:171","msg":"trace[1328355031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5181; }","duration":"143.997857ms","start":"2026-07-29T06:33:15.387489Z","end":"2026-07-29T06:33:15.531487Z","steps":["trace[1328355031] 'agreement among raft nodes before linearized reading' (duration: 143.278646ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:33:15.807569Z","caller":"traceutil/trace.go:171","msg":"trace[995256785] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"109.443051ms","start":"2026-07-29T06:33:15.698097Z","end":"2026-07-29T06:33:15.80754Z","steps":["trace[995256785] 'process raft request' (duration: 51.02594ms)","trace[995256785] 'compare' (duration: 58.281725ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T06:33:16.653873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.124596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T06:33:16.653883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.612112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T06:33:16.653945Z","caller":"traceutil/trace.go:171","msg":"trace[1862543539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5191; }","duration":"266.243772ms","start":"2026-07-29T06:33:16.387684Z","end":"2026-07-29T06:33:16.653927Z","steps":["trace[1862543539] 'range keys from in-memory index tree' (duration: 266.020501ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:33:16.654013Z","caller":"traceutil/trace.go:171","msg":"trace[1812649054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5191; }","duration":"167.864743ms","start":"2026-07-29T06:33:16.486119Z","end":"2026-07-29T06:33:16.653984Z","steps":["trace[1812649054] 'count revisions from in-memory index tree' (duration: 167.472777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:33:16.654014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.507341ms","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-07-29T06:33:16.654079Z","caller":"traceutil/trace.go:171","msg":"trace[812358268] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5191; }","duration":"174.605135ms","start":"2026-07-29T06:33:16.479454Z","end":"2026-07-29T06:33:16.654059Z","steps":["trace[812358268] 'count revisions from in-memory index tree' (duration: 174.393516ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:34:07.2303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2865} {"level":"info","ts":"2026-07-29T06:34:07.319679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2865,"took":"88.175363ms","hash":3310741650,"current-db-size-bytes":54095872,"current-db-size":"54 MB","current-db-size-in-use-bytes":30072832,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-29T06:34:07.319738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3310741650,"revision":2865,"compact-revision":-1} {"level":"info","ts":"2026-07-29T06:34:18.049049Z","caller":"traceutil/trace.go:171","msg":"trace[2041655832] linearizableReadLoop","detail":"{readStateIndex:5835; appliedIndex:5834; }","duration":"133.006752ms","start":"2026-07-29T06:34:17.91601Z","end":"2026-07-29T06:34:18.049017Z","steps":["trace[2041655832] 'read index received' (duration: 126.32792ms)","trace[2041655832] 'applied index is now lower than readState.Index' (duration: 6.677652ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:34:18.049152Z","caller":"traceutil/trace.go:171","msg":"trace[1795680660] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"136.052744ms","start":"2026-07-29T06:34:17.913067Z","end":"2026-07-29T06:34:18.04912Z","steps":["trace[1795680660] 'process raft request' (duration: 129.33501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:34:18.049366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.340288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-29T06:34:18.049515Z","caller":"traceutil/trace.go:171","msg":"trace[2130332281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5635; }","duration":"133.495345ms","start":"2026-07-29T06:34:17.915971Z","end":"2026-07-29T06:34:18.049466Z","steps":["trace[2130332281] 'agreement among raft nodes before linearized reading' (duration: 133.16568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:34:18.049548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.161683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-29T06:34:18.049856Z","caller":"traceutil/trace.go:171","msg":"trace[2052601126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5635; }","duration":"111.491159ms","start":"2026-07-29T06:34:17.938302Z","end":"2026-07-29T06:34:18.049793Z","steps":["trace[2052601126] 'agreement among raft nodes before linearized reading' (duration: 110.930633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:34:18.049506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.413455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-29T06:34:18.050131Z","caller":"traceutil/trace.go:171","msg":"trace[1525616360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5635; }","duration":"121.124467ms","start":"2026-07-29T06:34:17.928975Z","end":"2026-07-29T06:34:18.050099Z","steps":["trace[1525616360] 'agreement among raft nodes before linearized reading' (duration: 120.253287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:34:18.049676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.984675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T06:34:18.050331Z","caller":"traceutil/trace.go:171","msg":"trace[863379441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5635; }","duration":"117.555382ms","start":"2026-07-29T06:34:17.93267Z","end":"2026-07-29T06:34:18.050225Z","steps":["trace[863379441] 'agreement among raft nodes before linearized reading' (duration: 116.935733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:36:37.118587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.970225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T06:36:37.118749Z","caller":"traceutil/trace.go:171","msg":"trace[1365335082] linearizableReadLoop","detail":"{readStateIndex:6833; appliedIndex:6831; }","duration":"157.585126ms","start":"2026-07-29T06:36:36.961149Z","end":"2026-07-29T06:36:37.118734Z","steps":["trace[1365335082] 'read index received' (duration: 20.398771ms)","trace[1365335082] 'applied index is now lower than readState.Index' (duration: 137.185145ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:36:37.119125Z","caller":"traceutil/trace.go:171","msg":"trace[1630487479] transaction","detail":"{read_only:false; response_revision:6595; number_of_response:1; }","duration":"241.539903ms","start":"2026-07-29T06:36:36.877569Z","end":"2026-07-29T06:36:37.119109Z","steps":["trace[1630487479] 'process raft request' (duration: 103.967129ms)","trace[1630487479] 'compare' (duration: 136.875569ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T06:36:37.119298Z","caller":"traceutil/trace.go:171","msg":"trace[827906536] transaction","detail":"{read_only:false; response_revision:6596; number_of_response:1; }","duration":"229.064749ms","start":"2026-07-29T06:36:36.890225Z","end":"2026-07-29T06:36:37.11929Z","steps":["trace[827906536] 'process raft request' (duration: 228.461179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:36:37.119525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.369916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:1 size:111517"} {"level":"info","ts":"2026-07-29T06:36:37.119564Z","caller":"traceutil/trace.go:171","msg":"trace[665830730] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:1; response_revision:6596; }","duration":"158.410988ms","start":"2026-07-29T06:36:36.961142Z","end":"2026-07-29T06:36:37.119553Z","steps":["trace[665830730] 'agreement among raft nodes before linearized reading' (duration: 158.270181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:36:37.119767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.513653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T06:36:37.119808Z","caller":"traceutil/trace.go:171","msg":"trace[1964097385] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:6596; }","duration":"158.574216ms","start":"2026-07-29T06:36:36.961224Z","end":"2026-07-29T06:36:37.119798Z","steps":["trace[1964097385] 'agreement among raft nodes before linearized reading' (duration: 158.503322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T06:36:37.120271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.573993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-29T06:36:37.120313Z","caller":"traceutil/trace.go:171","msg":"trace[935101525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6596; }","duration":"130.645406ms","start":"2026-07-29T06:36:36.989659Z","end":"2026-07-29T06:36:37.120304Z","steps":["trace[935101525] 'agreement among raft nodes before linearized reading' (duration: 130.557812ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T06:39:07.237052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5580} {"level":"info","ts":"2026-07-29T06:39:07.318081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5580,"took":"79.361535ms","hash":1087785525,"current-db-size-bytes":54095872,"current-db-size":"54 MB","current-db-size-in-use-bytes":18313216,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-29T06:39:07.318135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087785525,"revision":5580,"compact-revision":2865}