{"level":"warn","ts":"2026-07-27T20:05:29.882243Z","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-27T20:05:29.882373Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-27T20:05:29.882457Z","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-27T20:05:29.88247Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-27T20:05:29.882525Z","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-27T20:05:29.883371Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-07-27T20:05:29.88348Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-27T20:05:29.888512Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.79507ms"} {"level":"info","ts":"2026-07-27T20:05:29.897839Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-07-27T20:05:29.897971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T20:05:29.898005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-07-27T20:05:29.89802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T20:05:29.898031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-07-27T20:05:29.898076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-07-27T20:05:29.904155Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T20:05:29.906296Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T20:05:29.907255Z","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-27T20:05:29.909435Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T20:05:29.909629Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T20:05:29.909858Z","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-27T20:05:29.909983Z","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-27T20:05:29.910104Z","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-27T20:05:29.91238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-07-27T20:05:29.912652Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-27T20:05:29.914468Z","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-27T20:05:29.914623Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-27T20:05:29.914678Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-27T20:05:29.9151Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T20:05:29.915044Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T20:05:30.598695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T20:05:30.598791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T20:05:30.598855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-07-27T20:05:30.598877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-07-27T20:05:30.598888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-27T20:05:30.598905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-07-27T20:05:30.59892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-27T20:05:30.600228Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T20:05:30.600444Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:05:30.600661Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:05:30.60107Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:30.60128Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T20:05:30.601323Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T20:05:30.604622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-07-27T20:05:30.604766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T20:05:30.604881Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:30.604966Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:30.604993Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:34.18987Z","caller":"traceutil/trace.go:171","msg":"trace[1915993148] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"216.07788ms","start":"2026-07-27T20:05:33.973757Z","end":"2026-07-27T20:05:34.189835Z","steps":["trace[1915993148] 'process raft request' (duration: 161.804471ms)","trace[1915993148] 'compare' (duration: 54.102954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:05:40.268664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.277058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:05:40.268805Z","caller":"traceutil/trace.go:171","msg":"trace[1270029400] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"328.040771ms","start":"2026-07-27T20:05:39.940744Z","end":"2026-07-27T20:05:40.268785Z","steps":["trace[1270029400] 'read index received' (duration: 115.955161ms)","trace[1270029400] 'applied index is now lower than readState.Index' (duration: 212.08453ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:05:40.268857Z","caller":"traceutil/trace.go:171","msg":"trace[117154054] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"370.290711ms","start":"2026-07-27T20:05:39.898525Z","end":"2026-07-27T20:05:40.268816Z","steps":["trace[117154054] 'process raft request' (duration: 158.275283ms)","trace[117154054] 'compare' (duration: 211.098164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:05:40.268981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.813522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-27T20:05:40.269032Z","caller":"traceutil/trace.go:171","msg":"trace[971900388] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:317; }","duration":"178.906715ms","start":"2026-07-27T20:05:40.090116Z","end":"2026-07-27T20:05:40.269023Z","steps":["trace[971900388] 'agreement among raft nodes before linearized reading' (duration: 178.840643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:05:40.269043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.313555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-07-27T20:05:40.269051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:05:39.898465Z","time spent":"370.496215ms","remote":"127.0.0.1:36320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:05:40.269126Z","caller":"traceutil/trace.go:171","msg":"trace[1950222942] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:317; }","duration":"280.424167ms","start":"2026-07-27T20:05:39.988685Z","end":"2026-07-27T20:05:40.269109Z","steps":["trace[1950222942] 'agreement among raft nodes before linearized reading' (duration: 280.265973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:05:40.269142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.159859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-27T20:05:40.268924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.187435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-07-27T20:05:40.269167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.794029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-27T20:05:40.269287Z","caller":"traceutil/trace.go:171","msg":"trace[1985910758] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:317; }","duration":"229.956883ms","start":"2026-07-27T20:05:40.039304Z","end":"2026-07-27T20:05:40.269261Z","steps":["trace[1985910758] 'agreement among raft nodes before linearized reading' (duration: 229.701107ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:40.269324Z","caller":"traceutil/trace.go:171","msg":"trace[1482550819] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:317; }","duration":"328.579294ms","start":"2026-07-27T20:05:39.940679Z","end":"2026-07-27T20:05:40.269259Z","steps":["trace[1482550819] 'agreement among raft nodes before linearized reading' (duration: 328.198275ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:40.269314Z","caller":"traceutil/trace.go:171","msg":"trace[1035473469] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:317; }","duration":"129.319823ms","start":"2026-07-27T20:05:40.139896Z","end":"2026-07-27T20:05:40.269216Z","steps":["trace[1035473469] 'agreement among raft nodes before linearized reading' (duration: 129.104488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:05:40.269476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:05:39.940662Z","time spent":"328.765459ms","remote":"127.0.0.1:36366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-27T20:05:40.590763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.057669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:05:40.591139Z","caller":"traceutil/trace.go:171","msg":"trace[1406544151] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"312.72372ms","start":"2026-07-27T20:05:40.27831Z","end":"2026-07-27T20:05:40.591033Z","steps":["trace[1406544151] 'process raft request' (duration: 205.28814ms)","trace[1406544151] 'compare' (duration: 106.807274ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:05:40.591193Z","caller":"traceutil/trace.go:171","msg":"trace[2100018446] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"312.520975ms","start":"2026-07-27T20:05:40.278637Z","end":"2026-07-27T20:05:40.591158Z","steps":["trace[2100018446] 'process raft request' (duration: 312.362051ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:40.591223Z","caller":"traceutil/trace.go:171","msg":"trace[1251812154] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:331; }","duration":"268.05085ms","start":"2026-07-27T20:05:40.323156Z","end":"2026-07-27T20:05:40.591207Z","steps":["trace[1251812154] 'read index received' (duration: 160.352764ms)","trace[1251812154] 'applied index is now lower than readState.Index' (duration: 107.696336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:05:40.591303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:05:40.278629Z","time spent":"312.634768ms","remote":"127.0.0.1:36636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:05:40.591322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:05:40.278283Z","time spent":"312.949126ms","remote":"127.0.0.1:36650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:05:40.591554Z","caller":"traceutil/trace.go:171","msg":"trace[1713563860] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"312.74623ms","start":"2026-07-27T20:05:40.278793Z","end":"2026-07-27T20:05:40.59154Z","steps":["trace[1713563860] 'process raft request' (duration: 312.297539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:05:40.591624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:05:40.278768Z","time spent":"312.825263ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:05:40.591809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.650315ms","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-27T20:05:40.591858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.64993ms","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-27T20:05:40.591919Z","caller":"traceutil/trace.go:171","msg":"trace[1109520451] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"313.038187ms","start":"2026-07-27T20:05:40.278862Z","end":"2026-07-27T20:05:40.5919Z","steps":["trace[1109520451] 'process raft request' (duration: 312.281049ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:40.591936Z","caller":"traceutil/trace.go:171","msg":"trace[1803420909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:322; }","duration":"267.746252ms","start":"2026-07-27T20:05:40.324153Z","end":"2026-07-27T20:05:40.5919Z","steps":["trace[1803420909] 'agreement among raft nodes before linearized reading' (duration: 267.616069ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:40.591862Z","caller":"traceutil/trace.go:171","msg":"trace[1203561456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:322; }","duration":"268.754927ms","start":"2026-07-27T20:05:40.323094Z","end":"2026-07-27T20:05:40.591849Z","steps":["trace[1203561456] 'agreement among raft nodes before linearized reading' (duration: 268.603623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:05:40.59203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:05:40.278838Z","time spent":"313.11593ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:06:08.815919Z","caller":"traceutil/trace.go:171","msg":"trace[1647258747] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"100.154461ms","start":"2026-07-27T20:06:08.715721Z","end":"2026-07-27T20:06:08.815875Z","steps":["trace[1647258747] 'process raft request' (duration: 99.72426ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:06:08.815949Z","caller":"traceutil/trace.go:171","msg":"trace[808049661] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"102.246393ms","start":"2026-07-27T20:06:08.713631Z","end":"2026-07-27T20:06:08.815877Z","steps":["trace[808049661] 'process raft request' (duration: 65.002405ms)","trace[808049661] 'compare' (duration: 36.535471ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:06:15.206754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.551794ms","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-27T20:06:15.206896Z","caller":"traceutil/trace.go:171","msg":"trace[106636024] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:586; }","duration":"221.711109ms","start":"2026-07-27T20:06:14.985167Z","end":"2026-07-27T20:06:15.206878Z","steps":["trace[106636024] 'range keys from in-memory index tree' (duration: 221.535204ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:06:15.217747Z","caller":"traceutil/trace.go:171","msg":"trace[178122457] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"114.650478ms","start":"2026-07-27T20:06:15.103059Z","end":"2026-07-27T20:06:15.21771Z","steps":["trace[178122457] 'read index received' (duration: 114.219598ms)","trace[178122457] 'applied index is now lower than readState.Index' (duration: 429.32µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:06:15.217888Z","caller":"traceutil/trace.go:171","msg":"trace[2054606934] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"247.35293ms","start":"2026-07-27T20:06:14.970455Z","end":"2026-07-27T20:06:15.217808Z","steps":["trace[2054606934] 'process raft request' (duration: 246.986832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:06:15.218133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.724563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-27T20:06:15.218146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.056998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-27T20:06:15.218265Z","caller":"traceutil/trace.go:171","msg":"trace[204263396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"115.256613ms","start":"2026-07-27T20:06:15.102982Z","end":"2026-07-27T20:06:15.218239Z","steps":["trace[204263396] 'agreement among raft nodes before linearized reading' (duration: 114.872734ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:06:15.218187Z","caller":"traceutil/trace.go:171","msg":"trace[2057694691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"106.853545ms","start":"2026-07-27T20:06:15.111319Z","end":"2026-07-27T20:06:15.218172Z","steps":["trace[2057694691] 'agreement among raft nodes before linearized reading' (duration: 106.595869ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:06:15.375655Z","caller":"traceutil/trace.go:171","msg":"trace[790567578] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"153.007985ms","start":"2026-07-27T20:06:15.222606Z","end":"2026-07-27T20:06:15.375614Z","steps":["trace[790567578] 'process raft request' (duration: 133.397591ms)","trace[790567578] 'compare' (duration: 19.323646ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:06:15.384719Z","caller":"traceutil/trace.go:171","msg":"trace[156467476] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"156.784638ms","start":"2026-07-27T20:06:15.227917Z","end":"2026-07-27T20:06:15.384702Z","steps":["trace[156467476] 'read index received' (duration: 128.08673ms)","trace[156467476] 'applied index is now lower than readState.Index' (duration: 28.696518ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:06:15.384801Z","caller":"traceutil/trace.go:171","msg":"trace[88387253] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"160.600321ms","start":"2026-07-27T20:06:15.224177Z","end":"2026-07-27T20:06:15.384777Z","steps":["trace[88387253] 'process raft request' (duration: 160.381236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:06:15.384875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.934691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:06:15.384918Z","caller":"traceutil/trace.go:171","msg":"trace[648787214] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:589; }","duration":"157.032143ms","start":"2026-07-27T20:06:15.22787Z","end":"2026-07-27T20:06:15.384902Z","steps":["trace[648787214] 'agreement among raft nodes before linearized reading' (duration: 156.934241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:07:40.72078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.992078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d756f6c-09b0-4fc2-817d-ba329e70bb0a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-27T20:07:40.720893Z","caller":"traceutil/trace.go:171","msg":"trace[1794323349] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d756f6c-09b0-4fc2-817d-ba329e70bb0a; range_end:; response_count:1; response_revision:1793; }","duration":"243.164622ms","start":"2026-07-27T20:07:40.477703Z","end":"2026-07-27T20:07:40.720867Z","steps":["trace[1794323349] 'range keys from in-memory index tree' (duration: 242.727972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:07:55.843302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.713445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:07:55.843459Z","caller":"traceutil/trace.go:171","msg":"trace[1832368731] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1919; }","duration":"106.923561ms","start":"2026-07-27T20:07:55.736496Z","end":"2026-07-27T20:07:55.843419Z","steps":["trace[1832368731] 'count revisions from in-memory index tree' (duration: 106.616673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:29.546842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.337515ms","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-27T20:08:29.546969Z","caller":"traceutil/trace.go:171","msg":"trace[1287492911] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"153.53347ms","start":"2026-07-27T20:08:29.393409Z","end":"2026-07-27T20:08:29.546942Z","steps":["trace[1287492911] 'range keys from in-memory index tree' (duration: 153.08005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:29.547057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.83699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:08:29.547127Z","caller":"traceutil/trace.go:171","msg":"trace[1426942552] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2110; }","duration":"241.955803ms","start":"2026-07-27T20:08:29.305155Z","end":"2026-07-27T20:08:29.547111Z","steps":["trace[1426942552] 'count revisions from in-memory index tree' (duration: 241.699217ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:29.702865Z","caller":"traceutil/trace.go:171","msg":"trace[70770757] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"111.365756ms","start":"2026-07-27T20:08:29.591464Z","end":"2026-07-27T20:08:29.702829Z","steps":["trace[70770757] 'read index received' (duration: 111.223412ms)","trace[70770757] 'applied index is now lower than readState.Index' (duration: 140.544µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:29.703024Z","caller":"traceutil/trace.go:171","msg":"trace[2067155384] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"112.075173ms","start":"2026-07-27T20:08:29.590893Z","end":"2026-07-27T20:08:29.702968Z","steps":["trace[2067155384] 'process raft request' (duration: 111.698904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:29.703176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.699264ms","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-27T20:08:29.703266Z","caller":"traceutil/trace.go:171","msg":"trace[1828217237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2111; }","duration":"111.818047ms","start":"2026-07-27T20:08:29.591428Z","end":"2026-07-27T20:08:29.703246Z","steps":["trace[1828217237] 'agreement among raft nodes before linearized reading' (duration: 111.581591ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:29.876471Z","caller":"traceutil/trace.go:171","msg":"trace[1663373963] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"168.486478ms","start":"2026-07-27T20:08:29.707964Z","end":"2026-07-27T20:08:29.876451Z","steps":["trace[1663373963] 'process raft request' (duration: 168.354055ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:29.876486Z","caller":"traceutil/trace.go:171","msg":"trace[737698863] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"173.482532ms","start":"2026-07-27T20:08:29.702976Z","end":"2026-07-27T20:08:29.876458Z","steps":["trace[737698863] 'read index received' (duration: 107.274835ms)","trace[737698863] 'applied index is now lower than readState.Index' (duration: 66.205137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:08:29.876667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.17874ms","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-27T20:08:29.87673Z","caller":"traceutil/trace.go:171","msg":"trace[259862803] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2112; }","duration":"181.291804ms","start":"2026-07-27T20:08:29.695421Z","end":"2026-07-27T20:08:29.876713Z","steps":["trace[259862803] 'agreement among raft nodes before linearized reading' (duration: 181.13251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:45.099927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425100,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:45.37357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.841262ms","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-27T20:10:45.373776Z","caller":"traceutil/trace.go:171","msg":"trace[157495642] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3039; }","duration":"388.063097ms","start":"2026-07-27T20:10:44.985689Z","end":"2026-07-27T20:10:45.373752Z","steps":["trace[157495642] 'range keys from in-memory index tree' (duration: 387.821432ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:45.374434Z","caller":"traceutil/trace.go:171","msg":"trace[2009932316] linearizableReadLoop","detail":"{readStateIndex:3159; appliedIndex:3158; }","duration":"775.373477ms","start":"2026-07-27T20:10:44.599033Z","end":"2026-07-27T20:10:45.374407Z","steps":["trace[2009932316] 'read index received' (duration: 770.873428ms)","trace[2009932316] 'applied index is now lower than readState.Index' (duration: 4.498509ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:10:45.374566Z","caller":"traceutil/trace.go:171","msg":"trace[1887643101] transaction","detail":"{read_only:false; response_revision:3040; number_of_response:1; }","duration":"821.04544ms","start":"2026-07-27T20:10:44.553429Z","end":"2026-07-27T20:10:45.374475Z","steps":["trace[1887643101] 'process raft request' (duration: 816.395447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:45.374798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:44.55341Z","time spent":"821.267525ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:10:45.374741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.538326ms","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-27T20:10:45.37494Z","caller":"traceutil/trace.go:171","msg":"trace[2080076731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3040; }","duration":"767.789712ms","start":"2026-07-27T20:10:44.607137Z","end":"2026-07-27T20:10:45.374927Z","steps":["trace[2080076731] 'agreement among raft nodes before linearized reading' (duration: 767.453254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:45.374816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.761137ms","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-27T20:10:45.375038Z","caller":"traceutil/trace.go:171","msg":"trace[365802069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3040; }","duration":"775.998922ms","start":"2026-07-27T20:10:44.598991Z","end":"2026-07-27T20:10:45.37499Z","steps":["trace[365802069] 'agreement among raft nodes before linearized reading' (duration: 775.586312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:45.374863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.315793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:10:45.375092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.732685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-27T20:10:45.375179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.335401ms","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-27T20:10:45.375243Z","caller":"traceutil/trace.go:171","msg":"trace[1219155639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3040; }","duration":"553.932411ms","start":"2026-07-27T20:10:44.821268Z","end":"2026-07-27T20:10:45.375201Z","steps":["trace[1219155639] 'agreement among raft nodes before linearized reading' (duration: 553.49358ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:45.375149Z","caller":"traceutil/trace.go:171","msg":"trace[2138212113] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:3040; }","duration":"141.649063ms","start":"2026-07-27T20:10:45.233469Z","end":"2026-07-27T20:10:45.375118Z","steps":["trace[2138212113] 'agreement among raft nodes before linearized reading' (duration: 141.294904ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:45.375299Z","caller":"traceutil/trace.go:171","msg":"trace[1613501845] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3040; }","duration":"767.450084ms","start":"2026-07-27T20:10:44.607792Z","end":"2026-07-27T20:10:45.375242Z","steps":["trace[1613501845] 'agreement among raft nodes before linearized reading' (duration: 767.057964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:45.375308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:44.821247Z","time spent":"554.048153ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-27T20:10:45.37511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:44.598976Z","time spent":"776.115736ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:10:45.374853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.24924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-27T20:10:45.375353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:44.607775Z","time spent":"767.566626ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-27T20:10:45.375396Z","caller":"traceutil/trace.go:171","msg":"trace[737233090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3040; }","duration":"550.896126ms","start":"2026-07-27T20:10:44.824484Z","end":"2026-07-27T20:10:45.37538Z","steps":["trace[737233090] 'agreement among raft nodes before linearized reading' (duration: 550.112237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:45.375458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:44.824462Z","time spent":"550.981199ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-27T20:10:45.374995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:44.60709Z","time spent":"767.892355ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-27T20:10:45.914351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:46.415094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:46.9159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:47.413786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000066505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-27T20:10:47.413893Z","caller":"traceutil/trace.go:171","msg":"trace[1861942289] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000232469s","start":"2026-07-27T20:10:45.41364Z","end":"2026-07-27T20:10:47.413872Z","steps":["trace[1861942289] 'agreement among raft nodes before linearized reading' (duration: 2.000094976s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:47.413944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.413626Z","time spent":"2.000308061s","remote":"127.0.0.1:36122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:10:47.416801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:47.917664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:48.418616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:48.91875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:49.096918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000871135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-27T20:10:49.097109Z","caller":"traceutil/trace.go:171","msg":"trace[453358922] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001140621s","start":"2026-07-27T20:10:47.095947Z","end":"2026-07-27T20:10:49.097088Z","steps":["trace[453358922] 'agreement among raft nodes before linearized reading' (duration: 2.000919426s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:49.097201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:47.095917Z","time spent":"2.001268604s","remote":"127.0.0.1:36122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/27 20:10:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-27T20:10:49.415124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000053395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-27T20:10:49.415277Z","caller":"traceutil/trace.go:171","msg":"trace[1383304442] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00029294s","start":"2026-07-27T20:10:47.414953Z","end":"2026-07-27T20:10:49.415246Z","steps":["trace[1383304442] 'agreement among raft nodes before linearized reading' (duration: 2.000102426s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:49.415362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:47.414923Z","time spent":"2.000417324s","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:10:49.418983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:49.920005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:50.378136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.38107Z","time spent":"4.997056005s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/27 20:10:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-27T20:10:50.378754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.380656Z","time spent":"4.998089741s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/27 20:10:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-27T20:10:50.381074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.381444Z","time spent":"4.999625448s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/27 20:10:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-27T20:10:50.420797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:50.842625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.845937Z","time spent":"4.996676776s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/27 20:10:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-27T20:10:50.920924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:10:51.320191Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.939433514s","expected-duration":"1s"} {"level":"info","ts":"2026-07-27T20:10:51.331871Z","caller":"traceutil/trace.go:171","msg":"trace[1673362472] transaction","detail":"{read_only:false; response_revision:3043; number_of_response:1; }","duration":"5.950423991s","start":"2026-07-27T20:10:45.381404Z","end":"2026-07-27T20:10:51.331828Z","steps":["trace[1673362472] 'process raft request' (duration: 5.950119214s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.332045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.381397Z","time spent":"5.950561435s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:10:51.333001Z","caller":"traceutil/trace.go:171","msg":"trace[1699264062] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"5.946647199s","start":"2026-07-27T20:10:45.386316Z","end":"2026-07-27T20:10:51.332963Z","steps":["trace[1699264062] 'process raft request' (duration: 5.945506971s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:51.333128Z","caller":"traceutil/trace.go:171","msg":"trace[1586388281] transaction","detail":"{read_only:false; response_revision:3047; number_of_response:1; }","duration":"5.486644598s","start":"2026-07-27T20:10:45.846443Z","end":"2026-07-27T20:10:51.333088Z","steps":["trace[1586388281] 'process raft request' (duration: 5.48547682s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:51.333242Z","caller":"traceutil/trace.go:171","msg":"trace[1968484546] transaction","detail":"{read_only:false; response_revision:3048; number_of_response:1; }","duration":"3.952821647s","start":"2026-07-27T20:10:47.380384Z","end":"2026-07-27T20:10:51.333206Z","steps":["trace[1968484546] 'process raft request' (duration: 3.952609811s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.333307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.846394Z","time spent":"5.486818222s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:10:51.333318Z","caller":"traceutil/trace.go:171","msg":"trace[1961658728] transaction","detail":"{read_only:false; response_revision:3049; number_of_response:1; }","duration":"3.288825004s","start":"2026-07-27T20:10:48.044479Z","end":"2026-07-27T20:10:51.333304Z","steps":["trace[1961658728] 'process raft request' (duration: 3.288581968s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.333421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:47.380351Z","time spent":"3.95297764s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:10:51.33344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.386282Z","time spent":"5.946803603s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:10:51.333522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:48.044442Z","time spent":"3.289020129s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:10:51.343144Z","caller":"traceutil/trace.go:171","msg":"trace[1956003487] linearizableReadLoop","detail":"{readStateIndex:3170; appliedIndex:3159; }","duration":"5.92943718s","start":"2026-07-27T20:10:45.413678Z","end":"2026-07-27T20:10:51.343116Z","steps":["trace[1956003487] 'read index received' (duration: 5.906816679s)","trace[1956003487] 'applied index is now lower than readState.Index' (duration: 22.615321ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:10:51.343406Z","caller":"traceutil/trace.go:171","msg":"trace[2132736069] transaction","detail":"{read_only:false; response_revision:3050; number_of_response:1; }","duration":"866.095728ms","start":"2026-07-27T20:10:50.477293Z","end":"2026-07-27T20:10:51.343389Z","steps":["trace[2132736069] 'process raft request' (duration: 865.643527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.34342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919326547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:10:51.343477Z","caller":"traceutil/trace.go:171","msg":"trace[1710232034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3050; }","duration":"1.919418989s","start":"2026-07-27T20:10:49.424044Z","end":"2026-07-27T20:10:51.343463Z","steps":["trace[1710232034] 'agreement among raft nodes before linearized reading' (duration: 1.919310516s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.343483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.683348763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-07-27T20:10:51.3435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.311822256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:10:51.343528Z","caller":"traceutil/trace.go:171","msg":"trace[1477711595] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3050; }","duration":"5.683459675s","start":"2026-07-27T20:10:45.660054Z","end":"2026-07-27T20:10:51.343514Z","steps":["trace[1477711595] 'agreement among raft nodes before linearized reading' (duration: 5.683292441s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.343533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:50.477271Z","time spent":"866.17944ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:10:51.343563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:45.660024Z","time spent":"5.683532057s","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-27T20:10:51.343618Z","caller":"traceutil/trace.go:171","msg":"trace[1336995102] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:3050; }","duration":"4.31199232s","start":"2026-07-27T20:10:47.031611Z","end":"2026-07-27T20:10:51.343604Z","steps":["trace[1336995102] 'agreement among raft nodes before linearized reading' (duration: 4.311835986s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.343664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:47.031569Z","time spent":"4.312083542s","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:10:51.343708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.934008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:10:51.343767Z","caller":"traceutil/trace.go:171","msg":"trace[43401932] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:3050; }","duration":"620.044671ms","start":"2026-07-27T20:10:50.723713Z","end":"2026-07-27T20:10:51.343757Z","steps":["trace[43401932] 'agreement among raft nodes before linearized reading' (duration: 619.971349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.343806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:50.72368Z","time spent":"620.115823ms","remote":"127.0.0.1:36434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:10:51.343859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.087774992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:10:51.343895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.864805402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-27T20:10:51.343919Z","caller":"traceutil/trace.go:171","msg":"trace[313122778] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3050; }","duration":"2.087869284s","start":"2026-07-27T20:10:49.25602Z","end":"2026-07-27T20:10:51.343889Z","steps":["trace[313122778] 'agreement among raft nodes before linearized reading' (duration: 2.087800492s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:51.343932Z","caller":"traceutil/trace.go:171","msg":"trace[2116560473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3050; }","duration":"4.864905405s","start":"2026-07-27T20:10:46.479017Z","end":"2026-07-27T20:10:51.343922Z","steps":["trace[2116560473] 'agreement among raft nodes before linearized reading' (duration: 4.864805662s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.343966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:46.478984Z","time spent":"4.864975067s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-27T20:10:51.34352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:49.424023Z","time spent":"1.919489741s","remote":"127.0.0.1:36122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:10:51.344038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786696755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-07-27T20:10:51.344032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758337343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-27T20:10:51.344074Z","caller":"traceutil/trace.go:171","msg":"trace[850028290] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3050; }","duration":"1.786796767s","start":"2026-07-27T20:10:49.557267Z","end":"2026-07-27T20:10:51.344063Z","steps":["trace[850028290] 'agreement among raft nodes before linearized reading' (duration: 1.786668774s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.344081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.660164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:10:51.343664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.917809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"warn","ts":"2026-07-27T20:10:51.343999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.308854315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:10:51.344375Z","caller":"traceutil/trace.go:171","msg":"trace[770353905] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3050; }","duration":"5.309286385s","start":"2026-07-27T20:10:46.035071Z","end":"2026-07-27T20:10:51.344357Z","steps":["trace[770353905] 'agreement among raft nodes before linearized reading' (duration: 5.308873575s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:51.344101Z","caller":"traceutil/trace.go:171","msg":"trace[909509977] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3050; }","duration":"1.758457455s","start":"2026-07-27T20:10:49.585619Z","end":"2026-07-27T20:10:51.344077Z","steps":["trace[909509977] 'agreement among raft nodes before linearized reading' (duration: 1.758292512s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.344433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:46.035036Z","time spent":"5.309382478s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:10:51.344373Z","caller":"traceutil/trace.go:171","msg":"trace[1273689630] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:3050; }","duration":"499.654697ms","start":"2026-07-27T20:10:50.84469Z","end":"2026-07-27T20:10:51.344345Z","steps":["trace[1273689630] 'agreement among raft nodes before linearized reading' (duration: 498.869048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.344468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:49.585577Z","time spent":"1.758881736s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-27T20:10:51.344113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:49.557237Z","time spent":"1.786867419s","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-27T20:10:51.344582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:50.844662Z","time spent":"499.829291ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-27T20:10:51.344137Z","caller":"traceutil/trace.go:171","msg":"trace[1846470773] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3050; }","duration":"727.771587ms","start":"2026-07-27T20:10:50.616346Z","end":"2026-07-27T20:10:51.344118Z","steps":["trace[1846470773] 'agreement among raft nodes before linearized reading' (duration: 727.665625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.344724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:50.616304Z","time spent":"728.406993ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:10:51.344188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.78604159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:10:51.344784Z","caller":"traceutil/trace.go:171","msg":"trace[1769241122] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:3050; }","duration":"2.786676215s","start":"2026-07-27T20:10:48.558089Z","end":"2026-07-27T20:10:51.344765Z","steps":["trace[1769241122] 'agreement among raft nodes before linearized reading' (duration: 2.786028659s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.344259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.388615956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c63e724dd7f1da\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-27T20:10:51.344859Z","caller":"traceutil/trace.go:171","msg":"trace[1274112094] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c63e724dd7f1da; range_end:; response_count:1; response_revision:3050; }","duration":"3.389254102s","start":"2026-07-27T20:10:47.955592Z","end":"2026-07-27T20:10:51.344846Z","steps":["trace[1274112094] 'agreement among raft nodes before linearized reading' (duration: 3.388575795s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.3449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:47.955562Z","time spent":"3.389327843s","remote":"127.0.0.1:36218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c63e724dd7f1da\" "} {"level":"warn","ts":"2026-07-27T20:10:51.34427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427042159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:10:51.344902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:48.558067Z","time spent":"2.786818389s","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":24,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:10:51.344237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.931086538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:10:51.344992Z","caller":"traceutil/trace.go:171","msg":"trace[597137542] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:3050; }","duration":"4.931912509s","start":"2026-07-27T20:10:46.41306Z","end":"2026-07-27T20:10:51.344972Z","steps":["trace[597137542] 'agreement among raft nodes before linearized reading' (duration: 4.930949575s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.34504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:46.413031Z","time spent":"4.931997331s","remote":"127.0.0.1:49048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:10:51.343965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:49.255991Z","time spent":"2.087962877s","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:10:51.345055Z","caller":"traceutil/trace.go:171","msg":"trace[1553543042] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:3050; }","duration":"1.427778937s","start":"2026-07-27T20:10:49.917158Z","end":"2026-07-27T20:10:51.344937Z","steps":["trace[1553543042] 'agreement among raft nodes before linearized reading' (duration: 1.427082999s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:51.345164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:49.917129Z","time spent":"1.428015413s","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:11:21.032402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:11:21.53259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:11:22.03354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:11:22.53435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:11:23.034525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900877344425575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:11:23.127318Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.598217454s","expected-duration":"1s"} {"level":"info","ts":"2026-07-27T20:11:23.127723Z","caller":"traceutil/trace.go:171","msg":"trace[782701558] transaction","detail":"{read_only:false; response_revision:3198; number_of_response:1; }","duration":"2.598707096s","start":"2026-07-27T20:11:20.528992Z","end":"2026-07-27T20:11:23.127699Z","steps":["trace[782701558] 'process raft request' (duration: 2.598518441s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.127852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:20.528969Z","time spent":"2.598808639s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:11:23.134369Z","caller":"traceutil/trace.go:171","msg":"trace[870749261] transaction","detail":"{read_only:false; response_revision:3199; number_of_response:1; }","duration":"2.603283697s","start":"2026-07-27T20:11:20.531061Z","end":"2026-07-27T20:11:23.134345Z","steps":["trace[870749261] 'process raft request' (duration: 2.603045222s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:23.134373Z","caller":"traceutil/trace.go:171","msg":"trace[1929956589] transaction","detail":"{read_only:false; response_revision:3200; number_of_response:1; }","duration":"1.763481729s","start":"2026-07-27T20:11:21.37086Z","end":"2026-07-27T20:11:23.134342Z","steps":["trace[1929956589] 'process raft request' (duration: 1.763393727s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.134465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:20.531032Z","time spent":"2.603388659s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:11:23.134545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.370836Z","time spent":"1.763595931s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:11:23.134637Z","caller":"traceutil/trace.go:171","msg":"trace[379615963] transaction","detail":"{read_only:false; response_revision:3201; number_of_response:1; }","duration":"1.66667295s","start":"2026-07-27T20:11:21.46795Z","end":"2026-07-27T20:11:23.134623Z","steps":["trace[379615963] 'process raft request' (duration: 1.666348742s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.134698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.467926Z","time spent":"1.666744902s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:11:23.134783Z","caller":"traceutil/trace.go:171","msg":"trace[1039388084] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"1.658131142s","start":"2026-07-27T20:11:21.476638Z","end":"2026-07-27T20:11:23.134769Z","steps":["trace[1039388084] 'process raft request' (duration: 1.658015779s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:23.134768Z","caller":"traceutil/trace.go:171","msg":"trace[1459313141] linearizableReadLoop","detail":"{readStateIndex:3330; appliedIndex:3323; }","duration":"2.603172994s","start":"2026-07-27T20:11:20.531571Z","end":"2026-07-27T20:11:23.134744Z","steps":["trace[1459313141] 'read index received' (duration: 2.59603085s)","trace[1459313141] 'applied index is now lower than readState.Index' (duration: 7.141044ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:23.134801Z","caller":"traceutil/trace.go:171","msg":"trace[1848956084] transaction","detail":"{read_only:false; response_revision:3204; number_of_response:1; }","duration":"1.414594076s","start":"2026-07-27T20:11:21.720188Z","end":"2026-07-27T20:11:23.134782Z","steps":["trace[1848956084] 'process raft request' (duration: 1.414510044s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.134854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.476617Z","time spent":"1.658201694s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:11:23.13488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.720165Z","time spent":"1.414675338s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:11:23.134907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.603334219s","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-27T20:11:23.134859Z","caller":"traceutil/trace.go:171","msg":"trace[1180238105] transaction","detail":"{read_only:false; response_revision:3202; number_of_response:1; }","duration":"1.663304627s","start":"2026-07-27T20:11:21.471498Z","end":"2026-07-27T20:11:23.134802Z","steps":["trace[1180238105] 'process raft request' (duration: 1.663029951s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718378221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:11:23.135027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.291381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:11:23.135062Z","caller":"traceutil/trace.go:171","msg":"trace[1375637972] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:3204; }","duration":"378.356361ms","start":"2026-07-27T20:11:22.756696Z","end":"2026-07-27T20:11:23.135052Z","steps":["trace[1375637972] 'agreement among raft nodes before linearized reading' (duration: 378.29323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609669261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-27T20:11:23.135065Z","caller":"traceutil/trace.go:171","msg":"trace[756404102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3204; }","duration":"1.718459433s","start":"2026-07-27T20:11:21.416588Z","end":"2026-07-27T20:11:23.135048Z","steps":["trace[756404102] 'agreement among raft nodes before linearized reading' (duration: 1.718399042s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:23.135117Z","caller":"traceutil/trace.go:171","msg":"trace[338534317] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3204; }","duration":"1.609756204s","start":"2026-07-27T20:11:21.52535Z","end":"2026-07-27T20:11:23.135106Z","steps":["trace[338534317] 'agreement among raft nodes before linearized reading' (duration: 1.60961348s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.13514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.638306069s","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-27T20:11:23.135181Z","caller":"traceutil/trace.go:171","msg":"trace[938605186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3204; }","duration":"1.63836402s","start":"2026-07-27T20:11:21.496811Z","end":"2026-07-27T20:11:23.135175Z","steps":["trace[938605186] 'agreement among raft nodes before linearized reading' (duration: 1.638294759s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631402742s","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":"warn","ts":"2026-07-27T20:11:23.135201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.496801Z","time spent":"1.638395502s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:11:23.135193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.416565Z","time spent":"1.718582536s","remote":"127.0.0.1:36122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:11:23.13515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.525334Z","time spent":"1.609810185s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-27T20:11:23.134959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.226170736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:11:23.135313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474949858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-27T20:11:23.135342Z","caller":"traceutil/trace.go:171","msg":"trace[661539115] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3204; }","duration":"1.47500646s","start":"2026-07-27T20:11:21.660327Z","end":"2026-07-27T20:11:23.135334Z","steps":["trace[661539115] 'agreement among raft nodes before linearized reading' (duration: 1.474905338s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.660288Z","time spent":"1.475080752s","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-27T20:11:23.135348Z","caller":"traceutil/trace.go:171","msg":"trace[1681716263] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3204; }","duration":"2.226609287s","start":"2026-07-27T20:11:20.908713Z","end":"2026-07-27T20:11:23.135322Z","steps":["trace[1681716263] 'agreement among raft nodes before linearized reading' (duration: 2.226185337s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:20.908693Z","time spent":"2.22671692s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:11:23.135483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.350692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:11:23.135442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.471479Z","time spent":"1.663543075s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:11:23.135526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890201608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:11:23.135564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764102915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-27T20:11:23.135612Z","caller":"traceutil/trace.go:171","msg":"trace[1435212466] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3204; }","duration":"1.764167336s","start":"2026-07-27T20:11:21.371426Z","end":"2026-07-27T20:11:23.135593Z","steps":["trace[1435212466] 'agreement among raft nodes before linearized reading' (duration: 1.764028133s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:22.756678Z","time spent":"378.429824ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:11:23.135669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.371419Z","time spent":"1.764242348s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-27T20:11:23.1356Z","caller":"traceutil/trace.go:171","msg":"trace[1679786871] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:3204; }","duration":"1.890303021s","start":"2026-07-27T20:11:21.245271Z","end":"2026-07-27T20:11:23.135574Z","steps":["trace[1679786871] 'agreement among raft nodes before linearized reading' (duration: 1.890199628s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158884755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-07-27T20:11:23.135023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644702496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-27T20:11:23.13579Z","caller":"traceutil/trace.go:171","msg":"trace[94029632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3204; }","duration":"1.645505795s","start":"2026-07-27T20:11:21.490273Z","end":"2026-07-27T20:11:23.135779Z","steps":["trace[94029632] 'agreement among raft nodes before linearized reading' (duration: 1.644708476s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:23.135799Z","caller":"traceutil/trace.go:171","msg":"trace[401009049] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3204; }","duration":"1.159252953s","start":"2026-07-27T20:11:21.976514Z","end":"2026-07-27T20:11:23.135767Z","steps":["trace[401009049] 'agreement among raft nodes before linearized reading' (duration: 1.158570067s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.976492Z","time spent":"1.159374267s","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-27T20:11:23.135213Z","caller":"traceutil/trace.go:171","msg":"trace[179093641] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3204; }","duration":"1.631557245s","start":"2026-07-27T20:11:21.503642Z","end":"2026-07-27T20:11:23.1352Z","steps":["trace[179093641] 'agreement among raft nodes before linearized reading' (duration: 1.631395071s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.135947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.503632Z","time spent":"1.632297432s","remote":"127.0.0.1:36460","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-27T20:11:23.135746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.245252Z","time spent":"1.890471214s","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":4,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:11:23.135827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:21.490254Z","time spent":"1.645564947s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-27T20:11:23.13551Z","caller":"traceutil/trace.go:171","msg":"trace[18499763] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3204; }","duration":"465.375593ms","start":"2026-07-27T20:11:22.670124Z","end":"2026-07-27T20:11:23.1355Z","steps":["trace[18499763] 'agreement among raft nodes before linearized reading' (duration: 465.324572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.136117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:22.670062Z","time spent":"466.036889ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:11:23.134944Z","caller":"traceutil/trace.go:171","msg":"trace[1305144101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3204; }","duration":"2.603388431s","start":"2026-07-27T20:11:20.531547Z","end":"2026-07-27T20:11:23.134936Z","steps":["trace[1305144101] 'agreement among raft nodes before linearized reading' (duration: 2.603278548s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:23.136173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:20.531537Z","time spent":"2.60462913s","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-27T20:14:09.254406Z","caller":"traceutil/trace.go:171","msg":"trace[548046986] linearizableReadLoop","detail":"{readStateIndex:5192; appliedIndex:5191; }","duration":"101.576563ms","start":"2026-07-27T20:14:09.152799Z","end":"2026-07-27T20:14:09.254375Z","steps":["trace[548046986] 'read index received' (duration: 91.001675ms)","trace[548046986] 'applied index is now lower than readState.Index' (duration: 10.573328ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:09.254501Z","caller":"traceutil/trace.go:171","msg":"trace[1063464706] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"129.706817ms","start":"2026-07-27T20:14:09.124744Z","end":"2026-07-27T20:14:09.254451Z","steps":["trace[1063464706] 'process raft request' (duration: 119.142059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:09.25483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.995152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-fmlpj-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-07-27T20:14:09.254939Z","caller":"traceutil/trace.go:171","msg":"trace[530206445] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-fmlpj-tls; range_end:; response_count:1; response_revision:5000; }","duration":"102.132876ms","start":"2026-07-27T20:14:09.152782Z","end":"2026-07-27T20:14:09.254914Z","steps":["trace[530206445] 'agreement among raft nodes before linearized reading' (duration: 101.738666ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:09.414562Z","caller":"traceutil/trace.go:171","msg":"trace[588568562] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5192; }","duration":"148.172925ms","start":"2026-07-27T20:14:09.26637Z","end":"2026-07-27T20:14:09.414543Z","steps":["trace[588568562] 'read index received' (duration: 147.9661ms)","trace[588568562] 'applied index is now lower than readState.Index' (duration: 205.965µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:09.414696Z","caller":"traceutil/trace.go:171","msg":"trace[1653469830] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"151.696971ms","start":"2026-07-27T20:14:09.262955Z","end":"2026-07-27T20:14:09.414652Z","steps":["trace[1653469830] 'process raft request' (duration: 151.446935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:09.414804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.411821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fmlpj-tls\" ","response":"range_response_count:1 size:4431"} {"level":"info","ts":"2026-07-27T20:14:09.414835Z","caller":"traceutil/trace.go:171","msg":"trace[1364311834] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fmlpj-tls; range_end:; response_count:1; response_revision:5001; }","duration":"148.478433ms","start":"2026-07-27T20:14:09.266345Z","end":"2026-07-27T20:14:09.414824Z","steps":["trace[1364311834] 'agreement among raft nodes before linearized reading' (duration: 148.313689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:09.415014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.986652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18c63eb4ba063c91\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-07-27T20:14:09.415115Z","caller":"traceutil/trace.go:171","msg":"trace[554774568] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18c63eb4ba063c91; range_end:; response_count:1; response_revision:5001; }","duration":"111.101434ms","start":"2026-07-27T20:14:09.303988Z","end":"2026-07-27T20:14:09.415089Z","steps":["trace[554774568] 'agreement among raft nodes before linearized reading' (duration: 110.831518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:09.738786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.754474ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-27T20:14:09.739136Z","caller":"traceutil/trace.go:171","msg":"trace[155285649] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"171.076422ms","start":"2026-07-27T20:14:09.568036Z","end":"2026-07-27T20:14:09.739112Z","steps":["trace[155285649] 'process raft request' (duration: 170.895688ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:09.761385Z","caller":"traceutil/trace.go:171","msg":"trace[1665620636] linearizableReadLoop","detail":"{readStateIndex:5207; appliedIndex:5203; }","duration":"153.516446ms","start":"2026-07-27T20:14:09.607845Z","end":"2026-07-27T20:14:09.761362Z","steps":["trace[1665620636] 'read index received' (duration: 67.979484ms)","trace[1665620636] 'applied index is now lower than readState.Index' (duration: 85.535842ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:09.761467Z","caller":"traceutil/trace.go:171","msg":"trace[791039559] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"189.604773ms","start":"2026-07-27T20:14:09.571826Z","end":"2026-07-27T20:14:09.761431Z","steps":["trace[791039559] 'process raft request' (duration: 189.4684ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:09.76154Z","caller":"traceutil/trace.go:171","msg":"trace[239752660] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"193.197471ms","start":"2026-07-27T20:14:09.568288Z","end":"2026-07-27T20:14:09.761486Z","steps":["trace[239752660] 'process raft request' (duration: 192.848382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:09.761947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.090249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786897"} {"level":"info","ts":"2026-07-27T20:14:09.761987Z","caller":"traceutil/trace.go:171","msg":"trace[1350203652] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5014; }","duration":"154.199551ms","start":"2026-07-27T20:14:09.607777Z","end":"2026-07-27T20:14:09.761976Z","steps":["trace[1350203652] 'agreement among raft nodes before linearized reading' (duration: 153.677459ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:22.789958Z","caller":"traceutil/trace.go:171","msg":"trace[676268097] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"115.298715ms","start":"2026-07-27T20:14:22.674621Z","end":"2026-07-27T20:14:22.78992Z","steps":["trace[676268097] 'process raft request' (duration: 114.972407ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:22.913448Z","caller":"traceutil/trace.go:171","msg":"trace[1998786636] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"108.094661ms","start":"2026-07-27T20:14:22.805325Z","end":"2026-07-27T20:14:22.913419Z","steps":["trace[1998786636] 'compare' (duration: 97.260996ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:22.913442Z","caller":"traceutil/trace.go:171","msg":"trace[866565083] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"106.182334ms","start":"2026-07-27T20:14:22.807225Z","end":"2026-07-27T20:14:22.913407Z","steps":["trace[866565083] 'process raft request' (duration: 106.096211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:23.334587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.913427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-hlbcq\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-07-27T20:14:23.334683Z","caller":"traceutil/trace.go:171","msg":"trace[1974842070] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-hlbcq; range_end:; response_count:1; response_revision:5274; }","duration":"263.04732ms","start":"2026-07-27T20:14:23.07161Z","end":"2026-07-27T20:14:23.334657Z","steps":["trace[1974842070] 'range keys from in-memory index tree' (duration: 262.704402ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:23.654607Z","caller":"traceutil/trace.go:171","msg":"trace[493396663] linearizableReadLoop","detail":"{readStateIndex:5476; appliedIndex:5475; }","duration":"322.460065ms","start":"2026-07-27T20:14:23.332099Z","end":"2026-07-27T20:14:23.654559Z","steps":["trace[493396663] 'read index received' (duration: 322.26539ms)","trace[493396663] 'applied index is now lower than readState.Index' (duration: 193.055µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:23.654646Z","caller":"traceutil/trace.go:171","msg":"trace[893861587] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"331.689249ms","start":"2026-07-27T20:14:23.322914Z","end":"2026-07-27T20:14:23.654604Z","steps":["trace[893861587] 'process raft request' (duration: 331.395932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:23.65485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:23.322888Z","time spent":"331.849413ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:23.654926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.807844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xvslf\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-07-27T20:14:23.655164Z","caller":"traceutil/trace.go:171","msg":"trace[1405931697] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xvslf; range_end:; response_count:1; response_revision:5275; }","duration":"323.09082ms","start":"2026-07-27T20:14:23.332012Z","end":"2026-07-27T20:14:23.655103Z","steps":["trace[1405931697] 'agreement among raft nodes before linearized reading' (duration: 322.67454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:23.655269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:23.331983Z","time spent":"323.275115ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-xvslf\" "} {"level":"info","ts":"2026-07-27T20:14:23.669286Z","caller":"traceutil/trace.go:171","msg":"trace[160406012] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"329.691531ms","start":"2026-07-27T20:14:23.339567Z","end":"2026-07-27T20:14:23.669258Z","steps":["trace[160406012] 'process raft request' (duration: 329.448475ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:23.669302Z","caller":"traceutil/trace.go:171","msg":"trace[1920313681] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"328.481582ms","start":"2026-07-27T20:14:23.340801Z","end":"2026-07-27T20:14:23.669282Z","steps":["trace[1920313681] 'process raft request' (duration: 328.356679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:23.669371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:23.340791Z","time spent":"328.549254ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:23.669512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.02958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:23.669509Z","caller":"traceutil/trace.go:171","msg":"trace[1313028090] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"327.084477ms","start":"2026-07-27T20:14:23.342402Z","end":"2026-07-27T20:14:23.669486Z","steps":["trace[1313028090] 'process raft request' (duration: 326.80767ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:23.669568Z","caller":"traceutil/trace.go:171","msg":"trace[836456124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5278; }","duration":"254.117593ms","start":"2026-07-27T20:14:23.415428Z","end":"2026-07-27T20:14:23.669545Z","steps":["trace[836456124] 'agreement among raft nodes before linearized reading' (duration: 254.045281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:23.669609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:23.342394Z","time spent":"327.164559ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8040,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:23.669792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:23.339546Z","time spent":"329.778163ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:31.174132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.759742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dc80f96d-ad7f-4067-a367-1f1e75a4199e\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-27T20:14:31.174239Z","caller":"traceutil/trace.go:171","msg":"trace[1809348806] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dc80f96d-ad7f-4067-a367-1f1e75a4199e; range_end:; response_count:1; response_revision:5462; }","duration":"241.930977ms","start":"2026-07-27T20:14:30.932287Z","end":"2026-07-27T20:14:31.174218Z","steps":["trace[1809348806] 'range keys from in-memory index tree' (duration: 241.425734ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:15:30.866108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-07-27T20:15:30.938315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"70.412903ms","hash":874343960,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":33570816,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-27T20:15:30.93856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874343960,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-07-27T20:20:11.529963Z","caller":"traceutil/trace.go:171","msg":"trace[1967627465] transaction","detail":"{read_only:false; response_revision:7937; number_of_response:1; }","duration":"179.332859ms","start":"2026-07-27T20:20:11.350598Z","end":"2026-07-27T20:20:11.529931Z","steps":["trace[1967627465] 'process raft request' (duration: 179.137815ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:11.552649Z","caller":"traceutil/trace.go:171","msg":"trace[526920726] transaction","detail":"{read_only:false; response_revision:7939; number_of_response:1; }","duration":"176.001898ms","start":"2026-07-27T20:20:11.376587Z","end":"2026-07-27T20:20:11.552589Z","steps":["trace[526920726] 'process raft request' (duration: 175.794903ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:11.552572Z","caller":"traceutil/trace.go:171","msg":"trace[304855100] transaction","detail":"{read_only:false; response_revision:7938; number_of_response:1; }","duration":"193.718209ms","start":"2026-07-27T20:20:11.358796Z","end":"2026-07-27T20:20:11.552514Z","steps":["trace[304855100] 'process raft request' (duration: 193.410062ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:12.368687Z","caller":"traceutil/trace.go:171","msg":"trace[1850090340] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"173.495878ms","start":"2026-07-27T20:20:12.195152Z","end":"2026-07-27T20:20:12.368648Z","steps":["trace[1850090340] 'process raft request' (duration: 118.439599ms)","trace[1850090340] 'compare' (duration: 54.902745ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:12.439049Z","caller":"traceutil/trace.go:171","msg":"trace[218154295] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"232.339468ms","start":"2026-07-27T20:20:12.206687Z","end":"2026-07-27T20:20:12.439027Z","steps":["trace[218154295] 'process raft request' (duration: 232.220046ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:12.439236Z","caller":"traceutil/trace.go:171","msg":"trace[1049303042] linearizableReadLoop","detail":"{readStateIndex:8230; appliedIndex:8228; }","duration":"231.908948ms","start":"2026-07-27T20:20:12.207273Z","end":"2026-07-27T20:20:12.439182Z","steps":["trace[1049303042] 'read index received' (duration: 106.389477ms)","trace[1049303042] 'applied index is now lower than readState.Index' (duration: 125.511161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:12.439528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.210765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-27T20:20:12.439641Z","caller":"traceutil/trace.go:171","msg":"trace[169620260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7943; }","duration":"232.39401ms","start":"2026-07-27T20:20:12.207221Z","end":"2026-07-27T20:20:12.439615Z","steps":["trace[169620260] 'agreement among raft nodes before linearized reading' (duration: 232.01691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:12.439644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.013385ms","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-27T20:20:12.4397Z","caller":"traceutil/trace.go:171","msg":"trace[496142975] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7943; }","duration":"189.115588ms","start":"2026-07-27T20:20:12.250567Z","end":"2026-07-27T20:20:12.439682Z","steps":["trace[496142975] 'agreement among raft nodes before linearized reading' (duration: 188.876242ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:13.733119Z","caller":"traceutil/trace.go:171","msg":"trace[662777361] transaction","detail":"{read_only:false; response_revision:7948; number_of_response:1; }","duration":"171.952471ms","start":"2026-07-27T20:20:13.561129Z","end":"2026-07-27T20:20:13.733081Z","steps":["trace[662777361] 'process raft request' (duration: 136.717264ms)","trace[662777361] 'compare' (duration: 35.031702ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:14.009338Z","caller":"traceutil/trace.go:171","msg":"trace[574924176] transaction","detail":"{read_only:false; response_revision:7949; number_of_response:1; }","duration":"123.910863ms","start":"2026-07-27T20:20:13.88537Z","end":"2026-07-27T20:20:14.009281Z","steps":["trace[574924176] 'process raft request' (duration: 123.561995ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:14.321076Z","caller":"traceutil/trace.go:171","msg":"trace[1353865525] linearizableReadLoop","detail":"{readStateIndex:8238; appliedIndex:8237; }","duration":"108.961489ms","start":"2026-07-27T20:20:14.21209Z","end":"2026-07-27T20:20:14.321052Z","steps":["trace[1353865525] 'read index received' (duration: 96.497995ms)","trace[1353865525] 'applied index is now lower than readState.Index' (duration: 12.462494ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:14.321135Z","caller":"traceutil/trace.go:171","msg":"trace[1713481021] transaction","detail":"{read_only:false; response_revision:7951; number_of_response:1; }","duration":"109.707068ms","start":"2026-07-27T20:20:14.211388Z","end":"2026-07-27T20:20:14.321095Z","steps":["trace[1713481021] 'process raft request' (duration: 97.162932ms)","trace[1713481021] 'compare' (duration: 12.390992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:14.321296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.181264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-27T20:20:14.321334Z","caller":"traceutil/trace.go:171","msg":"trace[418809524] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7951; }","duration":"109.249085ms","start":"2026-07-27T20:20:14.212075Z","end":"2026-07-27T20:20:14.321324Z","steps":["trace[418809524] 'agreement among raft nodes before linearized reading' (duration: 109.103952ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:14.626189Z","caller":"traceutil/trace.go:171","msg":"trace[2100877352] transaction","detail":"{read_only:false; response_revision:7954; number_of_response:1; }","duration":"100.265857ms","start":"2026-07-27T20:20:14.525875Z","end":"2026-07-27T20:20:14.626141Z","steps":["trace[2100877352] 'process raft request' (duration: 100.032801ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:16.327356Z","caller":"traceutil/trace.go:171","msg":"trace[1261553293] transaction","detail":"{read_only:false; response_revision:7962; number_of_response:1; }","duration":"113.791116ms","start":"2026-07-27T20:20:16.21353Z","end":"2026-07-27T20:20:16.327321Z","steps":["trace[1261553293] 'process raft request' (duration: 113.5448ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:16.434034Z","caller":"traceutil/trace.go:171","msg":"trace[660043729] linearizableReadLoop","detail":"{readStateIndex:8251; appliedIndex:8249; }","duration":"108.510539ms","start":"2026-07-27T20:20:16.32549Z","end":"2026-07-27T20:20:16.434Z","steps":["trace[660043729] 'read index received' (duration: 1.682322ms)","trace[660043729] 'applied index is now lower than readState.Index' (duration: 106.826457ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:16.434146Z","caller":"traceutil/trace.go:171","msg":"trace[608574131] transaction","detail":"{read_only:false; response_revision:7963; number_of_response:1; }","duration":"172.290709ms","start":"2026-07-27T20:20:16.261798Z","end":"2026-07-27T20:20:16.434088Z","steps":["trace[608574131] 'process raft request' (duration: 171.988632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:16.434409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.888917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-27T20:20:16.434541Z","caller":"traceutil/trace.go:171","msg":"trace[350502602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7963; }","duration":"109.090252ms","start":"2026-07-27T20:20:16.325423Z","end":"2026-07-27T20:20:16.434513Z","steps":["trace[350502602] 'agreement among raft nodes before linearized reading' (duration: 108.721383ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:16.62887Z","caller":"traceutil/trace.go:171","msg":"trace[1388238693] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7966; }","duration":"102.993243ms","start":"2026-07-27T20:20:16.525848Z","end":"2026-07-27T20:20:16.628842Z","steps":["trace[1388238693] 'process raft request' (duration: 102.810678ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:16.703657Z","caller":"traceutil/trace.go:171","msg":"trace[1216133490] transaction","detail":"{read_only:false; response_revision:7967; number_of_response:1; }","duration":"133.802143ms","start":"2026-07-27T20:20:16.569823Z","end":"2026-07-27T20:20:16.703625Z","steps":["trace[1216133490] 'process raft request' (duration: 133.107447ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:17.551077Z","caller":"traceutil/trace.go:171","msg":"trace[1939822103] transaction","detail":"{read_only:false; response_revision:7972; number_of_response:1; }","duration":"107.72485ms","start":"2026-07-27T20:20:17.443321Z","end":"2026-07-27T20:20:17.551046Z","steps":["trace[1939822103] 'process raft request' (duration: 107.551436ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:17.767881Z","caller":"traceutil/trace.go:171","msg":"trace[486247438] transaction","detail":"{read_only:false; response_revision:7974; number_of_response:1; }","duration":"174.40925ms","start":"2026-07-27T20:20:17.593449Z","end":"2026-07-27T20:20:17.767858Z","steps":["trace[486247438] 'process raft request' (duration: 173.99656ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:17.768025Z","caller":"traceutil/trace.go:171","msg":"trace[2115253622] transaction","detail":"{read_only:false; response_revision:7973; number_of_response:1; }","duration":"208.096909ms","start":"2026-07-27T20:20:17.559888Z","end":"2026-07-27T20:20:17.767985Z","steps":["trace[2115253622] 'process raft request' (duration: 117.543497ms)","trace[2115253622] 'compare' (duration: 89.819484ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:17.768074Z","caller":"traceutil/trace.go:171","msg":"trace[1241568421] transaction","detail":"{read_only:false; response_revision:7975; number_of_response:1; }","duration":"154.797424ms","start":"2026-07-27T20:20:17.613267Z","end":"2026-07-27T20:20:17.768064Z","steps":["trace[1241568421] 'process raft request' (duration: 154.270551ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:17.915003Z","caller":"traceutil/trace.go:171","msg":"trace[981481700] transaction","detail":"{read_only:false; response_revision:7977; number_of_response:1; }","duration":"137.531933ms","start":"2026-07-27T20:20:17.777434Z","end":"2026-07-27T20:20:17.914966Z","steps":["trace[981481700] 'process raft request' (duration: 129.698354ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:18.048188Z","caller":"traceutil/trace.go:171","msg":"trace[1709901214] transaction","detail":"{read_only:false; response_revision:7979; number_of_response:1; }","duration":"110.512787ms","start":"2026-07-27T20:20:17.937634Z","end":"2026-07-27T20:20:18.048146Z","steps":["trace[1709901214] 'process raft request' (duration: 84.73237ms)","trace[1709901214] 'compare' (duration: 25.456699ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:19.671292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.92283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-27T20:20:19.935607Z","caller":"traceutil/trace.go:171","msg":"trace[1294130958] transaction","detail":"{read_only:false; response_revision:7987; number_of_response:1; }","duration":"160.944513ms","start":"2026-07-27T20:20:19.77462Z","end":"2026-07-27T20:20:19.935564Z","steps":["trace[1294130958] 'process raft request' (duration: 106.787895ms)","trace[1294130958] 'compare' (duration: 53.917842ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:19.93589Z","caller":"traceutil/trace.go:171","msg":"trace[259292903] transaction","detail":"{read_only:false; response_revision:7988; number_of_response:1; }","duration":"136.253833ms","start":"2026-07-27T20:20:19.799603Z","end":"2026-07-27T20:20:19.935857Z","steps":["trace[259292903] 'process raft request' (duration: 135.902615ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:20.128396Z","caller":"traceutil/trace.go:171","msg":"trace[1355924680] transaction","detail":"{read_only:false; response_revision:7990; number_of_response:1; }","duration":"137.155194ms","start":"2026-07-27T20:20:19.991192Z","end":"2026-07-27T20:20:20.128347Z","steps":["trace[1355924680] 'process raft request' (duration: 121.840131ms)","trace[1355924680] 'compare' (duration: 15.083397ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:20.145781Z","caller":"traceutil/trace.go:171","msg":"trace[1446861623] transaction","detail":"{read_only:false; response_revision:7991; number_of_response:1; }","duration":"132.699667ms","start":"2026-07-27T20:20:20.013044Z","end":"2026-07-27T20:20:20.145744Z","steps":["trace[1446861623] 'process raft request' (duration: 132.370999ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:20.270021Z","caller":"traceutil/trace.go:171","msg":"trace[904807173] transaction","detail":"{read_only:false; response_revision:7993; number_of_response:1; }","duration":"118.584892ms","start":"2026-07-27T20:20:20.151392Z","end":"2026-07-27T20:20:20.269977Z","steps":["trace[904807173] 'process raft request' (duration: 89.998957ms)","trace[904807173] 'compare' (duration: 28.34535ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:20.270093Z","caller":"traceutil/trace.go:171","msg":"trace[1275389561] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7994; }","duration":"111.682965ms","start":"2026-07-27T20:20:20.158376Z","end":"2026-07-27T20:20:20.270059Z","steps":["trace[1275389561] 'process raft request' (duration: 111.583313ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:20.270096Z","caller":"traceutil/trace.go:171","msg":"trace[1215855252] transaction","detail":"{read_only:false; response_revision:7994; number_of_response:1; }","duration":"114.213257ms","start":"2026-07-27T20:20:20.155826Z","end":"2026-07-27T20:20:20.270039Z","steps":["trace[1215855252] 'process raft request' (duration: 114.066613ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:20.445111Z","caller":"traceutil/trace.go:171","msg":"trace[494707387] transaction","detail":"{read_only:false; response_revision:7996; number_of_response:1; }","duration":"160.517822ms","start":"2026-07-27T20:20:20.284555Z","end":"2026-07-27T20:20:20.445073Z","steps":["trace[494707387] 'process raft request' (duration: 96.190839ms)","trace[494707387] 'compare' (duration: 64.124869ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:20.610822Z","caller":"traceutil/trace.go:171","msg":"trace[360356985] linearizableReadLoop","detail":"{readStateIndex:8289; appliedIndex:8286; }","duration":"126.416403ms","start":"2026-07-27T20:20:20.484372Z","end":"2026-07-27T20:20:20.610789Z","steps":["trace[360356985] 'read index received' (duration: 49.844891ms)","trace[360356985] 'applied index is now lower than readState.Index' (duration: 76.570222ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:20.610912Z","caller":"traceutil/trace.go:171","msg":"trace[404476698] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"157.629312ms","start":"2026-07-27T20:20:20.453233Z","end":"2026-07-27T20:20:20.610862Z","steps":["trace[404476698] 'process raft request' (duration: 106.721615ms)","trace[404476698] 'compare' (duration: 50.131168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:20.611186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.798642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-27T20:20:20.611288Z","caller":"traceutil/trace.go:171","msg":"trace[1402237207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7999; }","duration":"126.952825ms","start":"2026-07-27T20:20:20.484312Z","end":"2026-07-27T20:20:20.611265Z","steps":["trace[1402237207] 'agreement among raft nodes before linearized reading' (duration: 126.632798ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:20.956391Z","caller":"traceutil/trace.go:171","msg":"trace[341791278] transaction","detail":"{read_only:false; response_revision:8005; number_of_response:1; }","duration":"108.59174ms","start":"2026-07-27T20:20:20.847739Z","end":"2026-07-27T20:20:20.956331Z","steps":["trace[341791278] 'process raft request' (duration: 54.26037ms)","trace[341791278] 'compare' (duration: 54.135375ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:21.027161Z","caller":"traceutil/trace.go:171","msg":"trace[1478268427] transaction","detail":"{read_only:false; response_revision:8006; number_of_response:1; }","duration":"173.340564ms","start":"2026-07-27T20:20:20.853784Z","end":"2026-07-27T20:20:21.027125Z","steps":["trace[1478268427] 'process raft request' (duration: 173.059657ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:21.027334Z","caller":"traceutil/trace.go:171","msg":"trace[236128275] linearizableReadLoop","detail":"{readStateIndex:8296; appliedIndex:8294; }","duration":"173.469727ms","start":"2026-07-27T20:20:20.853822Z","end":"2026-07-27T20:20:21.027291Z","steps":["trace[236128275] 'read index received' (duration: 48.193732ms)","trace[236128275] 'applied index is now lower than readState.Index' (duration: 125.273395ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:21.027383Z","caller":"traceutil/trace.go:171","msg":"trace[2042400229] transaction","detail":"{read_only:false; response_revision:8007; number_of_response:1; }","duration":"166.954989ms","start":"2026-07-27T20:20:20.860388Z","end":"2026-07-27T20:20:21.027343Z","steps":["trace[2042400229] 'process raft request' (duration: 166.59995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.027654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.805826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-27T20:20:21.027712Z","caller":"traceutil/trace.go:171","msg":"trace[1846870841] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:8007; }","duration":"173.877937ms","start":"2026-07-27T20:20:20.853815Z","end":"2026-07-27T20:20:21.027693Z","steps":["trace[1846870841] 'agreement among raft nodes before linearized reading' (duration: 173.636242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.02792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.14419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/barbican-api-certs\" ","response":"range_response_count:1 size:2631"} {"level":"info","ts":"2026-07-27T20:20:21.027967Z","caller":"traceutil/trace.go:171","msg":"trace[751458555] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/barbican-api-certs; range_end:; response_count:1; response_revision:8007; }","duration":"173.237822ms","start":"2026-07-27T20:20:20.854714Z","end":"2026-07-27T20:20:21.027952Z","steps":["trace[751458555] 'agreement among raft nodes before linearized reading' (duration: 172.892423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.028021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.721299ms","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-27T20:20:21.028168Z","caller":"traceutil/trace.go:171","msg":"trace[929739915] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8007; }","duration":"114.910633ms","start":"2026-07-27T20:20:20.913202Z","end":"2026-07-27T20:20:21.028112Z","steps":["trace[929739915] 'agreement among raft nodes before linearized reading' (duration: 114.573995ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:21.25814Z","caller":"traceutil/trace.go:171","msg":"trace[330418581] linearizableReadLoop","detail":"{readStateIndex:8305; appliedIndex:8304; }","duration":"159.432656ms","start":"2026-07-27T20:20:21.098671Z","end":"2026-07-27T20:20:21.258104Z","steps":["trace[330418581] 'read index received' (duration: 106.388476ms)","trace[330418581] 'applied index is now lower than readState.Index' (duration: 53.0429ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:21.258243Z","caller":"traceutil/trace.go:171","msg":"trace[474818755] transaction","detail":"{read_only:false; response_revision:8015; number_of_response:1; }","duration":"200.951176ms","start":"2026-07-27T20:20:21.05723Z","end":"2026-07-27T20:20:21.258181Z","steps":["trace[474818755] 'process raft request' (duration: 147.854474ms)","trace[474818755] 'compare' (duration: 52.900447ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:21.258759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.330136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-27T20:20:21.258872Z","caller":"traceutil/trace.go:171","msg":"trace[645422895] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:8015; }","duration":"155.5319ms","start":"2026-07-27T20:20:21.103311Z","end":"2026-07-27T20:20:21.258843Z","steps":["trace[645422895] 'agreement among raft nodes before linearized reading' (duration: 155.10438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.259387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.760122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-07-27T20:20:21.259433Z","caller":"traceutil/trace.go:171","msg":"trace[1892646589] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8015; }","duration":"191.869224ms","start":"2026-07-27T20:20:21.067553Z","end":"2026-07-27T20:20:21.259422Z","steps":["trace[1892646589] 'agreement among raft nodes before linearized reading' (duration: 190.717577ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:21.813085Z","caller":"traceutil/trace.go:171","msg":"trace[184888176] linearizableReadLoop","detail":"{readStateIndex:8307; appliedIndex:8305; }","duration":"545.955793ms","start":"2026-07-27T20:20:21.267104Z","end":"2026-07-27T20:20:21.81306Z","steps":["trace[184888176] 'read index received' (duration: 36.012606ms)","trace[184888176] 'applied index is now lower than readState.Index' (duration: 509.941747ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:21.813082Z","caller":"traceutil/trace.go:171","msg":"trace[1419166421] transaction","detail":"{read_only:false; response_revision:8017; number_of_response:1; }","duration":"547.338226ms","start":"2026-07-27T20:20:21.265697Z","end":"2026-07-27T20:20:21.813035Z","steps":["trace[1419166421] 'process raft request' (duration: 546.609719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.813272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:21.265675Z","time spent":"547.50241ms","remote":"127.0.0.1:36218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":805,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:20:21.813315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.902038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-07-27T20:20:21.813317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.200029ms","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-27T20:20:21.813386Z","caller":"traceutil/trace.go:171","msg":"trace[1033424667] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8018; }","duration":"546.291201ms","start":"2026-07-27T20:20:21.267078Z","end":"2026-07-27T20:20:21.813369Z","steps":["trace[1033424667] 'agreement among raft nodes before linearized reading' (duration: 546.100947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.813408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.143251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:21.813428Z","caller":"traceutil/trace.go:171","msg":"trace[1227917894] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8018; }","duration":"220.217383ms","start":"2026-07-27T20:20:21.593206Z","end":"2026-07-27T20:20:21.813423Z","steps":["trace[1227917894] 'agreement among raft nodes before linearized reading' (duration: 220.165531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.813434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:21.267068Z","time spent":"546.356873ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1309,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-07-27T20:20:21.813486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.729541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:21.81335Z","caller":"traceutil/trace.go:171","msg":"trace[1344389821] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8018; }","duration":"521.98891ms","start":"2026-07-27T20:20:21.291347Z","end":"2026-07-27T20:20:21.813336Z","steps":["trace[1344389821] 'agreement among raft nodes before linearized reading' (duration: 521.856286ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:21.8136Z","caller":"traceutil/trace.go:171","msg":"trace[339081571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8018; }","duration":"395.910045ms","start":"2026-07-27T20:20:21.417676Z","end":"2026-07-27T20:20:21.813586Z","steps":["trace[339081571] 'agreement among raft nodes before linearized reading' (duration: 395.71171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.813631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:21.417646Z","time spent":"395.979577ms","remote":"127.0.0.1:36122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:20:21.813641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:21.291325Z","time spent":"522.273667ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1841,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-07-27T20:20:21.813117Z","caller":"traceutil/trace.go:171","msg":"trace[1566232210] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"537.774704ms","start":"2026-07-27T20:20:21.275303Z","end":"2026-07-27T20:20:21.813077Z","steps":["trace[1566232210] 'process raft request' (duration: 537.669602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:21.814218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:21.275277Z","time spent":"538.895761ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6306,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:20:22.051593Z","caller":"traceutil/trace.go:171","msg":"trace[2045540244] linearizableReadLoop","detail":"{readStateIndex:8310; appliedIndex:8309; }","duration":"172.625956ms","start":"2026-07-27T20:20:21.878933Z","end":"2026-07-27T20:20:22.051559Z","steps":["trace[2045540244] 'read index received' (duration: 118.753956ms)","trace[2045540244] 'applied index is now lower than readState.Index' (duration: 53.87008ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:22.051677Z","caller":"traceutil/trace.go:171","msg":"trace[272959194] transaction","detail":"{read_only:false; response_revision:8020; number_of_response:1; }","duration":"174.613015ms","start":"2026-07-27T20:20:21.876996Z","end":"2026-07-27T20:20:22.051609Z","steps":["trace[272959194] 'process raft request' (duration: 120.695524ms)","trace[272959194] 'compare' (duration: 53.552702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:22.051975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.023097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-07-27T20:20:22.052027Z","caller":"traceutil/trace.go:171","msg":"trace[11156140] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8020; }","duration":"173.128938ms","start":"2026-07-27T20:20:21.878881Z","end":"2026-07-27T20:20:22.052009Z","steps":["trace[11156140] 'agreement among raft nodes before linearized reading' (duration: 172.856082ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.062702Z","caller":"traceutil/trace.go:171","msg":"trace[279147837] transaction","detail":"{read_only:false; response_revision:8021; number_of_response:1; }","duration":"119.858464ms","start":"2026-07-27T20:20:21.942814Z","end":"2026-07-27T20:20:22.062673Z","steps":["trace[279147837] 'process raft request' (duration: 119.644439ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.062812Z","caller":"traceutil/trace.go:171","msg":"trace[1341529438] transaction","detail":"{read_only:false; response_revision:8022; number_of_response:1; }","duration":"119.486795ms","start":"2026-07-27T20:20:21.943297Z","end":"2026-07-27T20:20:22.062784Z","steps":["trace[1341529438] 'process raft request' (duration: 119.30176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.063202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.592548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112177"} {"level":"info","ts":"2026-07-27T20:20:22.06327Z","caller":"traceutil/trace.go:171","msg":"trace[636336793] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8022; }","duration":"182.668691ms","start":"2026-07-27T20:20:21.880587Z","end":"2026-07-27T20:20:22.063256Z","steps":["trace[636336793] 'agreement among raft nodes before linearized reading' (duration: 182.095717ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.290968Z","caller":"traceutil/trace.go:171","msg":"trace[1788707331] linearizableReadLoop","detail":"{readStateIndex:8313; appliedIndex:8312; }","duration":"223.810111ms","start":"2026-07-27T20:20:22.067134Z","end":"2026-07-27T20:20:22.290944Z","steps":["trace[1788707331] 'read index received' (duration: 277.896µs)","trace[1788707331] 'applied index is now lower than readState.Index' (duration: 223.531555ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:22.291024Z","caller":"traceutil/trace.go:171","msg":"trace[929458942] transaction","detail":"{read_only:false; response_revision:8023; number_of_response:1; }","duration":"223.928144ms","start":"2026-07-27T20:20:22.067063Z","end":"2026-07-27T20:20:22.290991Z","steps":["trace[929458942] 'process raft request' (duration: 191.228789ms)","trace[929458942] 'compare' (duration: 32.524711ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:22.291232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.070087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-07-27T20:20:22.291327Z","caller":"traceutil/trace.go:171","msg":"trace[1983906835] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:8023; }","duration":"224.194851ms","start":"2026-07-27T20:20:22.067108Z","end":"2026-07-27T20:20:22.291303Z","steps":["trace[1983906835] 'agreement among raft nodes before linearized reading' (duration: 223.949895ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.448524Z","caller":"traceutil/trace.go:171","msg":"trace[97925049] linearizableReadLoop","detail":"{readStateIndex:8315; appliedIndex:8313; }","duration":"157.456178ms","start":"2026-07-27T20:20:22.291035Z","end":"2026-07-27T20:20:22.448491Z","steps":["trace[97925049] 'read index received' (duration: 127.611372ms)","trace[97925049] 'applied index is now lower than readState.Index' (duration: 29.843606ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:22.448565Z","caller":"traceutil/trace.go:171","msg":"trace[38188524] transaction","detail":"{read_only:false; response_revision:8024; number_of_response:1; }","duration":"209.719698ms","start":"2026-07-27T20:20:22.238799Z","end":"2026-07-27T20:20:22.448519Z","steps":["trace[38188524] 'process raft request' (duration: 209.559185ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.448626Z","caller":"traceutil/trace.go:171","msg":"trace[411329997] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8023; }","duration":"379.822135ms","start":"2026-07-27T20:20:22.068746Z","end":"2026-07-27T20:20:22.448568Z","steps":["trace[411329997] 'process raft request' (duration: 349.910277ms)","trace[411329997] 'compare' (duration: 29.560199ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:22.448804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.330458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/barbican-api-75dc54c74b-bd4km\" ","response":"range_response_count:1 size:8311"} {"level":"warn","ts":"2026-07-27T20:20:22.448851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.127489ms","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:4725"} {"level":"warn","ts":"2026-07-27T20:20:22.448852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.487822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:20:22.448852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.552261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-27T20:20:22.448904Z","caller":"traceutil/trace.go:171","msg":"trace[1354618624] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8024; }","duration":"375.228822ms","start":"2026-07-27T20:20:22.07366Z","end":"2026-07-27T20:20:22.448888Z","steps":["trace[1354618624] 'agreement among raft nodes before linearized reading' (duration: 375.016817ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.448902Z","caller":"traceutil/trace.go:171","msg":"trace[447136832] range","detail":"{range_begin:/registry/pods/openstack/barbican-api-75dc54c74b-bd4km; range_end:; response_count:1; response_revision:8024; }","duration":"211.482592ms","start":"2026-07-27T20:20:22.237391Z","end":"2026-07-27T20:20:22.448874Z","steps":["trace[447136832] 'agreement among raft nodes before linearized reading' (duration: 211.137404ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.448939Z","caller":"traceutil/trace.go:171","msg":"trace[2108321448] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:8024; }","duration":"373.671164ms","start":"2026-07-27T20:20:22.075254Z","end":"2026-07-27T20:20:22.448925Z","steps":["trace[2108321448] 'agreement among raft nodes before linearized reading' (duration: 373.391927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.448948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:22.073647Z","time spent":"375.291243ms","remote":"127.0.0.1:35568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4749,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-07-27T20:20:22.448939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:22.068723Z","time spent":"380.107581ms","remote":"127.0.0.1:49030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":71,"response count":0,"response size":2817,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:20:22.448987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:22.075241Z","time spent":"373.736855ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1367,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"info","ts":"2026-07-27T20:20:22.448939Z","caller":"traceutil/trace.go:171","msg":"trace[1496167958] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:8024; }","duration":"153.601504ms","start":"2026-07-27T20:20:22.295315Z","end":"2026-07-27T20:20:22.448916Z","steps":["trace[1496167958] 'agreement among raft nodes before linearized reading' (duration: 153.466581ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.665266Z","caller":"traceutil/trace.go:171","msg":"trace[1252772540] linearizableReadLoop","detail":"{readStateIndex:8316; appliedIndex:8315; }","duration":"203.653551ms","start":"2026-07-27T20:20:22.461573Z","end":"2026-07-27T20:20:22.665226Z","steps":["trace[1252772540] 'read index received' (duration: 193.754891ms)","trace[1252772540] 'applied index is now lower than readState.Index' (duration: 9.89625ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:22.665376Z","caller":"traceutil/trace.go:171","msg":"trace[190089245] transaction","detail":"{read_only:false; response_revision:8025; number_of_response:1; }","duration":"204.134403ms","start":"2026-07-27T20:20:22.4612Z","end":"2026-07-27T20:20:22.665334Z","steps":["trace[190089245] 'process raft request' (duration: 194.086219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.665469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.868276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:22.665563Z","caller":"traceutil/trace.go:171","msg":"trace[724806984] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:8025; }","duration":"203.981639ms","start":"2026-07-27T20:20:22.461558Z","end":"2026-07-27T20:20:22.665539Z","steps":["trace[724806984] 'agreement among raft nodes before linearized reading' (duration: 203.832165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.66568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.727771ms","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-27T20:20:22.665748Z","caller":"traceutil/trace.go:171","msg":"trace[1848779374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8025; }","duration":"126.843763ms","start":"2026-07-27T20:20:22.538888Z","end":"2026-07-27T20:20:22.665732Z","steps":["trace[1848779374] 'agreement among raft nodes before linearized reading' (duration: 126.69406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.66568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.052461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/barbican-api-certs\" ","response":"range_response_count:1 size:4336"} {"level":"info","ts":"2026-07-27T20:20:22.665886Z","caller":"traceutil/trace.go:171","msg":"trace[1061736911] range","detail":"{range_begin:/registry/secrets/openstack/barbican-api-certs; range_end:; response_count:1; response_revision:8025; }","duration":"204.317997ms","start":"2026-07-27T20:20:22.461533Z","end":"2026-07-27T20:20:22.665851Z","steps":["trace[1061736911] 'agreement among raft nodes before linearized reading' (duration: 203.943378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.665891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.712778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-07-27T20:20:22.665985Z","caller":"traceutil/trace.go:171","msg":"trace[674767166] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8025; }","duration":"202.836361ms","start":"2026-07-27T20:20:22.463125Z","end":"2026-07-27T20:20:22.665961Z","steps":["trace[674767166] 'agreement among raft nodes before linearized reading' (duration: 202.561954ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.889289Z","caller":"traceutil/trace.go:171","msg":"trace[1584253035] linearizableReadLoop","detail":"{readStateIndex:8319; appliedIndex:8317; }","duration":"216.573095ms","start":"2026-07-27T20:20:22.672688Z","end":"2026-07-27T20:20:22.889262Z","steps":["trace[1584253035] 'read index received' (duration: 39.996652ms)","trace[1584253035] 'applied index is now lower than readState.Index' (duration: 176.575503ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:22.889385Z","caller":"traceutil/trace.go:171","msg":"trace[427903843] transaction","detail":"{read_only:false; response_revision:8028; number_of_response:1; }","duration":"217.246251ms","start":"2026-07-27T20:20:22.672093Z","end":"2026-07-27T20:20:22.889339Z","steps":["trace[427903843] 'process raft request' (duration: 216.993894ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.889608Z","caller":"traceutil/trace.go:171","msg":"trace[279151340] transaction","detail":"{read_only:false; response_revision:8027; number_of_response:1; }","duration":"217.573348ms","start":"2026-07-27T20:20:22.672013Z","end":"2026-07-27T20:20:22.889586Z","steps":["trace[279151340] 'process raft request' (duration: 207.75307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.889958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.23645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112177"} {"level":"info","ts":"2026-07-27T20:20:22.889996Z","caller":"traceutil/trace.go:171","msg":"trace[948490438] transaction","detail":"{read_only:false; response_revision:8029; number_of_response:1; }","duration":"205.127326ms","start":"2026-07-27T20:20:22.684823Z","end":"2026-07-27T20:20:22.88995Z","steps":["trace[948490438] 'process raft request' (duration: 204.363848ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:22.890052Z","caller":"traceutil/trace.go:171","msg":"trace[1419747538] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8029; }","duration":"217.355633ms","start":"2026-07-27T20:20:22.672676Z","end":"2026-07-27T20:20:22.890032Z","steps":["trace[1419747538] 'agreement among raft nodes before linearized reading' (duration: 216.939753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:22.890167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.962156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-27T20:20:22.890237Z","caller":"traceutil/trace.go:171","msg":"trace[130477319] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:8029; }","duration":"215.080678ms","start":"2026-07-27T20:20:22.675141Z","end":"2026-07-27T20:20:22.890222Z","steps":["trace[130477319] 'agreement among raft nodes before linearized reading' (duration: 214.608806ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:23.134361Z","caller":"traceutil/trace.go:171","msg":"trace[617400934] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8031; }","duration":"236.792737ms","start":"2026-07-27T20:20:22.897539Z","end":"2026-07-27T20:20:23.134332Z","steps":["trace[617400934] 'process raft request' (duration: 218.731427ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:23.134389Z","caller":"traceutil/trace.go:171","msg":"trace[2068393974] linearizableReadLoop","detail":"{readStateIndex:8322; appliedIndex:8321; }","duration":"236.168322ms","start":"2026-07-27T20:20:22.898191Z","end":"2026-07-27T20:20:23.13436Z","steps":["trace[2068393974] 'read index received' (duration: 71.57473ms)","trace[2068393974] 'applied index is now lower than readState.Index' (duration: 164.592272ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:23.134613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.409938ms","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-27T20:20:23.134677Z","caller":"traceutil/trace.go:171","msg":"trace[1686570944] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8032; }","duration":"236.49627ms","start":"2026-07-27T20:20:22.898153Z","end":"2026-07-27T20:20:23.134649Z","steps":["trace[1686570944] 'agreement among raft nodes before linearized reading' (duration: 236.349066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:23.134978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.58689ms","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-27T20:20:23.135032Z","caller":"traceutil/trace.go:171","msg":"trace[2011471901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8032; }","duration":"173.689203ms","start":"2026-07-27T20:20:22.96133Z","end":"2026-07-27T20:20:23.135019Z","steps":["trace[2011471901] 'agreement among raft nodes before linearized reading' (duration: 173.459247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:23.13503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.164525ms","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-27T20:20:23.134542Z","caller":"traceutil/trace.go:171","msg":"trace[24748121] transaction","detail":"{read_only:false; response_revision:8032; number_of_response:1; }","duration":"230.32916ms","start":"2026-07-27T20:20:22.904113Z","end":"2026-07-27T20:20:23.134442Z","steps":["trace[24748121] 'process raft request' (duration: 230.113635ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:23.135136Z","caller":"traceutil/trace.go:171","msg":"trace[396671715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8032; }","duration":"102.340609ms","start":"2026-07-27T20:20:23.032767Z","end":"2026-07-27T20:20:23.135108Z","steps":["trace[396671715] 'agreement among raft nodes before linearized reading' (duration: 101.97777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:23.136024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.833769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-07-27T20:20:23.136069Z","caller":"traceutil/trace.go:171","msg":"trace[1907161272] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8032; }","duration":"211.937072ms","start":"2026-07-27T20:20:22.92412Z","end":"2026-07-27T20:20:23.136057Z","steps":["trace[1907161272] 'agreement among raft nodes before linearized reading' (duration: 210.428965ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:23.349105Z","caller":"traceutil/trace.go:171","msg":"trace[1690748185] transaction","detail":"{read_only:false; response_revision:8033; number_of_response:1; }","duration":"209.526374ms","start":"2026-07-27T20:20:23.139546Z","end":"2026-07-27T20:20:23.349072Z","steps":["trace[1690748185] 'process raft request' (duration: 182.002115ms)","trace[1690748185] 'compare' (duration: 27.380646ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:23.363403Z","caller":"traceutil/trace.go:171","msg":"trace[504551461] transaction","detail":"{read_only:false; response_revision:8034; number_of_response:1; }","duration":"223.00951ms","start":"2026-07-27T20:20:23.140367Z","end":"2026-07-27T20:20:23.363376Z","steps":["trace[504551461] 'process raft request' (duration: 222.797765ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:23.363821Z","caller":"traceutil/trace.go:171","msg":"trace[222722011] linearizableReadLoop","detail":"{readStateIndex:8326; appliedIndex:8323; }","duration":"223.39879ms","start":"2026-07-27T20:20:23.140395Z","end":"2026-07-27T20:20:23.363794Z","steps":["trace[222722011] 'read index received' (duration: 181.152423ms)","trace[222722011] 'applied index is now lower than readState.Index' (duration: 42.245287ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:23.363944Z","caller":"traceutil/trace.go:171","msg":"trace[662436406] transaction","detail":"{read_only:false; response_revision:8036; number_of_response:1; }","duration":"212.533817ms","start":"2026-07-27T20:20:23.15139Z","end":"2026-07-27T20:20:23.363923Z","steps":["trace[662436406] 'process raft request' (duration: 212.276161ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:23.363926Z","caller":"traceutil/trace.go:171","msg":"trace[2132307487] transaction","detail":"{read_only:false; response_revision:8035; number_of_response:1; }","duration":"223.491183ms","start":"2026-07-27T20:20:23.140397Z","end":"2026-07-27T20:20:23.363888Z","steps":["trace[2132307487] 'process raft request' (duration: 222.931198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:23.364146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.727319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-27T20:20:23.364232Z","caller":"traceutil/trace.go:171","msg":"trace[505982886] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8036; }","duration":"223.876252ms","start":"2026-07-27T20:20:23.140338Z","end":"2026-07-27T20:20:23.364214Z","steps":["trace[505982886] 'agreement among raft nodes before linearized reading' (duration: 223.651267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:23.364232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.827961ms","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-27T20:20:23.364275Z","caller":"traceutil/trace.go:171","msg":"trace[2113974041] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8036; }","duration":"195.915703ms","start":"2026-07-27T20:20:23.168347Z","end":"2026-07-27T20:20:23.364262Z","steps":["trace[2113974041] 'agreement among raft nodes before linearized reading' (duration: 195.734528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:23.364515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.009394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112177"} {"level":"info","ts":"2026-07-27T20:20:23.364651Z","caller":"traceutil/trace.go:171","msg":"trace[1867146832] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8036; }","duration":"219.172798ms","start":"2026-07-27T20:20:23.145457Z","end":"2026-07-27T20:20:23.364629Z","steps":["trace[1867146832] 'agreement among raft nodes before linearized reading' (duration: 218.585794ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:23.577395Z","caller":"traceutil/trace.go:171","msg":"trace[1911124868] transaction","detail":"{read_only:false; response_revision:8037; number_of_response:1; }","duration":"139.978573ms","start":"2026-07-27T20:20:23.437376Z","end":"2026-07-27T20:20:23.577355Z","steps":["trace[1911124868] 'process raft request' (duration: 135.065723ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:24.022268Z","caller":"traceutil/trace.go:171","msg":"trace[595371895] linearizableReadLoop","detail":"{readStateIndex:8329; appliedIndex:8328; }","duration":"162.361508ms","start":"2026-07-27T20:20:23.859872Z","end":"2026-07-27T20:20:24.022233Z","steps":["trace[595371895] 'read index received' (duration: 162.052579ms)","trace[595371895] 'applied index is now lower than readState.Index' (duration: 307.849µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:24.022422Z","caller":"traceutil/trace.go:171","msg":"trace[689790967] transaction","detail":"{read_only:false; response_revision:8038; number_of_response:1; }","duration":"166.375646ms","start":"2026-07-27T20:20:23.855982Z","end":"2026-07-27T20:20:24.022357Z","steps":["trace[689790967] 'process raft request' (duration: 166.046348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.022658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.760707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7453"} {"level":"info","ts":"2026-07-27T20:20:24.022717Z","caller":"traceutil/trace.go:171","msg":"trace[1056351735] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8038; }","duration":"162.86981ms","start":"2026-07-27T20:20:23.859829Z","end":"2026-07-27T20:20:24.022698Z","steps":["trace[1056351735] 'agreement among raft nodes before linearized reading' (duration: 162.554222ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:24.050503Z","caller":"traceutil/trace.go:171","msg":"trace[21921256] transaction","detail":"{read_only:false; response_revision:8039; number_of_response:1; }","duration":"172.196516ms","start":"2026-07-27T20:20:23.878265Z","end":"2026-07-27T20:20:24.050462Z","steps":["trace[21921256] 'process raft request' (duration: 171.93748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.050602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.811057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-27T20:20:24.050714Z","caller":"traceutil/trace.go:171","msg":"trace[1388655388] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8039; }","duration":"113.990401ms","start":"2026-07-27T20:20:23.936697Z","end":"2026-07-27T20:20:24.050687Z","steps":["trace[1388655388] 'agreement among raft nodes before linearized reading' (duration: 113.684914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.387023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.403336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:20:24.387314Z","caller":"traceutil/trace.go:171","msg":"trace[1314846566] transaction","detail":"{read_only:false; response_revision:8041; number_of_response:1; }","duration":"332.534494ms","start":"2026-07-27T20:20:24.054722Z","end":"2026-07-27T20:20:24.387256Z","steps":["trace[1314846566] 'process raft request' (duration: 209.762069ms)","trace[1314846566] 'compare' (duration: 122.14239ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:24.387556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:24.054698Z","time spent":"332.73681ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:20:24.387774Z","caller":"traceutil/trace.go:171","msg":"trace[1885644212] transaction","detail":"{read_only:false; response_revision:8042; number_of_response:1; }","duration":"332.988115ms","start":"2026-07-27T20:20:24.054773Z","end":"2026-07-27T20:20:24.387762Z","steps":["trace[1885644212] 'process raft request' (duration: 332.367401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.387838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:24.054756Z","time spent":"333.050988ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:20:24.387884Z","caller":"traceutil/trace.go:171","msg":"trace[1899392947] linearizableReadLoop","detail":"{readStateIndex:8335; appliedIndex:8330; }","duration":"313.878971ms","start":"2026-07-27T20:20:24.073979Z","end":"2026-07-27T20:20:24.387858Z","steps":["trace[1899392947] 'read index received' (duration: 21.079933ms)","trace[1899392947] 'applied index is now lower than readState.Index' (duration: 292.797758ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:24.387884Z","caller":"traceutil/trace.go:171","msg":"trace[1583912359] transaction","detail":"{read_only:false; response_revision:8043; number_of_response:1; }","duration":"319.309523ms","start":"2026-07-27T20:20:24.068532Z","end":"2026-07-27T20:20:24.387842Z","steps":["trace[1583912359] 'process raft request' (duration: 319.017146ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:24.387923Z","caller":"traceutil/trace.go:171","msg":"trace[1104001025] transaction","detail":"{read_only:false; response_revision:8044; number_of_response:1; }","duration":"319.15729ms","start":"2026-07-27T20:20:24.068697Z","end":"2026-07-27T20:20:24.387854Z","steps":["trace[1104001025] 'process raft request' (duration: 318.909624ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:24.388035Z","caller":"traceutil/trace.go:171","msg":"trace[230339626] transaction","detail":"{read_only:false; response_revision:8045; number_of_response:1; }","duration":"178.258873ms","start":"2026-07-27T20:20:24.209767Z","end":"2026-07-27T20:20:24.388026Z","steps":["trace[230339626] 'process raft request' (duration: 178.028397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.388064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:24.068509Z","time spent":"319.453726ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:20:24.388156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:24.068661Z","time spent":"319.371674ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:20:24.388302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.28289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xvslf\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-07-27T20:20:24.388631Z","caller":"traceutil/trace.go:171","msg":"trace[1011623469] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xvslf; range_end:; response_count:1; response_revision:8045; }","duration":"314.477555ms","start":"2026-07-27T20:20:24.073935Z","end":"2026-07-27T20:20:24.388412Z","steps":["trace[1011623469] 'agreement among raft nodes before linearized reading' (duration: 314.025494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.388698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:24.073919Z","time spent":"314.763142ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-xvslf\" "} {"level":"warn","ts":"2026-07-27T20:20:24.402343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.186529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/barbican-api-75dc54c74b-bd4km\" ","response":"range_response_count:1 size:8322"} {"level":"info","ts":"2026-07-27T20:20:24.40243Z","caller":"traceutil/trace.go:171","msg":"trace[1920119132] range","detail":"{range_begin:/registry/pods/openstack/barbican-api-75dc54c74b-bd4km; range_end:; response_count:1; response_revision:8046; }","duration":"138.297921ms","start":"2026-07-27T20:20:24.264089Z","end":"2026-07-27T20:20:24.402387Z","steps":["trace[1920119132] 'agreement among raft nodes before linearized reading' (duration: 138.161658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.402693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.12331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-27T20:20:24.402752Z","caller":"traceutil/trace.go:171","msg":"trace[599172444] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:8046; }","duration":"104.271425ms","start":"2026-07-27T20:20:24.298466Z","end":"2026-07-27T20:20:24.402737Z","steps":["trace[599172444] 'agreement among raft nodes before linearized reading' (duration: 104.111871ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:24.599103Z","caller":"traceutil/trace.go:171","msg":"trace[995304796] linearizableReadLoop","detail":"{readStateIndex:8339; appliedIndex:8337; }","duration":"134.12097ms","start":"2026-07-27T20:20:24.464955Z","end":"2026-07-27T20:20:24.599076Z","steps":["trace[995304796] 'read index received' (duration: 1.408883ms)","trace[995304796] 'applied index is now lower than readState.Index' (duration: 132.710727ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:24.599148Z","caller":"traceutil/trace.go:171","msg":"trace[1758321842] transaction","detail":"{read_only:false; response_revision:8047; number_of_response:1; }","duration":"185.736177ms","start":"2026-07-27T20:20:24.413364Z","end":"2026-07-27T20:20:24.599101Z","steps":["trace[1758321842] 'process raft request' (duration: 185.516411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:24.59934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.363877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-27T20:20:24.599423Z","caller":"traceutil/trace.go:171","msg":"trace[2059036857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8047; }","duration":"134.50272ms","start":"2026-07-27T20:20:24.464908Z","end":"2026-07-27T20:20:24.59941Z","steps":["trace[2059036857] 'agreement among raft nodes before linearized reading' (duration: 134.280435ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:24.686009Z","caller":"traceutil/trace.go:171","msg":"trace[1661163008] transaction","detail":"{read_only:false; response_revision:8048; number_of_response:1; }","duration":"218.623375ms","start":"2026-07-27T20:20:24.467345Z","end":"2026-07-27T20:20:24.685968Z","steps":["trace[1661163008] 'process raft request' (duration: 162.237984ms)","trace[1661163008] 'compare' (duration: 56.120404ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:24.930524Z","caller":"traceutil/trace.go:171","msg":"trace[819332883] linearizableReadLoop","detail":"{readStateIndex:8341; appliedIndex:8340; }","duration":"175.337023ms","start":"2026-07-27T20:20:24.755138Z","end":"2026-07-27T20:20:24.930475Z","steps":["trace[819332883] 'read index received' (duration: 127.584233ms)","trace[819332883] 'applied index is now lower than readState.Index' (duration: 47.75114ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:24.930652Z","caller":"traceutil/trace.go:171","msg":"trace[1223766690] transaction","detail":"{read_only:false; response_revision:8049; number_of_response:1; }","duration":"211.455631ms","start":"2026-07-27T20:20:24.719186Z","end":"2026-07-27T20:20:24.930642Z","steps":["trace[1223766690] 'process raft request' (duration: 163.582298ms)","trace[1223766690] 'compare' (duration: 47.458323ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:24.930783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.63031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:24.930877Z","caller":"traceutil/trace.go:171","msg":"trace[753109316] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8049; }","duration":"175.785283ms","start":"2026-07-27T20:20:24.755072Z","end":"2026-07-27T20:20:24.930857Z","steps":["trace[753109316] 'agreement among raft nodes before linearized reading' (duration: 175.617459ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:24.939559Z","caller":"traceutil/trace.go:171","msg":"trace[1753606603] transaction","detail":"{read_only:false; response_revision:8050; number_of_response:1; }","duration":"132.361897ms","start":"2026-07-27T20:20:24.80717Z","end":"2026-07-27T20:20:24.939532Z","steps":["trace[1753606603] 'process raft request' (duration: 131.905906ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:25.148226Z","caller":"traceutil/trace.go:171","msg":"trace[1019374924] linearizableReadLoop","detail":"{readStateIndex:8344; appliedIndex:8342; }","duration":"202.745859ms","start":"2026-07-27T20:20:24.945445Z","end":"2026-07-27T20:20:25.14819Z","steps":["trace[1019374924] 'read index received' (duration: 53.651924ms)","trace[1019374924] 'applied index is now lower than readState.Index' (duration: 149.092595ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:25.148313Z","caller":"traceutil/trace.go:171","msg":"trace[756958288] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"203.897157ms","start":"2026-07-27T20:20:24.944374Z","end":"2026-07-27T20:20:25.148271Z","steps":["trace[756958288] 'process raft request' (duration: 150.584891ms)","trace[756958288] 'compare' (duration: 53.06763ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:25.148489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.013516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19443"} {"level":"info","ts":"2026-07-27T20:20:25.148534Z","caller":"traceutil/trace.go:171","msg":"trace[1398994716] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8052; }","duration":"203.094517ms","start":"2026-07-27T20:20:24.945428Z","end":"2026-07-27T20:20:25.148522Z","steps":["trace[1398994716] 'agreement among raft nodes before linearized reading' (duration: 202.895053ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:25.501697Z","caller":"traceutil/trace.go:171","msg":"trace[298701065] transaction","detail":"{read_only:false; response_revision:8055; number_of_response:1; }","duration":"111.348857ms","start":"2026-07-27T20:20:25.390306Z","end":"2026-07-27T20:20:25.501655Z","steps":["trace[298701065] 'process raft request' (duration: 110.888616ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:26.533737Z","caller":"traceutil/trace.go:171","msg":"trace[1201024876] transaction","detail":"{read_only:false; response_revision:8058; number_of_response:1; }","duration":"138.347103ms","start":"2026-07-27T20:20:26.395361Z","end":"2026-07-27T20:20:26.533709Z","steps":["trace[1201024876] 'process raft request' (duration: 138.094497ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:26.708101Z","caller":"traceutil/trace.go:171","msg":"trace[902431231] linearizableReadLoop","detail":"{readStateIndex:8351; appliedIndex:8349; }","duration":"298.322422ms","start":"2026-07-27T20:20:26.409746Z","end":"2026-07-27T20:20:26.708068Z","steps":["trace[902431231] 'read index received' (duration: 123.715968ms)","trace[902431231] 'applied index is now lower than readState.Index' (duration: 174.605174ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:26.708426Z","caller":"traceutil/trace.go:171","msg":"trace[1202294243] transaction","detail":"{read_only:false; response_revision:8059; number_of_response:1; }","duration":"312.122097ms","start":"2026-07-27T20:20:26.396289Z","end":"2026-07-27T20:20:26.708411Z","steps":["trace[1202294243] 'process raft request' (duration: 256.67216ms)","trace[1202294243] 'compare' (duration: 54.967386ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:26.708564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:26.396254Z","time spent":"312.211969ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:20:26.708809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.421781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-75dc54c74b-bd4km.18c63f0c8f8001b7\" ","response":"range_response_count:1 size:859"} {"level":"info","ts":"2026-07-27T20:20:26.708927Z","caller":"traceutil/trace.go:171","msg":"trace[2143880426] range","detail":"{range_begin:/registry/events/openstack/barbican-api-75dc54c74b-bd4km.18c63f0c8f8001b7; range_end:; response_count:1; response_revision:8059; }","duration":"179.600766ms","start":"2026-07-27T20:20:26.529295Z","end":"2026-07-27T20:20:26.708896Z","steps":["trace[2143880426] 'agreement among raft nodes before linearized reading' (duration: 179.224107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:26.709119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.370198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xvslf\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-07-27T20:20:26.709165Z","caller":"traceutil/trace.go:171","msg":"trace[519986019] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xvslf; range_end:; response_count:1; response_revision:8059; }","duration":"299.45118ms","start":"2026-07-27T20:20:26.409702Z","end":"2026-07-27T20:20:26.709154Z","steps":["trace[519986019] 'agreement among raft nodes before linearized reading' (duration: 299.277106ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:27.108805Z","caller":"traceutil/trace.go:171","msg":"trace[2060211926] linearizableReadLoop","detail":"{readStateIndex:8352; appliedIndex:8351; }","duration":"394.344456ms","start":"2026-07-27T20:20:26.714424Z","end":"2026-07-27T20:20:27.108768Z","steps":["trace[2060211926] 'read index received' (duration: 393.625379ms)","trace[2060211926] 'applied index is now lower than readState.Index' (duration: 717.577µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:27.108833Z","caller":"traceutil/trace.go:171","msg":"trace[516002191] transaction","detail":"{read_only:false; response_revision:8060; number_of_response:1; }","duration":"396.855787ms","start":"2026-07-27T20:20:26.711937Z","end":"2026-07-27T20:20:27.108793Z","steps":["trace[516002191] 'process raft request' (duration: 396.076868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:27.109041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:26.711902Z","time spent":"397.009781ms","remote":"127.0.0.1:36218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:20:27.109103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.00039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:27.109227Z","caller":"traceutil/trace.go:171","msg":"trace[1674658260] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8060; }","duration":"304.224487ms","start":"2026-07-27T20:20:26.804974Z","end":"2026-07-27T20:20:27.109198Z","steps":["trace[1674658260] 'agreement among raft nodes before linearized reading' (duration: 304.020011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:27.109281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:26.804934Z","time spent":"304.335269ms","remote":"127.0.0.1:44984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:20:27.109394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.954622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223982"} {"level":"info","ts":"2026-07-27T20:20:27.109444Z","caller":"traceutil/trace.go:171","msg":"trace[1650344472] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8060; }","duration":"395.063084ms","start":"2026-07-27T20:20:26.71437Z","end":"2026-07-27T20:20:27.109433Z","steps":["trace[1650344472] 'agreement among raft nodes before linearized reading' (duration: 394.574572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:27.109482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:26.714347Z","time spent":"395.124486ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":224006,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-27T20:20:27.109644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.419382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-27T20:20:27.109612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.535276ms","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-27T20:20:27.109684Z","caller":"traceutil/trace.go:171","msg":"trace[1538996462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8060; }","duration":"172.503614ms","start":"2026-07-27T20:20:26.93717Z","end":"2026-07-27T20:20:27.109674Z","steps":["trace[1538996462] 'agreement among raft nodes before linearized reading' (duration: 172.34812ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:27.109749Z","caller":"traceutil/trace.go:171","msg":"trace[92641546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8060; }","duration":"105.69244ms","start":"2026-07-27T20:20:27.004011Z","end":"2026-07-27T20:20:27.109703Z","steps":["trace[92641546] 'agreement among raft nodes before linearized reading' (duration: 105.006202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:27.313357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.940948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-43af9051-0ddb-4df0-9f46-c8f31735583f\" ","response":"range_response_count:1 size:1293"} {"level":"info","ts":"2026-07-27T20:20:27.313502Z","caller":"traceutil/trace.go:171","msg":"trace[602070543] range","detail":"{range_begin:/registry/persistentvolumes/pvc-43af9051-0ddb-4df0-9f46-c8f31735583f; range_end:; response_count:1; response_revision:8063; }","duration":"102.159063ms","start":"2026-07-27T20:20:27.211312Z","end":"2026-07-27T20:20:27.313471Z","steps":["trace[602070543] 'range keys from in-memory index tree' (duration: 101.645731ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:27.601951Z","caller":"traceutil/trace.go:171","msg":"trace[1804196633] transaction","detail":"{read_only:false; response_revision:8065; number_of_response:1; }","duration":"118.805818ms","start":"2026-07-27T20:20:27.483112Z","end":"2026-07-27T20:20:27.601918Z","steps":["trace[1804196633] 'process raft request' (duration: 115.826266ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:30.956457Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6004} {"level":"info","ts":"2026-07-27T20:20:31.269345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6004,"took":"310.428337ms","hash":245865487,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":21483520,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-27T20:20:31.269404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":245865487,"revision":6004,"compact-revision":2970} {"level":"info","ts":"2026-07-27T20:20:41.519069Z","caller":"traceutil/trace.go:171","msg":"trace[211405221] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"194.078408ms","start":"2026-07-27T20:20:41.324957Z","end":"2026-07-27T20:20:41.519036Z","steps":["trace[211405221] 'process raft request' (duration: 193.846412ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:41.554837Z","caller":"traceutil/trace.go:171","msg":"trace[1505168110] linearizableReadLoop","detail":"{readStateIndex:8568; appliedIndex:8566; }","duration":"184.169328ms","start":"2026-07-27T20:20:41.370637Z","end":"2026-07-27T20:20:41.554807Z","steps":["trace[1505168110] 'read index received' (duration: 148.07575ms)","trace[1505168110] 'applied index is now lower than readState.Index' (duration: 36.092317ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:41.554964Z","caller":"traceutil/trace.go:171","msg":"trace[1089908302] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"229.577011ms","start":"2026-07-27T20:20:41.325378Z","end":"2026-07-27T20:20:41.554955Z","steps":["trace[1089908302] 'process raft request' (duration: 229.271304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.55522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.456065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.555314Z","caller":"traceutil/trace.go:171","msg":"trace[265893304] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8271; }","duration":"184.575427ms","start":"2026-07-27T20:20:41.370719Z","end":"2026-07-27T20:20:41.555295Z","steps":["trace[265893304] 'agreement among raft nodes before linearized reading' (duration: 184.425354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.555398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.021005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.555444Z","caller":"traceutil/trace.go:171","msg":"trace[1165285498] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8271; }","duration":"108.152769ms","start":"2026-07-27T20:20:41.447283Z","end":"2026-07-27T20:20:41.555436Z","steps":["trace[1165285498] 'agreement among raft nodes before linearized reading' (duration: 108.059556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.555564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.926796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.555576Z","caller":"traceutil/trace.go:171","msg":"trace[1836550828] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8271; }","duration":"184.975357ms","start":"2026-07-27T20:20:41.370598Z","end":"2026-07-27T20:20:41.555573Z","steps":["trace[1836550828] 'agreement among raft nodes before linearized reading' (duration: 184.950966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.555893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.57672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.555939Z","caller":"traceutil/trace.go:171","msg":"trace[2137530497] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8271; }","duration":"108.668591ms","start":"2026-07-27T20:20:41.447257Z","end":"2026-07-27T20:20:41.555926Z","steps":["trace[2137530497] 'agreement among raft nodes before linearized reading' (duration: 108.572689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.556119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.08343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.556148Z","caller":"traceutil/trace.go:171","msg":"trace[1866686438] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8271; }","duration":"127.130611ms","start":"2026-07-27T20:20:41.429008Z","end":"2026-07-27T20:20:41.556139Z","steps":["trace[1866686438] 'agreement among raft nodes before linearized reading' (duration: 127.08252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.556298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.89786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.556329Z","caller":"traceutil/trace.go:171","msg":"trace[406709756] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8271; }","duration":"127.947171ms","start":"2026-07-27T20:20:41.428368Z","end":"2026-07-27T20:20:41.556316Z","steps":["trace[406709756] 'agreement among raft nodes before linearized reading' (duration: 127.90522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.556395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.302639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.55642Z","caller":"traceutil/trace.go:171","msg":"trace[1232762856] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8271; }","duration":"107.397931ms","start":"2026-07-27T20:20:41.449017Z","end":"2026-07-27T20:20:41.556415Z","steps":["trace[1232762856] 'agreement among raft nodes before linearized reading' (duration: 107.35858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.556475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.119115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.556506Z","caller":"traceutil/trace.go:171","msg":"trace[1455550004] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8271; }","duration":"128.149486ms","start":"2026-07-27T20:20:41.428347Z","end":"2026-07-27T20:20:41.556497Z","steps":["trace[1455550004] 'agreement among raft nodes before linearized reading' (duration: 128.104485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.556627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.390502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.556655Z","caller":"traceutil/trace.go:171","msg":"trace[1469365050] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8271; }","duration":"142.429013ms","start":"2026-07-27T20:20:41.414218Z","end":"2026-07-27T20:20:41.556647Z","steps":["trace[1469365050] 'agreement among raft nodes before linearized reading' (duration: 142.387442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.557053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.730895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.55708Z","caller":"traceutil/trace.go:171","msg":"trace[2045547552] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8271; }","duration":"185.769396ms","start":"2026-07-27T20:20:41.371305Z","end":"2026-07-27T20:20:41.557074Z","steps":["trace[2045547552] 'agreement among raft nodes before linearized reading' (duration: 185.727475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.557197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.104419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.557216Z","caller":"traceutil/trace.go:171","msg":"trace[371679174] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8271; }","duration":"143.1532ms","start":"2026-07-27T20:20:41.414054Z","end":"2026-07-27T20:20:41.557207Z","steps":["trace[371679174] 'agreement among raft nodes before linearized reading' (duration: 143.104819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.557299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.360005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.557313Z","caller":"traceutil/trace.go:171","msg":"trace[1719461910] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8271; }","duration":"143.387005ms","start":"2026-07-27T20:20:41.413921Z","end":"2026-07-27T20:20:41.557308Z","steps":["trace[1719461910] 'agreement among raft nodes before linearized reading' (duration: 143.362905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.557424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.999501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-27T20:20:41.557437Z","caller":"traceutil/trace.go:171","msg":"trace[1406738263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8271; }","duration":"151.023461ms","start":"2026-07-27T20:20:41.406409Z","end":"2026-07-27T20:20:41.557432Z","steps":["trace[1406738263] 'agreement among raft nodes before linearized reading' (duration: 150.97614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.557509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.32357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.557524Z","caller":"traceutil/trace.go:171","msg":"trace[1151522399] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8271; }","duration":"163.35584ms","start":"2026-07-27T20:20:41.394162Z","end":"2026-07-27T20:20:41.557518Z","steps":["trace[1151522399] 'agreement among raft nodes before linearized reading' (duration: 163.32922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.55762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.229372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.557636Z","caller":"traceutil/trace.go:171","msg":"trace[193117341] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8271; }","duration":"164.273693ms","start":"2026-07-27T20:20:41.393355Z","end":"2026-07-27T20:20:41.557628Z","steps":["trace[193117341] 'agreement among raft nodes before linearized reading' (duration: 164.248893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:41.55771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.399167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:41.557725Z","caller":"traceutil/trace.go:171","msg":"trace[657751115] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8271; }","duration":"164.448558ms","start":"2026-07-27T20:20:41.393271Z","end":"2026-07-27T20:20:41.557719Z","steps":["trace[657751115] 'agreement among raft nodes before linearized reading' (duration: 164.421218ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:53.014379Z","caller":"traceutil/trace.go:171","msg":"trace[1943193348] transaction","detail":"{read_only:false; response_revision:8329; number_of_response:1; }","duration":"142.73671ms","start":"2026-07-27T20:20:52.871614Z","end":"2026-07-27T20:20:53.01435Z","steps":["trace[1943193348] 'process raft request' (duration: 142.544025ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:54.564508Z","caller":"traceutil/trace.go:171","msg":"trace[478405707] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"257.386348ms","start":"2026-07-27T20:20:54.307081Z","end":"2026-07-27T20:20:54.564468Z","steps":["trace[478405707] 'process raft request' (duration: 247.911528ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:54.564706Z","caller":"traceutil/trace.go:171","msg":"trace[2061868646] transaction","detail":"{read_only:false; response_revision:8337; number_of_response:1; }","duration":"139.358907ms","start":"2026-07-27T20:20:54.425338Z","end":"2026-07-27T20:20:54.564697Z","steps":["trace[2061868646] 'process raft request' (duration: 139.07507ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:54.564696Z","caller":"traceutil/trace.go:171","msg":"trace[1866506716] linearizableReadLoop","detail":"{readStateIndex:8636; appliedIndex:8634; }","duration":"154.222309ms","start":"2026-07-27T20:20:54.410431Z","end":"2026-07-27T20:20:54.564653Z","steps":["trace[1866506716] 'read index received' (duration: 82.26336ms)","trace[1866506716] 'applied index is now lower than readState.Index' (duration: 71.955849ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:54.565078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.618668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-27T20:20:54.565208Z","caller":"traceutil/trace.go:171","msg":"trace[1609488168] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8337; }","duration":"154.809714ms","start":"2026-07-27T20:20:54.410368Z","end":"2026-07-27T20:20:54.565178Z","steps":["trace[1609488168] 'agreement among raft nodes before linearized reading' (duration: 154.533427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:54.569473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.752059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-27T20:20:54.569529Z","caller":"traceutil/trace.go:171","msg":"trace[1502257717] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8338; }","duration":"158.844301ms","start":"2026-07-27T20:20:54.410668Z","end":"2026-07-27T20:20:54.569512Z","steps":["trace[1502257717] 'agreement among raft nodes before linearized reading' (duration: 158.691608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:54.569566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.001336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-75dc54c74b-bd4km.18c63f0ea57e14d7\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-07-27T20:20:54.569642Z","caller":"traceutil/trace.go:171","msg":"trace[2112269765] range","detail":"{range_begin:/registry/events/openstack/barbican-api-75dc54c74b-bd4km.18c63f0ea57e14d7; range_end:; response_count:1; response_revision:8338; }","duration":"129.097439ms","start":"2026-07-27T20:20:54.440522Z","end":"2026-07-27T20:20:54.56962Z","steps":["trace[2112269765] 'agreement among raft nodes before linearized reading' (duration: 128.853483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:54.5695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.859056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:54.569836Z","caller":"traceutil/trace.go:171","msg":"trace[1955288211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8338; }","duration":"153.196265ms","start":"2026-07-27T20:20:54.416584Z","end":"2026-07-27T20:20:54.56978Z","steps":["trace[1955288211] 'agreement among raft nodes before linearized reading' (duration: 152.809335ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:21:23.025374Z","caller":"traceutil/trace.go:171","msg":"trace[197238121] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"173.632851ms","start":"2026-07-27T20:21:22.851694Z","end":"2026-07-27T20:21:23.025327Z","steps":["trace[197238121] 'process raft request' (duration: 173.377675ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:21:24.56182Z","caller":"traceutil/trace.go:171","msg":"trace[1530258933] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"118.123171ms","start":"2026-07-27T20:21:24.443661Z","end":"2026-07-27T20:21:24.561784Z","steps":["trace[1530258933] 'process raft request' (duration: 117.884715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:21:36.741619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.702168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2c4kd\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-07-27T20:21:36.741738Z","caller":"traceutil/trace.go:171","msg":"trace[958040523] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2c4kd; range_end:; response_count:1; response_revision:8655; }","duration":"130.894813ms","start":"2026-07-27T20:21:36.610817Z","end":"2026-07-27T20:21:36.741712Z","steps":["trace[958040523] 'range keys from in-memory index tree' (duration: 130.492653ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:21:37.410189Z","caller":"traceutil/trace.go:171","msg":"trace[806964556] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"131.17805ms","start":"2026-07-27T20:21:37.278983Z","end":"2026-07-27T20:21:37.410161Z","steps":["trace[806964556] 'process raft request' (duration: 130.972275ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:08.150067Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002454.wal"} {"level":"info","ts":"2026-07-27T20:23:28.55673Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:23:28.563812Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-27T20:23:28.563934Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-27T20:25:30.967638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8079} {"level":"info","ts":"2026-07-27T20:25:31.032158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8079,"took":"62.192991ms","hash":1937748658,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":29679616,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-27T20:25:31.032218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1937748658,"revision":8079,"compact-revision":6004} {"level":"warn","ts":"2026-07-27T20:26:32.53075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.315998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:26:32.530849Z","caller":"traceutil/trace.go:171","msg":"trace[1899273420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11265; }","duration":"114.470281ms","start":"2026-07-27T20:26:32.416359Z","end":"2026-07-27T20:26:32.530829Z","steps":["trace[1899273420] 'range keys from in-memory index tree' (duration: 114.177094ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:30:30.975905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10698} {"level":"info","ts":"2026-07-27T20:30:31.072752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10698,"took":"94.476732ms","hash":4277195956,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":27525120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-27T20:30:31.072822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4277195956,"revision":10698,"compact-revision":8079} {"level":"info","ts":"2026-07-27T20:33:04.043381Z","caller":"traceutil/trace.go:171","msg":"trace[1177405585] transaction","detail":"{read_only:false; response_revision:13887; number_of_response:1; }","duration":"151.784011ms","start":"2026-07-27T20:33:03.891578Z","end":"2026-07-27T20:33:04.043362Z","steps":["trace[1177405585] 'process raft request' (duration: 151.452363ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:33:04.152222Z","caller":"traceutil/trace.go:171","msg":"trace[598925853] transaction","detail":"{read_only:false; response_revision:13889; number_of_response:1; }","duration":"103.927844ms","start":"2026-07-27T20:33:04.048251Z","end":"2026-07-27T20:33:04.152179Z","steps":["trace[598925853] 'process raft request' (duration: 77.688825ms)","trace[598925853] 'compare' (duration: 25.985233ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:33:04.152296Z","caller":"traceutil/trace.go:171","msg":"trace[1950985785] transaction","detail":"{read_only:false; response_revision:13890; number_of_response:1; }","duration":"103.966915ms","start":"2026-07-27T20:33:04.048283Z","end":"2026-07-27T20:33:04.15225Z","steps":["trace[1950985785] 'process raft request' (duration: 103.78663ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:33:29.654928Z","caller":"traceutil/trace.go:171","msg":"trace[1230289174] transaction","detail":"{read_only:false; response_revision:14213; number_of_response:1; }","duration":"169.158526ms","start":"2026-07-27T20:33:29.485731Z","end":"2026-07-27T20:33:29.654889Z","steps":["trace[1230289174] 'process raft request' (duration: 168.686805ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:30.708444Z","caller":"traceutil/trace.go:171","msg":"trace[1565958023] transaction","detail":"{read_only:false; response_revision:14892; number_of_response:1; }","duration":"121.843641ms","start":"2026-07-27T20:34:30.586581Z","end":"2026-07-27T20:34:30.708425Z","steps":["trace[1565958023] 'process raft request' (duration: 120.713723ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:30.708444Z","caller":"traceutil/trace.go:171","msg":"trace[253014701] linearizableReadLoop","detail":"{readStateIndex:15407; appliedIndex:15406; }","duration":"109.365287ms","start":"2026-07-27T20:34:30.599051Z","end":"2026-07-27T20:34:30.708416Z","steps":["trace[253014701] 'read index received' (duration: 108.129916ms)","trace[253014701] 'applied index is now lower than readState.Index' (duration: 1.234121ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:34:30.708577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.50958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-27T20:34:30.708628Z","caller":"traceutil/trace.go:171","msg":"trace[158538190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14892; }","duration":"109.612503ms","start":"2026-07-27T20:34:30.599006Z","end":"2026-07-27T20:34:30.708619Z","steps":["trace[158538190] 'agreement among raft nodes before linearized reading' (duration: 109.444009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:34:30.708948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.100761ms","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-27T20:34:30.708984Z","caller":"traceutil/trace.go:171","msg":"trace[536654924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14892; }","duration":"107.156643ms","start":"2026-07-27T20:34:30.601814Z","end":"2026-07-27T20:34:30.708971Z","steps":["trace[536654924] 'agreement among raft nodes before linearized reading' (duration: 106.863676ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:30.990593Z","caller":"traceutil/trace.go:171","msg":"trace[1898066207] transaction","detail":"{read_only:false; response_revision:14893; number_of_response:1; }","duration":"278.051411ms","start":"2026-07-27T20:34:30.712516Z","end":"2026-07-27T20:34:30.990567Z","steps":["trace[1898066207] 'process raft request' (duration: 268.368995ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:31.017197Z","caller":"traceutil/trace.go:171","msg":"trace[1014890794] transaction","detail":"{read_only:false; response_revision:14894; number_of_response:1; }","duration":"302.965628ms","start":"2026-07-27T20:34:30.714203Z","end":"2026-07-27T20:34:31.017168Z","steps":["trace[1014890794] 'process raft request' (duration: 302.489266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:34:31.017323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:34:30.714174Z","time spent":"303.084381ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:34:31.017478Z","caller":"traceutil/trace.go:171","msg":"trace[129009253] transaction","detail":"{read_only:false; response_revision:14895; number_of_response:1; }","duration":"302.833795ms","start":"2026-07-27T20:34:30.714633Z","end":"2026-07-27T20:34:31.017467Z","steps":["trace[129009253] 'process raft request' (duration: 302.180399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:34:31.017541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:34:30.714619Z","time spent":"302.883786ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:34:31.017711Z","caller":"traceutil/trace.go:171","msg":"trace[1033596713] transaction","detail":"{read_only:false; response_revision:14896; number_of_response:1; }","duration":"302.835824ms","start":"2026-07-27T20:34:30.714866Z","end":"2026-07-27T20:34:31.017702Z","steps":["trace[1033596713] 'process raft request' (duration: 301.979194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:34:31.017762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:34:30.714849Z","time spent":"302.882366ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:35:30.986184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12802} {"level":"info","ts":"2026-07-27T20:35:31.070788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12802,"took":"81.62963ms","hash":2060416652,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":29732864,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-27T20:35:31.07094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2060416652,"revision":12802,"compact-revision":10698} {"level":"info","ts":"2026-07-27T20:40:30.992104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15222} {"level":"info","ts":"2026-07-27T20:40:31.094463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15222,"took":"98.252553ms","hash":1633933446,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31510528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T20:40:31.094601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1633933446,"revision":15222,"compact-revision":12802} {"level":"warn","ts":"2026-07-27T20:40:50.538991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.655385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:40:50.53918Z","caller":"traceutil/trace.go:171","msg":"trace[250743825] transaction","detail":"{read_only:false; response_revision:17639; number_of_response:1; }","duration":"498.107512ms","start":"2026-07-27T20:40:50.041004Z","end":"2026-07-27T20:40:50.539111Z","steps":["trace[250743825] 'process raft request' (duration: 220.207611ms)","trace[250743825] 'compare' (duration: 276.932648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:40:50.539253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:40:50.04098Z","time spent":"498.241745ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:40:50.599967Z","caller":"traceutil/trace.go:171","msg":"trace[1262013081] linearizableReadLoop","detail":"{readStateIndex:18249; appliedIndex:18248; }","duration":"326.856439ms","start":"2026-07-27T20:40:50.273087Z","end":"2026-07-27T20:40:50.599944Z","steps":["trace[1262013081] 'read index received' (duration: 326.655664ms)","trace[1262013081] 'applied index is now lower than readState.Index' (duration: 199.975µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:40:50.600313Z","caller":"traceutil/trace.go:171","msg":"trace[366486605] transaction","detail":"{read_only:false; response_revision:17640; number_of_response:1; }","duration":"327.543835ms","start":"2026-07-27T20:40:50.272755Z","end":"2026-07-27T20:40:50.600299Z","steps":["trace[366486605] 'process raft request' (duration: 327.052754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:40:50.600429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:40:50.272729Z","time spent":"327.612696ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:40:50.600719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.733163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-sb6hw\" ","response":"range_response_count:1 size:20759"} {"level":"info","ts":"2026-07-27T20:40:50.600823Z","caller":"traceutil/trace.go:171","msg":"trace[990507408] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-sb6hw; range_end:; response_count:1; response_revision:17640; }","duration":"323.880846ms","start":"2026-07-27T20:40:50.276924Z","end":"2026-07-27T20:40:50.600805Z","steps":["trace[990507408] 'agreement among raft nodes before linearized reading' (duration: 323.60611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:40:50.600932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:40:50.276853Z","time spent":"324.061281ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20783,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-sb6hw\" "} {"level":"warn","ts":"2026-07-27T20:40:50.601018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.379026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-27T20:40:50.601003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.871673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-27T20:40:50.601079Z","caller":"traceutil/trace.go:171","msg":"trace[2078039876] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:17640; }","duration":"246.474628ms","start":"2026-07-27T20:40:50.354587Z","end":"2026-07-27T20:40:50.601061Z","steps":["trace[2078039876] 'agreement among raft nodes before linearized reading' (duration: 246.397257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:40:50.60094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.022379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:40:50.601197Z","caller":"traceutil/trace.go:171","msg":"trace[278045195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17640; }","duration":"183.323436ms","start":"2026-07-27T20:40:50.417861Z","end":"2026-07-27T20:40:50.601185Z","steps":["trace[278045195] 'agreement among raft nodes before linearized reading' (duration: 183.03487ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:40:50.601155Z","caller":"traceutil/trace.go:171","msg":"trace[1990746516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17640; }","duration":"328.089408ms","start":"2026-07-27T20:40:50.273025Z","end":"2026-07-27T20:40:50.601114Z","steps":["trace[1990746516] 'agreement among raft nodes before linearized reading' (duration: 327.652397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:40:50.601317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:40:50.272961Z","time spent":"328.341744ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-27T20:44:18.204275Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:44:18.217232Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-27T20:44:18.217364Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-27T20:45:31.000511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17500} {"level":"info","ts":"2026-07-27T20:45:31.099372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17500,"took":"93.722799ms","hash":2191533575,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":34496512,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-27T20:45:31.099469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2191533575,"revision":17500,"compact-revision":15222} {"level":"info","ts":"2026-07-27T20:46:43.530705Z","caller":"traceutil/trace.go:171","msg":"trace[219018141] linearizableReadLoop","detail":"{readStateIndex:21054; appliedIndex:21052; }","duration":"253.026144ms","start":"2026-07-27T20:46:43.277645Z","end":"2026-07-27T20:46:43.530671Z","steps":["trace[219018141] 'read index received' (duration: 224.540774ms)","trace[219018141] 'applied index is now lower than readState.Index' (duration: 28.48407ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:46:43.530781Z","caller":"traceutil/trace.go:171","msg":"trace[131981289] transaction","detail":"{read_only:false; response_revision:20354; number_of_response:1; }","duration":"317.75265ms","start":"2026-07-27T20:46:43.212986Z","end":"2026-07-27T20:46:43.530738Z","steps":["trace[131981289] 'process raft request' (duration: 289.180179ms)","trace[131981289] 'compare' (duration: 28.065239ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:46:43.530898Z","caller":"traceutil/trace.go:171","msg":"trace[1419413204] transaction","detail":"{read_only:false; response_revision:20355; number_of_response:1; }","duration":"316.693095ms","start":"2026-07-27T20:46:43.214164Z","end":"2026-07-27T20:46:43.530857Z","steps":["trace[1419413204] 'process raft request' (duration: 316.425959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:46:43.531023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.371622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-27T20:46:43.531078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:46:43.214149Z","time spent":"316.836068ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:46:43.531101Z","caller":"traceutil/trace.go:171","msg":"trace[90127539] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20355; }","duration":"253.485536ms","start":"2026-07-27T20:46:43.277592Z","end":"2026-07-27T20:46:43.531078Z","steps":["trace[90127539] 'agreement among raft nodes before linearized reading' (duration: 253.26974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:46:43.530924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:46:43.212962Z","time spent":"317.878143ms","remote":"127.0.0.1:36460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:46:43.534773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.040897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:46:43.534854Z","caller":"traceutil/trace.go:171","msg":"trace[1161262312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20356; }","duration":"118.18017ms","start":"2026-07-27T20:46:43.416654Z","end":"2026-07-27T20:46:43.534834Z","steps":["trace[1161262312] 'agreement among raft nodes before linearized reading' (duration: 118.038926ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:46:43.534872Z","caller":"traceutil/trace.go:171","msg":"trace[881171016] transaction","detail":"{read_only:false; response_revision:20356; number_of_response:1; }","duration":"219.130463ms","start":"2026-07-27T20:46:43.315688Z","end":"2026-07-27T20:46:43.534818Z","steps":["trace[881171016] 'process raft request' (duration: 218.907908ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:46:44.711775Z","caller":"traceutil/trace.go:171","msg":"trace[884840939] linearizableReadLoop","detail":"{readStateIndex:21059; appliedIndex:21058; }","duration":"172.375122ms","start":"2026-07-27T20:46:44.539365Z","end":"2026-07-27T20:46:44.71174Z","steps":["trace[884840939] 'read index received' (duration: 152.475711ms)","trace[884840939] 'applied index is now lower than readState.Index' (duration: 19.897421ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:46:44.712082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.665519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-27T20:46:44.712151Z","caller":"traceutil/trace.go:171","msg":"trace[393717049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20359; }","duration":"172.812733ms","start":"2026-07-27T20:46:44.539327Z","end":"2026-07-27T20:46:44.71214Z","steps":["trace[393717049] 'agreement among raft nodes before linearized reading' (duration: 172.525526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:46:44.712356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.215905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-27T20:46:44.712478Z","caller":"traceutil/trace.go:171","msg":"trace[615985652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20359; }","duration":"169.372449ms","start":"2026-07-27T20:46:44.543084Z","end":"2026-07-27T20:46:44.712456Z","steps":["trace[615985652] 'agreement among raft nodes before linearized reading' (duration: 168.972819ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:46:44.71183Z","caller":"traceutil/trace.go:171","msg":"trace[45932527] transaction","detail":"{read_only:false; response_revision:20359; number_of_response:1; }","duration":"216.063569ms","start":"2026-07-27T20:46:44.495735Z","end":"2026-07-27T20:46:44.711799Z","steps":["trace[45932527] 'process raft request' (duration: 196.100036ms)","trace[45932527] 'compare' (duration: 19.361519ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:50:31.00708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19968} {"level":"info","ts":"2026-07-27T20:50:31.121242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19968,"took":"109.789389ms","hash":704380843,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31916032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T20:50:31.121321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":704380843,"revision":19968,"compact-revision":17500} {"level":"info","ts":"2026-07-27T20:50:45.515178Z","caller":"traceutil/trace.go:171","msg":"trace[1282270686] linearizableReadLoop","detail":"{readStateIndex:23606; appliedIndex:23605; }","duration":"107.777971ms","start":"2026-07-27T20:50:45.407376Z","end":"2026-07-27T20:50:45.515154Z","steps":["trace[1282270686] 'read index received' (duration: 107.463743ms)","trace[1282270686] 'applied index is now lower than readState.Index' (duration: 312.408µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:50:45.515342Z","caller":"traceutil/trace.go:171","msg":"trace[1300324677] transaction","detail":"{read_only:false; response_revision:22830; number_of_response:1; }","duration":"133.316665ms","start":"2026-07-27T20:50:45.382013Z","end":"2026-07-27T20:50:45.51533Z","steps":["trace[1300324677] 'process raft request' (duration: 132.877325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:50:45.515659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.851504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:87328"} {"level":"info","ts":"2026-07-27T20:50:45.515765Z","caller":"traceutil/trace.go:171","msg":"trace[451439289] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22830; }","duration":"106.028088ms","start":"2026-07-27T20:50:45.40972Z","end":"2026-07-27T20:50:45.515748Z","steps":["trace[451439289] 'agreement among raft nodes before linearized reading' (duration: 105.728291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:50:45.516794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.412721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:50:45.517009Z","caller":"traceutil/trace.go:171","msg":"trace[1921266221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22830; }","duration":"101.279973ms","start":"2026-07-27T20:50:45.415654Z","end":"2026-07-27T20:50:45.516934Z","steps":["trace[1921266221] 'agreement among raft nodes before linearized reading' (duration: 99.923829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:50:45.517165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.766821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-27T20:50:45.517453Z","caller":"traceutil/trace.go:171","msg":"trace[904729910] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:22830; }","duration":"109.993945ms","start":"2026-07-27T20:50:45.407334Z","end":"2026-07-27T20:50:45.517328Z","steps":["trace[904729910] 'agreement among raft nodes before linearized reading' (duration: 108.522519ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:50:45.86884Z","caller":"traceutil/trace.go:171","msg":"trace[2023247905] linearizableReadLoop","detail":"{readStateIndex:23607; appliedIndex:23606; }","duration":"273.951328ms","start":"2026-07-27T20:50:45.594853Z","end":"2026-07-27T20:50:45.868805Z","steps":["trace[2023247905] 'read index received' (duration: 273.639521ms)","trace[2023247905] 'applied index is now lower than readState.Index' (duration: 291.477µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:50:45.869036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.165693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:50:45.869085Z","caller":"traceutil/trace.go:171","msg":"trace[2034184160] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22831; }","duration":"274.270206ms","start":"2026-07-27T20:50:45.594801Z","end":"2026-07-27T20:50:45.869071Z","steps":["trace[2034184160] 'agreement among raft nodes before linearized reading' (duration: 274.124462ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:50:45.869037Z","caller":"traceutil/trace.go:171","msg":"trace[758348864] transaction","detail":"{read_only:false; response_revision:22831; number_of_response:1; }","duration":"290.907572ms","start":"2026-07-27T20:50:45.577986Z","end":"2026-07-27T20:50:45.868894Z","steps":["trace[758348864] 'process raft request' (duration: 290.505063ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:50:45.870363Z","caller":"traceutil/trace.go:171","msg":"trace[1957608973] transaction","detail":"{read_only:false; response_revision:22832; number_of_response:1; }","duration":"126.963501ms","start":"2026-07-27T20:50:45.74336Z","end":"2026-07-27T20:50:45.870324Z","steps":["trace[1957608973] 'process raft request' (duration: 126.780796ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:31.017471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22236} {"level":"info","ts":"2026-07-27T20:55:31.14149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22236,"took":"116.442042ms","hash":2610502760,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":42344448,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-27T20:55:31.141552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2610502760,"revision":22236,"compact-revision":19968} {"level":"info","ts":"2026-07-27T21:00:24.216908Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006f03.wal"} {"level":"info","ts":"2026-07-27T21:00:31.023283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25285} {"level":"info","ts":"2026-07-27T21:00:31.167326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25285,"took":"140.193017ms","hash":142024698,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31076352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-27T21:00:31.167383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":142024698,"revision":25285,"compact-revision":22236}