{"level":"warn","ts":"2026-08-22T14:36:11.808999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:36:11.809078Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-08-22T14:36:11.80914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:36:11.809158Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-08-22T14:36:11.809177Z","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-08-22T14:36:11.809704Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-08-22T14:36:11.809785Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-08-22T14:36:11.814526Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.529978ms"} {"level":"info","ts":"2026-08-22T14:36:11.826778Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-08-22T14:36:11.826884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:36:11.826918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-08-22T14:36:11.826928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:36:11.826942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-08-22T14:36:11.826996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-08-22T14:36:11.839649Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:36:11.844651Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:36:11.84626Z","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-08-22T14:36:11.848662Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:36:11.848801Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:36:11.848952Z","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-08-22T14:36:11.848999Z","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-08-22T14:36:11.849018Z","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-08-22T14:36:11.850356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-08-22T14:36:11.850477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-08-22T14:36:11.852303Z","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-08-22T14:36:11.852481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-08-22T14:36:11.852512Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-08-22T14:36:11.85344Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:36:11.853472Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:36:11.928119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:36:11.928212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:36:11.928251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-08-22T14:36:11.928297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:36:11.92831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-08-22T14:36:11.928328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-08-22T14:36:11.928367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-08-22T14:36:11.929691Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:36:11.930428Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:36:11.930476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:36:11.930948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:36:11.931056Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:36:11.931219Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:36:11.93127Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:36:11.931457Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:36:11.931599Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:36:11.934825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:36:11.934962Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"warn","ts":"2026-08-22T14:36:15.169388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.372711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:15.169678Z","caller":"traceutil/trace.go:171","msg":"trace[1722315103] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"302.992011ms","start":"2026-08-22T14:36:14.866629Z","end":"2026-08-22T14:36:15.169621Z","steps":["trace[1722315103] 'process raft request' (duration: 188.567906ms)","trace[1722315103] 'compare' (duration: 113.123802ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:15.169707Z","caller":"traceutil/trace.go:171","msg":"trace[1435847584] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"295.470005ms","start":"2026-08-22T14:36:14.874208Z","end":"2026-08-22T14:36:15.169678Z","steps":["trace[1435847584] 'process raft request' (duration: 295.355881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:15.169902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:14.866612Z","time spent":"303.210217ms","remote":"127.0.0.1:49688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":650,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:21.340499Z","caller":"traceutil/trace.go:171","msg":"trace[408528827] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"110.27715ms","start":"2026-08-22T14:36:21.230195Z","end":"2026-08-22T14:36:21.340472Z","steps":["trace[408528827] 'read index received' (duration: 110.122225ms)","trace[408528827] 'applied index is now lower than readState.Index' (duration: 154.115µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:21.340576Z","caller":"traceutil/trace.go:171","msg":"trace[1033238037] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"148.141325ms","start":"2026-08-22T14:36:21.192389Z","end":"2026-08-22T14:36:21.34053Z","steps":["trace[1033238037] 'process raft request' (duration: 147.919428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:21.3411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.857809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-22T14:36:21.341386Z","caller":"traceutil/trace.go:171","msg":"trace[1862985519] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:335; }","duration":"111.040224ms","start":"2026-08-22T14:36:21.230163Z","end":"2026-08-22T14:36:21.341203Z","steps":["trace[1862985519] 'agreement among raft nodes before linearized reading' (duration: 110.457345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:21.693234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.613537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:21.693347Z","caller":"traceutil/trace.go:171","msg":"trace[326860709] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"196.152833ms","start":"2026-08-22T14:36:21.497177Z","end":"2026-08-22T14:36:21.69333Z","steps":["trace[326860709] 'read index received' (duration: 75.427682ms)","trace[326860709] 'applied index is now lower than readState.Index' (duration: 120.723781ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:21.69358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.403882ms","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-08-22T14:36:21.693622Z","caller":"traceutil/trace.go:171","msg":"trace[1741674856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:336; }","duration":"196.502254ms","start":"2026-08-22T14:36:21.497107Z","end":"2026-08-22T14:36:21.693609Z","steps":["trace[1741674856] 'agreement among raft nodes before linearized reading' (duration: 196.349449ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:21.693438Z","caller":"traceutil/trace.go:171","msg":"trace[1534207513] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"347.590827ms","start":"2026-08-22T14:36:21.345795Z","end":"2026-08-22T14:36:21.693386Z","steps":["trace[1534207513] 'process raft request' (duration: 226.751872ms)","trace[1534207513] 'compare' (duration: 120.467813ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:21.69391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:21.34575Z","time spent":"347.991049ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:22.426269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.626481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:22.426424Z","caller":"traceutil/trace.go:171","msg":"trace[2074572510] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"726.162024ms","start":"2026-08-22T14:36:21.70022Z","end":"2026-08-22T14:36:22.426382Z","steps":["trace[2074572510] 'read index received' (duration: 252.181362ms)","trace[2074572510] 'applied index is now lower than readState.Index' (duration: 473.978842ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:22.426478Z","caller":"traceutil/trace.go:171","msg":"trace[200636917] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"727.744356ms","start":"2026-08-22T14:36:21.698683Z","end":"2026-08-22T14:36:22.426428Z","steps":["trace[200636917] 'process raft request' (duration: 253.632069ms)","trace[200636917] 'compare' (duration: 473.455725ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:22.426716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.235038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-22T14:36:22.426789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:21.698667Z","time spent":"728.000505ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:22.426782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.531686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-22T14:36:22.427076Z","caller":"traceutil/trace.go:171","msg":"trace[1289879750] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"726.756114ms","start":"2026-08-22T14:36:21.700165Z","end":"2026-08-22T14:36:22.426921Z","steps":["trace[1289879750] 'agreement among raft nodes before linearized reading' (duration: 726.33135ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:22.42684Z","caller":"traceutil/trace.go:171","msg":"trace[2033113660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:337; }","duration":"700.424344ms","start":"2026-08-22T14:36:21.726395Z","end":"2026-08-22T14:36:22.42682Z","steps":["trace[2033113660] 'agreement among raft nodes before linearized reading' (duration: 700.210867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:22.427143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:21.700146Z","time spent":"726.983781ms","remote":"127.0.0.1:49440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-22T14:36:22.427147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:21.726383Z","time spent":"700.750995ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:36:22.426754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.414464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-22T14:36:22.427266Z","caller":"traceutil/trace.go:171","msg":"trace[441747352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:337; }","duration":"284.982793ms","start":"2026-08-22T14:36:22.142274Z","end":"2026-08-22T14:36:22.427257Z","steps":["trace[441747352] 'agreement among raft nodes before linearized reading' (duration: 284.336132ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:50.678965Z","caller":"traceutil/trace.go:171","msg":"trace[1994352852] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"124.820345ms","start":"2026-08-22T14:36:50.554094Z","end":"2026-08-22T14:36:50.678914Z","steps":["trace[1994352852] 'process raft request' (duration: 31.277511ms)","trace[1994352852] 'compare' (duration: 93.340267ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:50.875825Z","caller":"traceutil/trace.go:171","msg":"trace[592589304] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"321.514835ms","start":"2026-08-22T14:36:50.554264Z","end":"2026-08-22T14:36:50.875779Z","steps":["trace[592589304] 'process raft request' (duration: 321.103862ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:50.875942Z","caller":"traceutil/trace.go:171","msg":"trace[2034686196] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"321.391191ms","start":"2026-08-22T14:36:50.554507Z","end":"2026-08-22T14:36:50.875898Z","steps":["trace[2034686196] 'process raft request' (duration: 321.233386ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:50.875961Z","caller":"traceutil/trace.go:171","msg":"trace[590354935] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"321.469454ms","start":"2026-08-22T14:36:50.554436Z","end":"2026-08-22T14:36:50.875905Z","steps":["trace[590354935] 'process raft request' (duration: 321.232646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.876042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.554254Z","time spent":"321.687281ms","remote":"127.0.0.1:49856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:50.876133Z","caller":"traceutil/trace.go:171","msg":"trace[1753349411] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"317.119182ms","start":"2026-08-22T14:36:50.558971Z","end":"2026-08-22T14:36:50.87609Z","steps":["trace[1753349411] 'process raft request' (duration: 316.815762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.876194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.554427Z","time spent":"321.664841ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:50.876221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.554484Z","time spent":"321.580808ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:50.876308Z","caller":"traceutil/trace.go:171","msg":"trace[271411977] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:560; }","duration":"299.828198ms","start":"2026-08-22T14:36:50.57642Z","end":"2026-08-22T14:36:50.876248Z","steps":["trace[271411977] 'read index received' (duration: 8.994214ms)","trace[271411977] 'applied index is now lower than readState.Index' (duration: 290.833074ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:50.876358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.558941Z","time spent":"317.276767ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:50.876317Z","caller":"traceutil/trace.go:171","msg":"trace[1375156369] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"317.073631ms","start":"2026-08-22T14:36:50.559228Z","end":"2026-08-22T14:36:50.876302Z","steps":["trace[1375156369] 'process raft request' (duration: 316.676728ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:50.876422Z","caller":"traceutil/trace.go:171","msg":"trace[1839159951] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"309.39827ms","start":"2026-08-22T14:36:50.566964Z","end":"2026-08-22T14:36:50.876363Z","steps":["trace[1839159951] 'process raft request' (duration: 309.158482ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:50.876473Z","caller":"traceutil/trace.go:171","msg":"trace[1517472268] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"314.694273ms","start":"2026-08-22T14:36:50.561761Z","end":"2026-08-22T14:36:50.876455Z","steps":["trace[1517472268] 'process raft request' (duration: 314.244018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.87648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.559207Z","time spent":"317.210095ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:50.876519Z","caller":"traceutil/trace.go:171","msg":"trace[1835889091] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"298.623478ms","start":"2026-08-22T14:36:50.577805Z","end":"2026-08-22T14:36:50.876428Z","steps":["trace[1835889091] 'process raft request' (duration: 298.39478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.876582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.124347ms","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-08-22T14:36:50.876593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.56695Z","time spent":"309.560305ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:50.876626Z","caller":"traceutil/trace.go:171","msg":"trace[702344462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"300.237951ms","start":"2026-08-22T14:36:50.576378Z","end":"2026-08-22T14:36:50.876616Z","steps":["trace[702344462] 'agreement among raft nodes before linearized reading' (duration: 300.058545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.876661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.57636Z","time spent":"300.294103ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T14:36:50.876417Z","caller":"traceutil/trace.go:171","msg":"trace[727809537] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"316.863473ms","start":"2026-08-22T14:36:50.559497Z","end":"2026-08-22T14:36:50.876361Z","steps":["trace[727809537] 'process raft request' (duration: 316.467801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.87697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.559489Z","time spent":"317.380401ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:50.876571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.561743Z","time spent":"314.768605ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:50.880017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.287565ms","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-08-22T14:36:50.880129Z","caller":"traceutil/trace.go:171","msg":"trace[1662381478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"301.527943ms","start":"2026-08-22T14:36:50.578583Z","end":"2026-08-22T14:36:50.880111Z","steps":["trace[1662381478] 'agreement among raft nodes before linearized reading' (duration: 301.340437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.880197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:50.578552Z","time spent":"301.632146ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T14:36:50.880466Z","caller":"traceutil/trace.go:171","msg":"trace[963341484] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"185.662441ms","start":"2026-08-22T14:36:50.694787Z","end":"2026-08-22T14:36:50.880449Z","steps":["trace[963341484] 'process raft request' (duration: 184.954938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:50.880652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.881553ms","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-08-22T14:36:50.880723Z","caller":"traceutil/trace.go:171","msg":"trace[413504760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"293.991147ms","start":"2026-08-22T14:36:50.586712Z","end":"2026-08-22T14:36:50.880703Z","steps":["trace[413504760] 'agreement among raft nodes before linearized reading' (duration: 293.874003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:51.949401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.447582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:51.95013Z","caller":"traceutil/trace.go:171","msg":"trace[431506951] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"832.267618ms","start":"2026-08-22T14:36:51.117797Z","end":"2026-08-22T14:36:51.950064Z","steps":["trace[431506951] 'process raft request' (duration: 208.065282ms)","trace[431506951] 'compare' (duration: 623.255295ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:51.950326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:51.117775Z","time spent":"832.461934ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:51.951856Z","caller":"traceutil/trace.go:171","msg":"trace[2026966650] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"787.058362ms","start":"2026-08-22T14:36:51.16471Z","end":"2026-08-22T14:36:51.951769Z","steps":["trace[2026966650] 'read index received' (duration: 161.167941ms)","trace[2026966650] 'applied index is now lower than readState.Index' (duration: 625.888031ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:51.952113Z","caller":"traceutil/trace.go:171","msg":"trace[1617670505] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"763.143601ms","start":"2026-08-22T14:36:51.188934Z","end":"2026-08-22T14:36:51.952077Z","steps":["trace[1617670505] 'process raft request' (duration: 762.166599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:51.952289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:51.188909Z","time spent":"763.246515ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:51.953468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.928108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:36:51.953504Z","caller":"traceutil/trace.go:171","msg":"trace[1059893770] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"833.943062ms","start":"2026-08-22T14:36:51.119513Z","end":"2026-08-22T14:36:51.953456Z","steps":["trace[1059893770] 'process raft request' (duration: 830.045245ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:51.953582Z","caller":"traceutil/trace.go:171","msg":"trace[332975002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"443.172617ms","start":"2026-08-22T14:36:51.510381Z","end":"2026-08-22T14:36:51.953553Z","steps":["trace[332975002] 'agreement among raft nodes before linearized reading' (duration: 442.094991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:51.953638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:51.119465Z","time spent":"834.107308ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:51.953661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:51.510354Z","time spent":"443.28855ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:36:51.953674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.963545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:51.953787Z","caller":"traceutil/trace.go:171","msg":"trace[1894715517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"789.103079ms","start":"2026-08-22T14:36:51.164645Z","end":"2026-08-22T14:36:51.953748Z","steps":["trace[1894715517] 'agreement among raft nodes before linearized reading' (duration: 788.126417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:51.953834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:51.164621Z","time spent":"789.201262ms","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:36:57.510539Z","caller":"traceutil/trace.go:171","msg":"trace[613742693] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"175.189678ms","start":"2026-08-22T14:36:57.335319Z","end":"2026-08-22T14:36:57.510508Z","steps":["trace[613742693] 'read index received' (duration: 173.689859ms)","trace[613742693] 'applied index is now lower than readState.Index' (duration: 1.498079ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:57.510618Z","caller":"traceutil/trace.go:171","msg":"trace[1265851344] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"280.107363ms","start":"2026-08-22T14:36:57.230458Z","end":"2026-08-22T14:36:57.510565Z","steps":["trace[1265851344] 'process raft request' (duration: 279.257816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:57.510787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.453737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xxp94\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-22T14:36:57.510832Z","caller":"traceutil/trace.go:171","msg":"trace[877559427] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xxp94; range_end:; response_count:1; response_revision:624; }","duration":"175.565591ms","start":"2026-08-22T14:36:57.335256Z","end":"2026-08-22T14:36:57.510821Z","steps":["trace[877559427] 'agreement among raft nodes before linearized reading' (duration: 175.362104ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:58.05018Z","caller":"traceutil/trace.go:171","msg":"trace[679509547] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"467.130438ms","start":"2026-08-22T14:36:57.583025Z","end":"2026-08-22T14:36:58.050155Z","steps":["trace[679509547] 'read index received' (duration: 431.671331ms)","trace[679509547] 'applied index is now lower than readState.Index' (duration: 35.458097ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:58.050239Z","caller":"traceutil/trace.go:171","msg":"trace[1662455748] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"520.196011ms","start":"2026-08-22T14:36:57.529985Z","end":"2026-08-22T14:36:58.050181Z","steps":["trace[1662455748] 'process raft request' (duration: 484.701992ms)","trace[1662455748] 'compare' (duration: 35.241391ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:58.050351Z","caller":"traceutil/trace.go:171","msg":"trace[1778887403] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"193.463005ms","start":"2026-08-22T14:36:57.85686Z","end":"2026-08-22T14:36:58.050323Z","steps":["trace[1778887403] 'process raft request' (duration: 193.237868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:58.050504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:57.52996Z","time spent":"520.413438ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:58.050587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.547542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:58.050691Z","caller":"traceutil/trace.go:171","msg":"trace[50380316] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:626; }","duration":"467.727508ms","start":"2026-08-22T14:36:57.582936Z","end":"2026-08-22T14:36:58.050664Z","steps":["trace[50380316] 'agreement among raft nodes before linearized reading' (duration: 467.318025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:58.050759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:57.582895Z","time spent":"467.849443ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-22T14:36:59.429941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515693143865488,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:36:59.889593Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.402204713s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-22T14:36:59.8911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722969654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:59.891198Z","caller":"traceutil/trace.go:171","msg":"trace[887126852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.723173611s","start":"2026-08-22T14:36:58.168Z","end":"2026-08-22T14:36:59.891174Z","steps":["trace[887126852] 'range keys from in-memory index tree' (duration: 1.722682584s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:59.891273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:58.167944Z","time spent":"1.723319135s","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:36:59.892776Z","caller":"traceutil/trace.go:171","msg":"trace[757287194] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"963.905805ms","start":"2026-08-22T14:36:58.928849Z","end":"2026-08-22T14:36:59.892754Z","steps":["trace[757287194] 'read index received' (duration: 961.00299ms)","trace[757287194] 'applied index is now lower than readState.Index' (duration: 2.901205ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:59.892929Z","caller":"traceutil/trace.go:171","msg":"trace[1732759618] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.406372059s","start":"2026-08-22T14:36:58.486519Z","end":"2026-08-22T14:36:59.892891Z","steps":["trace[1732759618] 'process raft request' (duration: 1.403592388s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:59.893011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.164194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-22T14:36:59.893058Z","caller":"traceutil/trace.go:171","msg":"trace[1813740208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"964.247696ms","start":"2026-08-22T14:36:58.928791Z","end":"2026-08-22T14:36:59.893039Z","steps":["trace[1813740208] 'agreement among raft nodes before linearized reading' (duration: 964.085511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:59.893091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:58.928749Z","time spent":"964.335109ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:36:59.893161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:58.486496Z","time spent":"1.406524034s","remote":"127.0.0.1:49414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:59.893153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.627769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-22T14:36:59.893268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.699441ms","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-08-22T14:36:59.893602Z","caller":"traceutil/trace.go:171","msg":"trace[1846900898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"961.058432ms","start":"2026-08-22T14:36:58.932518Z","end":"2026-08-22T14:36:59.893577Z","steps":["trace[1846900898] 'agreement among raft nodes before linearized reading' (duration: 960.596577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:59.893653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:58.932507Z","time spent":"961.136645ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T14:36:59.893529Z","caller":"traceutil/trace.go:171","msg":"trace[236495515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"960.813935ms","start":"2026-08-22T14:36:58.932478Z","end":"2026-08-22T14:36:59.893292Z","steps":["trace[236495515] 'agreement among raft nodes before linearized reading' (duration: 960.454273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:59.893883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:58.932441Z","time spent":"961.417684ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:37:01.770934Z","caller":"traceutil/trace.go:171","msg":"trace[1665648952] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"210.605615ms","start":"2026-08-22T14:37:01.560303Z","end":"2026-08-22T14:37:01.770908Z","steps":["trace[1665648952] 'process raft request' (duration: 210.4757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:15.248887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.595721ms","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-08-22T14:37:15.24902Z","caller":"traceutil/trace.go:171","msg":"trace[1820382858] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:795; }","duration":"687.803318ms","start":"2026-08-22T14:37:14.561187Z","end":"2026-08-22T14:37:15.248991Z","steps":["trace[1820382858] 'count revisions from in-memory index tree' (duration: 687.496528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:15.249104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:14.561164Z","time spent":"687.90148ms","remote":"127.0.0.1:49596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-08-22T14:37:15.250709Z","caller":"traceutil/trace.go:171","msg":"trace[490386441] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"134.842359ms","start":"2026-08-22T14:37:15.115842Z","end":"2026-08-22T14:37:15.250685Z","steps":["trace[490386441] 'process raft request' (duration: 134.672793ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:15.446814Z","caller":"traceutil/trace.go:171","msg":"trace[1172926658] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"187.474813ms","start":"2026-08-22T14:37:15.259303Z","end":"2026-08-22T14:37:15.446777Z","steps":["trace[1172926658] 'read index received' (duration: 137.61403ms)","trace[1172926658] 'applied index is now lower than readState.Index' (duration: 49.859103ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:15.446966Z","caller":"traceutil/trace.go:171","msg":"trace[729960314] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"190.556614ms","start":"2026-08-22T14:37:15.256362Z","end":"2026-08-22T14:37:15.446919Z","steps":["trace[729960314] 'process raft request' (duration: 140.527325ms)","trace[729960314] 'compare' (duration: 49.679878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:15.447006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.665599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:15.447188Z","caller":"traceutil/trace.go:171","msg":"trace[1560300293] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:797; }","duration":"187.851855ms","start":"2026-08-22T14:37:15.259289Z","end":"2026-08-22T14:37:15.447141Z","steps":["trace[1560300293] 'agreement among raft nodes before linearized reading' (duration: 187.622108ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:07.93189Z","caller":"traceutil/trace.go:171","msg":"trace[928762327] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"168.311886ms","start":"2026-08-22T14:38:07.763548Z","end":"2026-08-22T14:38:07.93186Z","steps":["trace[928762327] 'process raft request' (duration: 168.115709ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:31.723013Z","caller":"traceutil/trace.go:171","msg":"trace[1856229026] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"136.892076ms","start":"2026-08-22T14:38:31.586087Z","end":"2026-08-22T14:38:31.722979Z","steps":["trace[1856229026] 'process raft request' (duration: 136.70343ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:31.991162Z","caller":"traceutil/trace.go:171","msg":"trace[1121639141] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"108.671057ms","start":"2026-08-22T14:38:31.882458Z","end":"2026-08-22T14:38:31.991129Z","steps":["trace[1121639141] 'process raft request' (duration: 108.487031ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:32.669407Z","caller":"traceutil/trace.go:171","msg":"trace[1306582869] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"249.930654ms","start":"2026-08-22T14:38:32.41944Z","end":"2026-08-22T14:38:32.66937Z","steps":["trace[1306582869] 'read index received' (duration: 249.693486ms)","trace[1306582869] 'applied index is now lower than readState.Index' (duration: 236.228µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:38:32.669589Z","caller":"traceutil/trace.go:171","msg":"trace[472474576] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"453.216941ms","start":"2026-08-22T14:38:32.216349Z","end":"2026-08-22T14:38:32.669566Z","steps":["trace[472474576] 'process raft request' (duration: 452.860699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:32.669769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:32.216323Z","time spent":"453.317084ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:38:32.669885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.229104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:32.669983Z","caller":"traceutil/trace.go:171","msg":"trace[1812729759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1867; }","duration":"167.366777ms","start":"2026-08-22T14:38:32.502588Z","end":"2026-08-22T14:38:32.669955Z","steps":["trace[1812729759] 'agreement among raft nodes before linearized reading' (duration: 167.221672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:32.670014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.379354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:38:32.670125Z","caller":"traceutil/trace.go:171","msg":"trace[1646371699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"142.527049ms","start":"2026-08-22T14:38:32.527575Z","end":"2026-08-22T14:38:32.670102Z","steps":["trace[1646371699] 'agreement among raft nodes before linearized reading' (duration: 142.206839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:32.670127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.754681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-22T14:38:32.670109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.864692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-22T14:38:32.670207Z","caller":"traceutil/trace.go:171","msg":"trace[1542306567] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"128.861474ms","start":"2026-08-22T14:38:32.541319Z","end":"2026-08-22T14:38:32.67018Z","steps":["trace[1542306567] 'agreement among raft nodes before linearized reading' (duration: 128.629566ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:32.67022Z","caller":"traceutil/trace.go:171","msg":"trace[328920767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1867; }","duration":"250.012857ms","start":"2026-08-22T14:38:32.420182Z","end":"2026-08-22T14:38:32.670195Z","steps":["trace[328920767] 'agreement among raft nodes before linearized reading' (duration: 249.701027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:32.670316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.783479ms","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-08-22T14:38:32.670403Z","caller":"traceutil/trace.go:171","msg":"trace[1872456201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"250.450751ms","start":"2026-08-22T14:38:32.419938Z","end":"2026-08-22T14:38:32.670389Z","steps":["trace[1872456201] 'agreement among raft nodes before linearized reading' (duration: 249.686126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:32.670764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.32917ms","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-08-22T14:38:32.670814Z","caller":"traceutil/trace.go:171","msg":"trace[2133519439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1867; }","duration":"251.431574ms","start":"2026-08-22T14:38:32.419369Z","end":"2026-08-22T14:38:32.670801Z","steps":["trace[2133519439] 'agreement among raft nodes before linearized reading' (duration: 251.30705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:41.355468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.215665ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T14:38:41.355622Z","caller":"traceutil/trace.go:171","msg":"trace[1223818314] linearizableReadLoop","detail":"{readStateIndex:2028; appliedIndex:2027; }","duration":"333.206375ms","start":"2026-08-22T14:38:41.022388Z","end":"2026-08-22T14:38:41.355595Z","steps":["trace[1223818314] 'read index received' (duration: 19.852086ms)","trace[1223818314] 'applied index is now lower than readState.Index' (duration: 313.352429ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:38:41.355753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.35306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:41.355787Z","caller":"traceutil/trace.go:171","msg":"trace[1581137717] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1954; }","duration":"333.401742ms","start":"2026-08-22T14:38:41.022371Z","end":"2026-08-22T14:38:41.355773Z","steps":["trace[1581137717] 'agreement among raft nodes before linearized reading' (duration: 333.314589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:41.355842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:41.022319Z","time spent":"333.510674ms","remote":"127.0.0.1:46944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-08-22T14:38:41.436966Z","caller":"traceutil/trace.go:171","msg":"trace[1138457572] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"193.600311ms","start":"2026-08-22T14:38:41.243343Z","end":"2026-08-22T14:38:41.436944Z","steps":["trace[1138457572] 'process raft request' (duration: 193.307342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:41.436981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.070067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:41.437071Z","caller":"traceutil/trace.go:171","msg":"trace[1517143254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1955; }","duration":"273.234883ms","start":"2026-08-22T14:38:41.163816Z","end":"2026-08-22T14:38:41.437051Z","steps":["trace[1517143254] 'agreement among raft nodes before linearized reading' (duration: 273.063567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:45.911498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.666791ms","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-08-22T14:38:45.911663Z","caller":"traceutil/trace.go:171","msg":"trace[1306179993] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1980; }","duration":"255.873238ms","start":"2026-08-22T14:38:45.655767Z","end":"2026-08-22T14:38:45.91164Z","steps":["trace[1306179993] 'range keys from in-memory index tree' (duration: 255.64313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:45.911701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.655452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-08-22T14:38:45.911768Z","caller":"traceutil/trace.go:171","msg":"trace[293532460] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1980; }","duration":"236.758716ms","start":"2026-08-22T14:38:45.674985Z","end":"2026-08-22T14:38:45.911744Z","steps":["trace[293532460] 'range keys from in-memory index tree' (duration: 236.509418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:45.911577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.360473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T14:38:45.911819Z","caller":"traceutil/trace.go:171","msg":"trace[1566501610] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2054; }","duration":"126.077933ms","start":"2026-08-22T14:38:45.785708Z","end":"2026-08-22T14:38:45.911785Z","steps":["trace[1566501610] 'read index received' (duration: 82.765604ms)","trace[1566501610] 'applied index is now lower than readState.Index' (duration: 43.310689ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:38:45.911906Z","caller":"traceutil/trace.go:171","msg":"trace[952304077] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1980; }","duration":"236.756756ms","start":"2026-08-22T14:38:45.675127Z","end":"2026-08-22T14:38:45.911884Z","steps":["trace[952304077] 'range keys from in-memory index tree' (duration: 236.128825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:45.912119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.379863ms","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-08-22T14:38:45.912161Z","caller":"traceutil/trace.go:171","msg":"trace[647536885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1981; }","duration":"126.465146ms","start":"2026-08-22T14:38:45.78568Z","end":"2026-08-22T14:38:45.912145Z","steps":["trace[647536885] 'agreement among raft nodes before linearized reading' (duration: 126.218608ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:45.912153Z","caller":"traceutil/trace.go:171","msg":"trace[1375818637] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"139.079317ms","start":"2026-08-22T14:38:45.773022Z","end":"2026-08-22T14:38:45.912101Z","steps":["trace[1375818637] 'process raft request' (duration: 95.443857ms)","trace[1375818637] 'compare' (duration: 43.070571ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:17.554321Z","caller":"traceutil/trace.go:171","msg":"trace[436703350] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"108.644016ms","start":"2026-08-22T14:39:17.445647Z","end":"2026-08-22T14:39:17.554291Z","steps":["trace[436703350] 'process raft request' (duration: 108.45764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:18.530947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515693143868624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:39:19.031678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515693143868624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:39:19.219158Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.190627042s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:39:19.219594Z","caller":"traceutil/trace.go:171","msg":"trace[124010181] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"1.19121426s","start":"2026-08-22T14:39:18.028352Z","end":"2026-08-22T14:39:19.219566Z","steps":["trace[124010181] 'process raft request' (duration: 1.191054015s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:19.219741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.028321Z","time spent":"1.191334984s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:39:20.024411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.462418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:39:20.024748Z","caller":"traceutil/trace.go:171","msg":"trace[1663667075] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"443.341138ms","start":"2026-08-22T14:39:19.581379Z","end":"2026-08-22T14:39:20.02472Z","steps":["trace[1663667075] 'process raft request' (duration: 443.262836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.024868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:19.581342Z","time spent":"443.472413ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:39:20.025043Z","caller":"traceutil/trace.go:171","msg":"trace[800492210] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"918.580687ms","start":"2026-08-22T14:39:19.106443Z","end":"2026-08-22T14:39:20.025023Z","steps":["trace[800492210] 'process raft request' (duration: 918.105482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.025138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:19.106394Z","time spent":"918.69545ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:39:20.025162Z","caller":"traceutil/trace.go:171","msg":"trace[1463692289] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"1.051509013s","start":"2026-08-22T14:39:18.97362Z","end":"2026-08-22T14:39:20.025129Z","steps":["trace[1463692289] 'process raft request' (duration: 813.158466ms)","trace[1463692289] 'compare' (duration: 237.310114ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:20.025139Z","caller":"traceutil/trace.go:171","msg":"trace[515980617] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2251; }","duration":"1.995033762s","start":"2026-08-22T14:39:18.030074Z","end":"2026-08-22T14:39:20.025108Z","steps":["trace[515980617] 'read index received' (duration: 1.189339729s)","trace[515980617] 'applied index is now lower than readState.Index' (duration: 805.690783ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:39:20.025253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995165097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T14:39:20.025276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.973603Z","time spent":"1.051613036s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:39:20.025303Z","caller":"traceutil/trace.go:171","msg":"trace[45212530] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2174; }","duration":"1.995242639s","start":"2026-08-22T14:39:18.030049Z","end":"2026-08-22T14:39:20.025291Z","steps":["trace[45212530] 'agreement among raft nodes before linearized reading' (duration: 1.995132875s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.02534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.030037Z","time spent":"1.995292321s","remote":"127.0.0.1:49414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:39:20.025393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862652403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:39:20.025476Z","caller":"traceutil/trace.go:171","msg":"trace[2072489344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2174; }","duration":"1.862774668s","start":"2026-08-22T14:39:18.162674Z","end":"2026-08-22T14:39:20.025449Z","steps":["trace[2072489344] 'agreement among raft nodes before linearized reading' (duration: 1.862650024s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.025532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.162633Z","time spent":"1.862885281s","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:39:20.025536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569854254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-22T14:39:20.025576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058826421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-22T14:39:20.025596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066519611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-22T14:39:20.025671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.922258ms","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-08-22T14:39:20.025716Z","caller":"traceutil/trace.go:171","msg":"trace[1026157256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2174; }","duration":"1.059047798s","start":"2026-08-22T14:39:18.96663Z","end":"2026-08-22T14:39:20.025677Z","steps":["trace[1026157256] 'agreement among raft nodes before linearized reading' (duration: 1.058703047s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.025792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.96659Z","time spent":"1.059188313s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:39:20.025793Z","caller":"traceutil/trace.go:171","msg":"trace[1236835209] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2174; }","duration":"330.105313ms","start":"2026-08-22T14:39:19.695655Z","end":"2026-08-22T14:39:20.025761Z","steps":["trace[1236835209] 'agreement among raft nodes before linearized reading' (duration: 329.682149ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:20.025716Z","caller":"traceutil/trace.go:171","msg":"trace[2115426469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2174; }","duration":"1.066738349s","start":"2026-08-22T14:39:18.958952Z","end":"2026-08-22T14:39:20.02569Z","steps":["trace[2115426469] 'agreement among raft nodes before linearized reading' (duration: 1.066389787s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.025867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:19.695629Z","time spent":"330.214627ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-22T14:39:20.025907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.958925Z","time spent":"1.066959436s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:39:20.025884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829238766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-22T14:39:20.025579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059012448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-22T14:39:20.026014Z","caller":"traceutil/trace.go:171","msg":"trace[1445136709] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2174; }","duration":"1.829413322s","start":"2026-08-22T14:39:18.196569Z","end":"2026-08-22T14:39:20.025982Z","steps":["trace[1445136709] 'agreement among raft nodes before linearized reading' (duration: 1.829006929s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:20.025627Z","caller":"traceutil/trace.go:171","msg":"trace[86066579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2174; }","duration":"1.569997148s","start":"2026-08-22T14:39:18.455617Z","end":"2026-08-22T14:39:20.025614Z","steps":["trace[86066579] 'agreement among raft nodes before linearized reading' (duration: 1.569763011s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:20.026062Z","caller":"traceutil/trace.go:171","msg":"trace[278856200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2174; }","duration":"1.059581486s","start":"2026-08-22T14:39:18.966451Z","end":"2026-08-22T14:39:20.026033Z","steps":["trace[278856200] 'agreement among raft nodes before linearized reading' (duration: 1.058876983s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.02594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059486113s","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-08-22T14:39:20.026136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.455574Z","time spent":"1.570546867s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T14:39:20.026234Z","caller":"traceutil/trace.go:171","msg":"trace[1082721029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2174; }","duration":"1.059872386s","start":"2026-08-22T14:39:18.966309Z","end":"2026-08-22T14:39:20.026182Z","steps":["trace[1082721029] 'agreement among raft nodes before linearized reading' (duration: 1.05908914s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.02615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.966427Z","time spent":"1.05970549s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:39:20.026077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.196555Z","time spent":"1.829512305s","remote":"127.0.0.1:45992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T14:39:20.025585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058818031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-22T14:39:20.026695Z","caller":"traceutil/trace.go:171","msg":"trace[153735082] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2174; }","duration":"1.06001558s","start":"2026-08-22T14:39:18.966653Z","end":"2026-08-22T14:39:20.026669Z","steps":["trace[153735082] 'agreement among raft nodes before linearized reading' (duration: 1.058677276s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:20.026752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.966623Z","time spent":"1.060114473s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T14:39:20.026708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:39:18.96622Z","time spent":"1.060093273s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:40:46.844377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.221061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:40:46.844521Z","caller":"traceutil/trace.go:171","msg":"trace[80446651] linearizableReadLoop","detail":"{readStateIndex:2944; appliedIndex:2943; }","duration":"308.495412ms","start":"2026-08-22T14:40:46.536006Z","end":"2026-08-22T14:40:46.844501Z","steps":["trace[80446651] 'read index received' (duration: 1.9127ms)","trace[80446651] 'applied index is now lower than readState.Index' (duration: 306.580822ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:46.844632Z","caller":"traceutil/trace.go:171","msg":"trace[1273867874] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"437.726097ms","start":"2026-08-22T14:40:46.406893Z","end":"2026-08-22T14:40:46.844619Z","steps":["trace[1273867874] 'process raft request' (duration: 131.082483ms)","trace[1273867874] 'compare' (duration: 305.89363ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:46.844707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:46.406879Z","time spent":"437.793239ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:40:46.844981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.375404ms","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-08-22T14:40:46.8451Z","caller":"traceutil/trace.go:171","msg":"trace[1097963677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2837; }","duration":"252.56276ms","start":"2026-08-22T14:40:46.592507Z","end":"2026-08-22T14:40:46.845069Z","steps":["trace[1097963677] 'agreement among raft nodes before linearized reading' (duration: 252.205029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:46.845224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.211036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T14:40:46.845275Z","caller":"traceutil/trace.go:171","msg":"trace[1470254564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2837; }","duration":"309.31923ms","start":"2026-08-22T14:40:46.53594Z","end":"2026-08-22T14:40:46.845259Z","steps":["trace[1470254564] 'agreement among raft nodes before linearized reading' (duration: 309.179375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:46.845285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.963023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-22T14:40:46.845312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:46.535916Z","time spent":"309.386851ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T14:40:46.845331Z","caller":"traceutil/trace.go:171","msg":"trace[1748479586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2837; }","duration":"223.067556ms","start":"2026-08-22T14:40:46.622252Z","end":"2026-08-22T14:40:46.845319Z","steps":["trace[1748479586] 'agreement among raft nodes before linearized reading' (duration: 222.86541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:46.845416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.436638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T14:40:46.845546Z","caller":"traceutil/trace.go:171","msg":"trace[382830714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2837; }","duration":"229.639635ms","start":"2026-08-22T14:40:46.615878Z","end":"2026-08-22T14:40:46.845517Z","steps":["trace[382830714] 'agreement among raft nodes before linearized reading' (duration: 229.260063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:46.845526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.26681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T14:40:46.845416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.996232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-22T14:40:46.845652Z","caller":"traceutil/trace.go:171","msg":"trace[1612320466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2837; }","duration":"245.451236ms","start":"2026-08-22T14:40:46.60017Z","end":"2026-08-22T14:40:46.845621Z","steps":["trace[1612320466] 'agreement among raft nodes before linearized reading' (duration: 245.006132ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:46.845816Z","caller":"traceutil/trace.go:171","msg":"trace[1365811306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2837; }","duration":"222.357634ms","start":"2026-08-22T14:40:46.623352Z","end":"2026-08-22T14:40:46.845709Z","steps":["trace[1365811306] 'agreement among raft nodes before linearized reading' (duration: 221.826437ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:22.743006Z","caller":"traceutil/trace.go:171","msg":"trace[1166780582] transaction","detail":"{read_only:false; response_revision:3861; number_of_response:1; }","duration":"124.361045ms","start":"2026-08-22T14:43:22.618624Z","end":"2026-08-22T14:43:22.742985Z","steps":["trace[1166780582] 'process raft request' (duration: 124.22278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:44:47.346044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.790278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:44:47.346171Z","caller":"traceutil/trace.go:171","msg":"trace[833624481] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4891; }","duration":"214.994784ms","start":"2026-08-22T14:44:47.13115Z","end":"2026-08-22T14:44:47.346144Z","steps":["trace[833624481] 'count revisions from in-memory index tree' (duration: 214.679794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:44:47.346188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.760226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-904d657e-0cf7-414b-bf94-8a691606531e\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-08-22T14:44:47.34623Z","caller":"traceutil/trace.go:171","msg":"trace[1568659856] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-904d657e-0cf7-414b-bf94-8a691606531e; range_end:; response_count:1; response_revision:4891; }","duration":"126.838068ms","start":"2026-08-22T14:44:47.219377Z","end":"2026-08-22T14:44:47.346216Z","steps":["trace[1568659856] 'range keys from in-memory index tree' (duration: 126.324062ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:51.674523Z","caller":"traceutil/trace.go:171","msg":"trace[1731303632] linearizableReadLoop","detail":"{readStateIndex:5259; appliedIndex:5258; }","duration":"227.773998ms","start":"2026-08-22T14:44:51.446717Z","end":"2026-08-22T14:44:51.674491Z","steps":["trace[1731303632] 'read index received' (duration: 227.568411ms)","trace[1731303632] 'applied index is now lower than readState.Index' (duration: 204.157µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:44:51.674663Z","caller":"traceutil/trace.go:171","msg":"trace[1067533462] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"234.93974ms","start":"2026-08-22T14:44:51.439712Z","end":"2026-08-22T14:44:51.674651Z","steps":["trace[1067533462] 'process raft request' (duration: 234.586559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:44:51.674798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.054478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:44:51.674892Z","caller":"traceutil/trace.go:171","msg":"trace[806481795] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5069; }","duration":"228.187981ms","start":"2026-08-22T14:44:51.446683Z","end":"2026-08-22T14:44:51.674871Z","steps":["trace[806481795] 'agreement among raft nodes before linearized reading' (duration: 227.977364ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:51.680763Z","caller":"traceutil/trace.go:171","msg":"trace[2130120430] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"204.323369ms","start":"2026-08-22T14:44:51.476397Z","end":"2026-08-22T14:44:51.68072Z","steps":["trace[2130120430] 'process raft request' (duration: 204.0525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:44:51.680907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.266016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8c23e0d-9583-42eb-9987-f07b8f95459b\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-08-22T14:44:51.680994Z","caller":"traceutil/trace.go:171","msg":"trace[1341557052] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8c23e0d-9583-42eb-9987-f07b8f95459b; range_end:; response_count:1; response_revision:5070; }","duration":"212.467662ms","start":"2026-08-22T14:44:51.468504Z","end":"2026-08-22T14:44:51.680972Z","steps":["trace[1341557052] 'agreement among raft nodes before linearized reading' (duration: 212.125351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:44:51.681037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.614434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-xgq9z-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-08-22T14:44:51.681144Z","caller":"traceutil/trace.go:171","msg":"trace[1724955099] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-xgq9z-tls; range_end:; response_count:1; response_revision:5070; }","duration":"198.760619ms","start":"2026-08-22T14:44:51.482358Z","end":"2026-08-22T14:44:51.681118Z","steps":["trace[1724955099] 'agreement among raft nodes before linearized reading' (duration: 198.428858ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:57.255154Z","caller":"traceutil/trace.go:171","msg":"trace[1178142655] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"139.311472ms","start":"2026-08-22T14:44:57.115814Z","end":"2026-08-22T14:44:57.255126Z","steps":["trace[1178142655] 'process raft request' (duration: 139.106626ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:57.255418Z","caller":"traceutil/trace.go:171","msg":"trace[1328159059] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"138.285009ms","start":"2026-08-22T14:44:57.117116Z","end":"2026-08-22T14:44:57.255401Z","steps":["trace[1328159059] 'process raft request' (duration: 137.950678ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:57.255591Z","caller":"traceutil/trace.go:171","msg":"trace[856751782] linearizableReadLoop","detail":"{readStateIndex:5392; appliedIndex:5387; }","duration":"122.57042ms","start":"2026-08-22T14:44:57.132991Z","end":"2026-08-22T14:44:57.255561Z","steps":["trace[856751782] 'read index received' (duration: 6.568963ms)","trace[856751782] 'applied index is now lower than readState.Index' (duration: 116.000017ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:44:57.255753Z","caller":"traceutil/trace.go:171","msg":"trace[1341032455] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"137.05212ms","start":"2026-08-22T14:44:57.118671Z","end":"2026-08-22T14:44:57.255723Z","steps":["trace[1341032455] 'process raft request' (duration: 136.645066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:44:57.255993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.962494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-22T14:44:57.25612Z","caller":"traceutil/trace.go:171","msg":"trace[1408156857] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5197; }","duration":"123.137749ms","start":"2026-08-22T14:44:57.132944Z","end":"2026-08-22T14:44:57.256082Z","steps":["trace[1408156857] 'agreement among raft nodes before linearized reading' (duration: 122.729446ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:57.255615Z","caller":"traceutil/trace.go:171","msg":"trace[1228776112] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"129.794004ms","start":"2026-08-22T14:44:57.12579Z","end":"2026-08-22T14:44:57.255584Z","steps":["trace[1228776112] 'process raft request' (duration: 129.684411ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:04.163417Z","caller":"traceutil/trace.go:171","msg":"trace[994558037] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"286.684767ms","start":"2026-08-22T14:45:03.876682Z","end":"2026-08-22T14:45:04.163367Z","steps":["trace[994558037] 'process raft request' (duration: 256.862671ms)","trace[994558037] 'compare' (duration: 29.358781ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:45:04.169793Z","caller":"traceutil/trace.go:171","msg":"trace[1027575065] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"233.67468ms","start":"2026-08-22T14:45:03.936081Z","end":"2026-08-22T14:45:04.169756Z","steps":["trace[1027575065] 'process raft request' (duration: 233.457743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.661597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.647189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:45:04.661865Z","caller":"traceutil/trace.go:171","msg":"trace[898971558] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"488.614187ms","start":"2026-08-22T14:45:04.173172Z","end":"2026-08-22T14:45:04.661787Z","steps":["trace[898971558] 'process raft request' (duration: 379.682068ms)","trace[898971558] 'compare' (duration: 108.242286ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:45:04.662101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:45:04.173147Z","time spent":"488.842754ms","remote":"127.0.0.1:49384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":908,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:45:04.662163Z","caller":"traceutil/trace.go:171","msg":"trace[1394441661] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"480.06702ms","start":"2026-08-22T14:45:04.182074Z","end":"2026-08-22T14:45:04.662141Z","steps":["trace[1394441661] 'process raft request' (duration: 479.75844ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:04.661898Z","caller":"traceutil/trace.go:171","msg":"trace[540127121] linearizableReadLoop","detail":"{readStateIndex:5540; appliedIndex:5539; }","duration":"484.148062ms","start":"2026-08-22T14:45:04.177714Z","end":"2026-08-22T14:45:04.661862Z","steps":["trace[540127121] 'read index received' (duration: 375.147942ms)","trace[540127121] 'applied index is now lower than readState.Index' (duration: 108.99653ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:45:04.662242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:45:04.182064Z","time spent":"480.146503ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1005,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:45:04.662243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.898976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-rvlww\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-08-22T14:45:04.662306Z","caller":"traceutil/trace.go:171","msg":"trace[1640170568] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-rvlww; range_end:; response_count:1; response_revision:5345; }","duration":"489.00517ms","start":"2026-08-22T14:45:04.173285Z","end":"2026-08-22T14:45:04.66229Z","steps":["trace[1640170568] 'agreement among raft nodes before linearized reading' (duration: 488.678079ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:04.662257Z","caller":"traceutil/trace.go:171","msg":"trace[2092523945] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"480.496604ms","start":"2026-08-22T14:45:04.181682Z","end":"2026-08-22T14:45:04.662178Z","steps":["trace[2092523945] 'process raft request' (duration: 480.05212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.662333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:45:04.173273Z","time spent":"489.052331ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7840,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-rvlww\" "} {"level":"warn","ts":"2026-08-22T14:45:04.662475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:45:04.181662Z","time spent":"480.705761ms","remote":"127.0.0.1:49494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":524,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:45:04.662598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.519117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-22T14:45:04.662718Z","caller":"traceutil/trace.go:171","msg":"trace[189878748] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5345; }","duration":"481.683863ms","start":"2026-08-22T14:45:04.181Z","end":"2026-08-22T14:45:04.662684Z","steps":["trace[189878748] 'agreement among raft nodes before linearized reading' (duration: 481.345712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.662815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:45:04.180988Z","time spent":"481.809017ms","remote":"127.0.0.1:49478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-08-22T14:45:04.662831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.993509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T14:45:04.662867Z","caller":"traceutil/trace.go:171","msg":"trace[1693379313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5345; }","duration":"213.075952ms","start":"2026-08-22T14:45:04.449778Z","end":"2026-08-22T14:45:04.662854Z","steps":["trace[1693379313] 'agreement among raft nodes before linearized reading' (duration: 212.956408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.662958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.378139ms","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-08-22T14:45:04.663023Z","caller":"traceutil/trace.go:171","msg":"trace[1645046793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5345; }","duration":"187.465102ms","start":"2026-08-22T14:45:04.475537Z","end":"2026-08-22T14:45:04.663002Z","steps":["trace[1645046793] 'agreement among raft nodes before linearized reading' (duration: 187.337368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.663047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.053954ms","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-08-22T14:45:04.663113Z","caller":"traceutil/trace.go:171","msg":"trace[908572985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5345; }","duration":"193.134336ms","start":"2026-08-22T14:45:04.469963Z","end":"2026-08-22T14:45:04.663097Z","steps":["trace[908572985] 'agreement among raft nodes before linearized reading' (duration: 192.983011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.663207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.857675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T14:45:04.663261Z","caller":"traceutil/trace.go:171","msg":"trace[2015944221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5345; }","duration":"300.969519ms","start":"2026-08-22T14:45:04.362278Z","end":"2026-08-22T14:45:04.663247Z","steps":["trace[2015944221] 'agreement among raft nodes before linearized reading' (duration: 300.835295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.662819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.68454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T14:45:04.663301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:45:04.362245Z","time spent":"301.048331ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:45:04.663313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.48179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:45:04.663363Z","caller":"traceutil/trace.go:171","msg":"trace[1790316432] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5345; }","duration":"190.553492ms","start":"2026-08-22T14:45:04.472798Z","end":"2026-08-22T14:45:04.663351Z","steps":["trace[1790316432] 'agreement among raft nodes before linearized reading' (duration: 190.47825ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:04.663322Z","caller":"traceutil/trace.go:171","msg":"trace[63778321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5345; }","duration":"184.218287ms","start":"2026-08-22T14:45:04.479091Z","end":"2026-08-22T14:45:04.663309Z","steps":["trace[63778321] 'agreement among raft nodes before linearized reading' (duration: 183.581506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.663434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.42205ms","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-08-22T14:45:04.663467Z","caller":"traceutil/trace.go:171","msg":"trace[919881928] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5345; }","duration":"387.524263ms","start":"2026-08-22T14:45:04.275934Z","end":"2026-08-22T14:45:04.663458Z","steps":["trace[919881928] 'agreement among raft nodes before linearized reading' (duration: 387.411959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:04.663506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:45:04.275907Z","time spent":"387.592735ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-22T14:45:04.66527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.737766ms","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-08-22T14:45:04.665321Z","caller":"traceutil/trace.go:171","msg":"trace[459985479] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5345; }","duration":"193.851179ms","start":"2026-08-22T14:45:04.471457Z","end":"2026-08-22T14:45:04.665308Z","steps":["trace[459985479] 'agreement among raft nodes before linearized reading' (duration: 191.209843ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:05.34688Z","caller":"traceutil/trace.go:171","msg":"trace[158720943] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"161.110628ms","start":"2026-08-22T14:45:05.185738Z","end":"2026-08-22T14:45:05.346849Z","steps":["trace[158720943] 'process raft request' (duration: 160.306532ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:05.358733Z","caller":"traceutil/trace.go:171","msg":"trace[2006662611] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"167.993742ms","start":"2026-08-22T14:45:05.190704Z","end":"2026-08-22T14:45:05.358698Z","steps":["trace[2006662611] 'process raft request' (duration: 167.826817ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:12.508031Z","caller":"traceutil/trace.go:171","msg":"trace[2045602478] linearizableReadLoop","detail":"{readStateIndex:5756; appliedIndex:5755; }","duration":"213.887408ms","start":"2026-08-22T14:45:12.294108Z","end":"2026-08-22T14:45:12.507996Z","steps":["trace[2045602478] 'read index received' (duration: 213.537097ms)","trace[2045602478] 'applied index is now lower than readState.Index' (duration: 349.281µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:45:12.508078Z","caller":"traceutil/trace.go:171","msg":"trace[1969937943] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"219.324564ms","start":"2026-08-22T14:45:12.28871Z","end":"2026-08-22T14:45:12.508035Z","steps":["trace[1969937943] 'process raft request' (duration: 219.035585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:45:12.508357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.206808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-22T14:45:12.508407Z","caller":"traceutil/trace.go:171","msg":"trace[433604698] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5551; }","duration":"214.300822ms","start":"2026-08-22T14:45:12.294088Z","end":"2026-08-22T14:45:12.508388Z","steps":["trace[433604698] 'agreement among raft nodes before linearized reading' (duration: 214.042193ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:46:12.283133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-08-22T14:46:12.35972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"74.028877ms","hash":4053628258,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":33828864,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T14:46:12.359801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4053628258,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-08-22T14:51:12.290554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6057} {"level":"info","ts":"2026-08-22T14:51:12.394325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6057,"took":"101.037912ms","hash":259014510,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":23748608,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-22T14:51:12.394416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":259014510,"revision":6057,"compact-revision":2979} {"level":"info","ts":"2026-08-22T14:51:27.112485Z","caller":"traceutil/trace.go:171","msg":"trace[522219303] linearizableReadLoop","detail":"{readStateIndex:8659; appliedIndex:8658; }","duration":"308.983717ms","start":"2026-08-22T14:51:26.80347Z","end":"2026-08-22T14:51:27.112454Z","steps":["trace[522219303] 'read index received' (duration: 308.544803ms)","trace[522219303] 'applied index is now lower than readState.Index' (duration: 437.304µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:51:27.112603Z","caller":"traceutil/trace.go:171","msg":"trace[1578166715] transaction","detail":"{read_only:false; response_revision:8360; number_of_response:1; }","duration":"317.555885ms","start":"2026-08-22T14:51:26.794999Z","end":"2026-08-22T14:51:27.112555Z","steps":["trace[1578166715] 'process raft request' (duration: 317.181383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:27.112784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.296373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T14:51:27.112788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.302487ms","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-08-22T14:51:27.112837Z","caller":"traceutil/trace.go:171","msg":"trace[1158194845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8360; }","duration":"198.426737ms","start":"2026-08-22T14:51:26.914396Z","end":"2026-08-22T14:51:27.112823Z","steps":["trace[1158194845] 'agreement among raft nodes before linearized reading' (duration: 198.254292ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:27.112851Z","caller":"traceutil/trace.go:171","msg":"trace[2121085551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8360; }","duration":"309.432532ms","start":"2026-08-22T14:51:26.803402Z","end":"2026-08-22T14:51:27.112835Z","steps":["trace[2121085551] 'agreement among raft nodes before linearized reading' (duration: 309.164453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:27.112865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:26.794984Z","time spent":"317.74293ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:51:27.113072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.317508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T14:51:27.113114Z","caller":"traceutil/trace.go:171","msg":"trace[644736817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8360; }","duration":"191.39312ms","start":"2026-08-22T14:51:26.92171Z","end":"2026-08-22T14:51:27.113103Z","steps":["trace[644736817] 'agreement among raft nodes before linearized reading' (duration: 191.298957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:27.113105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.005906ms","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-08-22T14:51:27.113199Z","caller":"traceutil/trace.go:171","msg":"trace[219461784] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8360; }","duration":"186.11624ms","start":"2026-08-22T14:51:26.92706Z","end":"2026-08-22T14:51:27.113176Z","steps":["trace[219461784] 'agreement among raft nodes before linearized reading' (duration: 185.853391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:27.113325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.465126ms","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-08-22T14:51:27.113404Z","caller":"traceutil/trace.go:171","msg":"trace[280329938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8360; }","duration":"197.604811ms","start":"2026-08-22T14:51:26.915783Z","end":"2026-08-22T14:51:27.113388Z","steps":["trace[280329938] 'agreement among raft nodes before linearized reading' (duration: 197.342802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:27.112889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:26.803379Z","time spent":"309.500724ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:51:55.570235Z","caller":"traceutil/trace.go:171","msg":"trace[752980062] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"304.753471ms","start":"2026-08-22T14:51:55.265454Z","end":"2026-08-22T14:51:55.570208Z","steps":["trace[752980062] 'process raft request' (duration: 304.596336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.570394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.265437Z","time spent":"304.877414ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:51:55.82177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.615098ms","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-08-22T14:51:55.821899Z","caller":"traceutil/trace.go:171","msg":"trace[2023146177] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8564; }","duration":"165.749342ms","start":"2026-08-22T14:51:55.656127Z","end":"2026-08-22T14:51:55.821876Z","steps":["trace[2023146177] 'range keys from in-memory index tree' (duration: 165.602157ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:55.822094Z","caller":"traceutil/trace.go:171","msg":"trace[503872116] linearizableReadLoop","detail":"{readStateIndex:8872; appliedIndex:8869; }","duration":"485.365173ms","start":"2026-08-22T14:51:55.3367Z","end":"2026-08-22T14:51:55.822066Z","steps":["trace[503872116] 'read index received' (duration: 233.397046ms)","trace[503872116] 'applied index is now lower than readState.Index' (duration: 251.967007ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:51:55.822183Z","caller":"traceutil/trace.go:171","msg":"trace[2070884213] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"338.290043ms","start":"2026-08-22T14:51:55.483861Z","end":"2026-08-22T14:51:55.822151Z","steps":["trace[2070884213] 'process raft request' (duration: 338.100867ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:55.822188Z","caller":"traceutil/trace.go:171","msg":"trace[1557072828] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"322.112131ms","start":"2026-08-22T14:51:55.500043Z","end":"2026-08-22T14:51:55.822155Z","steps":["trace[1557072828] 'process raft request' (duration: 321.960047ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:55.822216Z","caller":"traceutil/trace.go:171","msg":"trace[2076854353] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"490.708985ms","start":"2026-08-22T14:51:55.331441Z","end":"2026-08-22T14:51:55.82215Z","steps":["trace[2076854353] 'process raft request' (duration: 490.461887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.8223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.483837Z","time spent":"338.397487ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:51:55.822328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.500029Z","time spent":"322.215474ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:51:55.822327Z","caller":"traceutil/trace.go:171","msg":"trace[1130312125] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"554.910088ms","start":"2026-08-22T14:51:55.267324Z","end":"2026-08-22T14:51:55.822234Z","steps":["trace[1130312125] 'process raft request' (duration: 478.875963ms)","trace[1130312125] 'compare' (duration: 75.367714ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:51:55.822455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.331425Z","time spent":"490.900432ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:51:55.822427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.717225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-22T14:51:55.822531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.100018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:51:55.822562Z","caller":"traceutil/trace.go:171","msg":"trace[1787937110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8568; }","duration":"485.88532ms","start":"2026-08-22T14:51:55.336662Z","end":"2026-08-22T14:51:55.822548Z","steps":["trace[1787937110] 'agreement among raft nodes before linearized reading' (duration: 485.541589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.822648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.267315Z","time spent":"555.178797ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:51:55.822687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.274173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T14:51:55.822718Z","caller":"traceutil/trace.go:171","msg":"trace[2005381949] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:8568; }","duration":"102.370155ms","start":"2026-08-22T14:51:55.720339Z","end":"2026-08-22T14:51:55.822709Z","steps":["trace[2005381949] 'agreement among raft nodes before linearized reading' (duration: 102.251842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.822663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.33664Z","time spent":"486.009483ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:51:55.822711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.921491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-22T14:51:55.822769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.253578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:51:55.822795Z","caller":"traceutil/trace.go:171","msg":"trace[1741765354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8568; }","duration":"486.019844ms","start":"2026-08-22T14:51:55.336753Z","end":"2026-08-22T14:51:55.822773Z","steps":["trace[1741765354] 'agreement among raft nodes before linearized reading' (duration: 485.695954ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:55.822571Z","caller":"traceutil/trace.go:171","msg":"trace[760747348] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8568; }","duration":"421.17221ms","start":"2026-08-22T14:51:55.401384Z","end":"2026-08-22T14:51:55.822556Z","steps":["trace[760747348] 'agreement among raft nodes before linearized reading' (duration: 421.092238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.822821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.550919ms","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-08-22T14:51:55.822804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.556011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:51:55.822875Z","caller":"traceutil/trace.go:171","msg":"trace[1641787376] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8568; }","duration":"485.619301ms","start":"2026-08-22T14:51:55.337241Z","end":"2026-08-22T14:51:55.82286Z","steps":["trace[1641787376] 'agreement among raft nodes before linearized reading' (duration: 485.436175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.82288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.401364Z","time spent":"421.49775ms","remote":"127.0.0.1:60108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:51:55.82285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.336748Z","time spent":"486.089647ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:51:55.822927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.337233Z","time spent":"485.686353ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T14:51:55.822938Z","caller":"traceutil/trace.go:171","msg":"trace[2072534852] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8568; }","duration":"333.769598ms","start":"2026-08-22T14:51:55.489141Z","end":"2026-08-22T14:51:55.82291Z","steps":["trace[2072534852] 'agreement among raft nodes before linearized reading' (duration: 333.547601ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:55.822835Z","caller":"traceutil/trace.go:171","msg":"trace[1850164507] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8568; }","duration":"441.31454ms","start":"2026-08-22T14:51:55.381483Z","end":"2026-08-22T14:51:55.822798Z","steps":["trace[1850164507] 'agreement among raft nodes before linearized reading' (duration: 441.206277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.822977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.956655ms","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-08-22T14:51:55.823137Z","caller":"traceutil/trace.go:171","msg":"trace[373369679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8568; }","duration":"474.188612ms","start":"2026-08-22T14:51:55.348912Z","end":"2026-08-22T14:51:55.823101Z","steps":["trace[373369679] 'agreement among raft nodes before linearized reading' (duration: 473.612093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:55.823401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.348894Z","time spent":"474.305455ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T14:51:55.823056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.381471Z","time spent":"441.558018ms","remote":"127.0.0.1:45928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:51:55.822982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:55.489126Z","time spent":"333.84793ms","remote":"127.0.0.1:46902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-08-22T14:52:01.963496Z","caller":"traceutil/trace.go:171","msg":"trace[1324248042] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"118.769555ms","start":"2026-08-22T14:52:01.844694Z","end":"2026-08-22T14:52:01.963464Z","steps":["trace[1324248042] 'process raft request' (duration: 118.547308ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:01.983519Z","caller":"traceutil/trace.go:171","msg":"trace[1631863095] linearizableReadLoop","detail":"{readStateIndex:8906; appliedIndex:8904; }","duration":"132.599152ms","start":"2026-08-22T14:52:01.850887Z","end":"2026-08-22T14:52:01.983487Z","steps":["trace[1631863095] 'read index received' (duration: 112.42242ms)","trace[1631863095] 'applied index is now lower than readState.Index' (duration: 20.175172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:52:01.98396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.537344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-22T14:52:01.983972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.789537ms","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-08-22T14:52:01.983987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.529232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T14:52:01.984056Z","caller":"traceutil/trace.go:171","msg":"trace[1856898025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8598; }","duration":"125.705739ms","start":"2026-08-22T14:52:01.858326Z","end":"2026-08-22T14:52:01.984032Z","steps":["trace[1856898025] 'agreement among raft nodes before linearized reading' (duration: 125.43351ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:01.984065Z","caller":"traceutil/trace.go:171","msg":"trace[1636490046] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8598; }","duration":"123.926902ms","start":"2026-08-22T14:52:01.860115Z","end":"2026-08-22T14:52:01.984042Z","steps":["trace[1636490046] 'agreement among raft nodes before linearized reading' (duration: 123.677663ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:01.984075Z","caller":"traceutil/trace.go:171","msg":"trace[1073225013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8598; }","duration":"120.652116ms","start":"2026-08-22T14:52:01.863404Z","end":"2026-08-22T14:52:01.984057Z","steps":["trace[1073225013] 'agreement among raft nodes before linearized reading' (duration: 120.425129ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:01.984002Z","caller":"traceutil/trace.go:171","msg":"trace[1901766816] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"137.587423ms","start":"2026-08-22T14:52:01.846399Z","end":"2026-08-22T14:52:01.983987Z","steps":["trace[1901766816] 'process raft request' (duration: 136.87589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:52:01.983988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.086398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T14:52:01.984171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.046605ms","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-08-22T14:52:01.984228Z","caller":"traceutil/trace.go:171","msg":"trace[3129896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8598; }","duration":"133.388667ms","start":"2026-08-22T14:52:01.850825Z","end":"2026-08-22T14:52:01.984214Z","steps":["trace[3129896] 'agreement among raft nodes before linearized reading' (duration: 132.86029ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:01.984294Z","caller":"traceutil/trace.go:171","msg":"trace[1537606026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8598; }","duration":"124.17995ms","start":"2026-08-22T14:52:01.860063Z","end":"2026-08-22T14:52:01.984243Z","steps":["trace[1537606026] 'agreement among raft nodes before linearized reading' (duration: 123.906381ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:02.14008Z","caller":"traceutil/trace.go:171","msg":"trace[1192304950] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"151.198272ms","start":"2026-08-22T14:52:01.988854Z","end":"2026-08-22T14:52:02.140052Z","steps":["trace[1192304950] 'process raft request' (duration: 135.855357ms)","trace[1192304950] 'compare' (duration: 15.216401ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:52:02.151013Z","caller":"traceutil/trace.go:171","msg":"trace[512026664] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"160.755521ms","start":"2026-08-22T14:52:01.990228Z","end":"2026-08-22T14:52:02.150984Z","steps":["trace[512026664] 'process raft request' (duration: 160.70521ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:02.151061Z","caller":"traceutil/trace.go:171","msg":"trace[419049879] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"161.740893ms","start":"2026-08-22T14:52:01.98926Z","end":"2026-08-22T14:52:02.151001Z","steps":["trace[419049879] 'process raft request' (duration: 161.443513ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:02.151397Z","caller":"traceutil/trace.go:171","msg":"trace[484814160] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"161.737082ms","start":"2026-08-22T14:52:01.989646Z","end":"2026-08-22T14:52:02.151383Z","steps":["trace[484814160] 'process raft request' (duration: 161.204095ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:02.151364Z","caller":"traceutil/trace.go:171","msg":"trace[437076738] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"161.428932ms","start":"2026-08-22T14:52:01.989894Z","end":"2026-08-22T14:52:02.151323Z","steps":["trace[437076738] 'process raft request' (duration: 161.002488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:52:02.915509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.169531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:52:02.915626Z","caller":"traceutil/trace.go:171","msg":"trace[152739613] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8604; }","duration":"103.382119ms","start":"2026-08-22T14:52:02.812212Z","end":"2026-08-22T14:52:02.915594Z","steps":["trace[152739613] 'count revisions from in-memory index tree' (duration: 103.018726ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:08.516595Z","caller":"traceutil/trace.go:171","msg":"trace[85469815] linearizableReadLoop","detail":"{readStateIndex:8989; appliedIndex:8988; }","duration":"130.398281ms","start":"2026-08-22T14:52:08.386162Z","end":"2026-08-22T14:52:08.516561Z","steps":["trace[85469815] 'read index received' (duration: 54.851381ms)","trace[85469815] 'applied index is now lower than readState.Index' (duration: 75.545489ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:52:08.516714Z","caller":"traceutil/trace.go:171","msg":"trace[240460582] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"130.606657ms","start":"2026-08-22T14:52:08.386059Z","end":"2026-08-22T14:52:08.516666Z","steps":["trace[240460582] 'process raft request' (duration: 55.048837ms)","trace[240460582] 'compare' (duration: 75.26153ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:52:08.51691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.716661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-22T14:52:08.517017Z","caller":"traceutil/trace.go:171","msg":"trace[1595692235] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8677; }","duration":"130.833905ms","start":"2026-08-22T14:52:08.386109Z","end":"2026-08-22T14:52:08.516943Z","steps":["trace[1595692235] 'agreement among raft nodes before linearized reading' (duration: 130.623568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:52:08.51719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.607247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-w2qwt\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-08-22T14:52:08.51728Z","caller":"traceutil/trace.go:171","msg":"trace[1020070979] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-w2qwt; range_end:; response_count:1; response_revision:8677; }","duration":"130.951348ms","start":"2026-08-22T14:52:08.386305Z","end":"2026-08-22T14:52:08.517256Z","steps":["trace[1020070979] 'agreement among raft nodes before linearized reading' (duration: 130.497613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:52:08.517992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.881346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112182"} {"level":"info","ts":"2026-08-22T14:52:08.518072Z","caller":"traceutil/trace.go:171","msg":"trace[169273721] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8677; }","duration":"130.99529ms","start":"2026-08-22T14:52:08.387045Z","end":"2026-08-22T14:52:08.51804Z","steps":["trace[169273721] 'agreement among raft nodes before linearized reading' (duration: 129.829073ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:09.185081Z","caller":"traceutil/trace.go:171","msg":"trace[690058424] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"169.063769ms","start":"2026-08-22T14:52:09.015985Z","end":"2026-08-22T14:52:09.185048Z","steps":["trace[690058424] 'process raft request' (duration: 91.96776ms)","trace[690058424] 'compare' (duration: 76.683656ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:52:30.088265Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e0.wal"} {"level":"info","ts":"2026-08-22T14:53:22.40501Z","caller":"traceutil/trace.go:171","msg":"trace[772563476] transaction","detail":"{read_only:false; response_revision:9345; number_of_response:1; }","duration":"132.188949ms","start":"2026-08-22T14:53:22.27279Z","end":"2026-08-22T14:53:22.404978Z","steps":["trace[772563476] 'process raft request' (duration: 131.955181ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:53:22.568363Z","caller":"traceutil/trace.go:171","msg":"trace[1184382069] linearizableReadLoop","detail":"{readStateIndex:9677; appliedIndex:9675; }","duration":"275.565868ms","start":"2026-08-22T14:53:22.292772Z","end":"2026-08-22T14:53:22.568338Z","steps":["trace[1184382069] 'read index received' (duration: 111.985746ms)","trace[1184382069] 'applied index is now lower than readState.Index' (duration: 163.579002ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:53:22.568397Z","caller":"traceutil/trace.go:171","msg":"trace[1383264413] transaction","detail":"{read_only:false; response_revision:9346; number_of_response:1; }","duration":"294.593202ms","start":"2026-08-22T14:53:22.273766Z","end":"2026-08-22T14:53:22.56836Z","steps":["trace[1383264413] 'process raft request' (duration: 250.903132ms)","trace[1383264413] 'compare' (duration: 43.561186ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:53:22.568561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.546795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-22T14:53:22.568593Z","caller":"traceutil/trace.go:171","msg":"trace[1010865400] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:9346; }","duration":"265.611706ms","start":"2026-08-22T14:53:22.30297Z","end":"2026-08-22T14:53:22.568581Z","steps":["trace[1010865400] 'agreement among raft nodes before linearized reading' (duration: 265.487972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:53:22.568561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.751474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:53:22.568616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.099579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:53:22.568667Z","caller":"traceutil/trace.go:171","msg":"trace[557240742] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:9346; }","duration":"151.189212ms","start":"2026-08-22T14:53:22.417462Z","end":"2026-08-22T14:53:22.568651Z","steps":["trace[557240742] 'agreement among raft nodes before linearized reading' (duration: 151.093219ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:53:22.56867Z","caller":"traceutil/trace.go:171","msg":"trace[1874678806] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:9346; }","duration":"275.94075ms","start":"2026-08-22T14:53:22.292706Z","end":"2026-08-22T14:53:22.568647Z","steps":["trace[1874678806] 'agreement among raft nodes before linearized reading' (duration: 275.728873ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:53:22.762042Z","caller":"traceutil/trace.go:171","msg":"trace[1924462109] transaction","detail":"{read_only:false; response_revision:9348; number_of_response:1; }","duration":"171.255379ms","start":"2026-08-22T14:53:22.59075Z","end":"2026-08-22T14:53:22.762005Z","steps":["trace[1924462109] 'process raft request' (duration: 104.175613ms)","trace[1924462109] 'compare' (duration: 66.785347ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:53:22.762052Z","caller":"traceutil/trace.go:171","msg":"trace[1350474569] transaction","detail":"{read_only:false; response_revision:9349; number_of_response:1; }","duration":"134.482823ms","start":"2026-08-22T14:53:22.627529Z","end":"2026-08-22T14:53:22.762012Z","steps":["trace[1350474569] 'process raft request' (duration: 134.39721ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:53:22.762304Z","caller":"traceutil/trace.go:171","msg":"trace[2116649625] linearizableReadLoop","detail":"{readStateIndex:9679; appliedIndex:9678; }","duration":"138.903405ms","start":"2026-08-22T14:53:22.623379Z","end":"2026-08-22T14:53:22.762283Z","steps":["trace[2116649625] 'read index received' (duration: 71.475398ms)","trace[2116649625] 'applied index is now lower than readState.Index' (duration: 67.425357ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:53:22.762551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.703536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T14:53:22.762552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.392422ms","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-08-22T14:53:22.76263Z","caller":"traceutil/trace.go:171","msg":"trace[1501281765] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:9349; }","duration":"124.844222ms","start":"2026-08-22T14:53:22.637766Z","end":"2026-08-22T14:53:22.76261Z","steps":["trace[1501281765] 'agreement among raft nodes before linearized reading' (duration: 124.673006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:53:22.762595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.087472ms","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-08-22T14:53:22.762753Z","caller":"traceutil/trace.go:171","msg":"trace[1215619639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9349; }","duration":"170.291218ms","start":"2026-08-22T14:53:22.592431Z","end":"2026-08-22T14:53:22.762722Z","steps":["trace[1215619639] 'agreement among raft nodes before linearized reading' (duration: 169.874095ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:53:22.762666Z","caller":"traceutil/trace.go:171","msg":"trace[238251650] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9349; }","duration":"170.521916ms","start":"2026-08-22T14:53:22.592118Z","end":"2026-08-22T14:53:22.76264Z","steps":["trace[238251650] 'agreement among raft nodes before linearized reading' (duration: 170.267358ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:53:57.320667Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T14:53:57.326432Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T14:53:57.326616Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T14:56:12.29857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8282} {"level":"info","ts":"2026-08-22T14:56:12.381461Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8282,"took":"79.874353ms","hash":3908396412,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":26652672,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-22T14:56:12.381528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3908396412,"revision":8282,"compact-revision":6057} {"level":"info","ts":"2026-08-22T14:56:47.482305Z","caller":"traceutil/trace.go:171","msg":"trace[1525192046] linearizableReadLoop","detail":"{readStateIndex:11570; appliedIndex:11569; }","duration":"109.20806ms","start":"2026-08-22T14:56:47.373043Z","end":"2026-08-22T14:56:47.482251Z","steps":["trace[1525192046] 'read index received' (duration: 93.390509ms)","trace[1525192046] 'applied index is now lower than readState.Index' (duration: 15.815601ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:56:47.482338Z","caller":"traceutil/trace.go:171","msg":"trace[1462019992] transaction","detail":"{read_only:false; response_revision:11185; number_of_response:1; }","duration":"114.565744ms","start":"2026-08-22T14:56:47.367741Z","end":"2026-08-22T14:56:47.482307Z","steps":["trace[1462019992] 'process raft request' (duration: 98.685291ms)","trace[1462019992] 'compare' (duration: 15.528342ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:56:47.482629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.555182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T14:56:47.482734Z","caller":"traceutil/trace.go:171","msg":"trace[1642990954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11185; }","duration":"109.725097ms","start":"2026-08-22T14:56:47.372984Z","end":"2026-08-22T14:56:47.48271Z","steps":["trace[1642990954] 'agreement among raft nodes before linearized reading' (duration: 109.402877ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:57:01.702056Z","caller":"traceutil/trace.go:171","msg":"trace[1092370968] transaction","detail":"{read_only:false; response_revision:11266; number_of_response:1; }","duration":"169.164089ms","start":"2026-08-22T14:57:01.532857Z","end":"2026-08-22T14:57:01.702021Z","steps":["trace[1092370968] 'process raft request' (duration: 150.591799ms)","trace[1092370968] 'compare' (duration: 18.123816ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:57:01.702068Z","caller":"traceutil/trace.go:171","msg":"trace[2070865260] linearizableReadLoop","detail":"{readStateIndex:11654; appliedIndex:11653; }","duration":"136.191483ms","start":"2026-08-22T14:57:01.5658Z","end":"2026-08-22T14:57:01.701992Z","steps":["trace[2070865260] 'read index received' (duration: 117.665945ms)","trace[2070865260] 'applied index is now lower than readState.Index' (duration: 18.524168ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:57:01.702233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.42978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T14:57:01.702315Z","caller":"traceutil/trace.go:171","msg":"trace[1589433763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11266; }","duration":"136.571485ms","start":"2026-08-22T14:57:01.565729Z","end":"2026-08-22T14:57:01.702301Z","steps":["trace[1589433763] 'agreement among raft nodes before linearized reading' (duration: 136.367489ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:57:01.887889Z","caller":"traceutil/trace.go:171","msg":"trace[1155117745] transaction","detail":"{read_only:false; response_revision:11268; number_of_response:1; }","duration":"114.857794ms","start":"2026-08-22T14:57:01.773003Z","end":"2026-08-22T14:57:01.887861Z","steps":["trace[1155117745] 'process raft request' (duration: 114.149031ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:12.305487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10800} {"level":"info","ts":"2026-08-22T15:01:12.397536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10800,"took":"89.312123ms","hash":1628303327,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":27758592,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T15:01:12.397625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1628303327,"revision":10800,"compact-revision":8282} {"level":"info","ts":"2026-08-22T15:02:51.689887Z","caller":"traceutil/trace.go:171","msg":"trace[1038642149] transaction","detail":"{read_only:false; response_revision:13655; number_of_response:1; }","duration":"114.762507ms","start":"2026-08-22T15:02:51.575091Z","end":"2026-08-22T15:02:51.689854Z","steps":["trace[1038642149] 'process raft request' (duration: 114.563591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:03:25.279809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.194061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:03:25.27993Z","caller":"traceutil/trace.go:171","msg":"trace[1512968607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13834; }","duration":"115.381047ms","start":"2026-08-22T15:03:25.164527Z","end":"2026-08-22T15:03:25.279908Z","steps":["trace[1512968607] 'range keys from in-memory index tree' (duration: 115.060387ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:52.236584Z","caller":"traceutil/trace.go:171","msg":"trace[1850323605] transaction","detail":"{read_only:false; response_revision:13971; number_of_response:1; }","duration":"184.099243ms","start":"2026-08-22T15:03:52.052445Z","end":"2026-08-22T15:03:52.236544Z","steps":["trace[1850323605] 'process raft request' (duration: 183.762362ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:59.606306Z","caller":"traceutil/trace.go:171","msg":"trace[961806694] transaction","detail":"{read_only:false; response_revision:14027; number_of_response:1; }","duration":"177.784379ms","start":"2026-08-22T15:03:59.428484Z","end":"2026-08-22T15:03:59.606268Z","steps":["trace[961806694] 'process raft request' (duration: 177.586132ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:04:00.650322Z","caller":"traceutil/trace.go:171","msg":"trace[212213519] transaction","detail":"{read_only:false; response_revision:14030; number_of_response:1; }","duration":"372.01612ms","start":"2026-08-22T15:04:00.278275Z","end":"2026-08-22T15:04:00.650291Z","steps":["trace[212213519] 'process raft request' (duration: 371.831514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:04:00.650667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.278259Z","time spent":"372.235067ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:04:00.968243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.355564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T15:04:00.96853Z","caller":"traceutil/trace.go:171","msg":"trace[1037784544] transaction","detail":"{read_only:false; response_revision:14031; number_of_response:1; }","duration":"687.310222ms","start":"2026-08-22T15:04:00.281165Z","end":"2026-08-22T15:04:00.968475Z","steps":["trace[1037784544] 'process raft request' (duration: 580.595996ms)","trace[1037784544] 'compare' (duration: 105.665632ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:04:00.968537Z","caller":"traceutil/trace.go:171","msg":"trace[1725989446] linearizableReadLoop","detail":"{readStateIndex:14516; appliedIndex:14514; }","duration":"686.953981ms","start":"2026-08-22T15:04:00.281555Z","end":"2026-08-22T15:04:00.968509Z","steps":["trace[1725989446] 'read index received' (duration: 368.550617ms)","trace[1725989446] 'applied index is now lower than readState.Index' (duration: 318.401493ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:04:00.968768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.281145Z","time spent":"687.497559ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:04:00.987222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.657237ms","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-08-22T15:04:00.987282Z","caller":"traceutil/trace.go:171","msg":"trace[777610187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14031; }","duration":"705.738359ms","start":"2026-08-22T15:04:00.281525Z","end":"2026-08-22T15:04:00.987263Z","steps":["trace[777610187] 'agreement among raft nodes before linearized reading' (duration: 687.075355ms)","trace[777610187] 'range keys from in-memory index tree' (duration: 18.484859ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:04:00.98733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.281513Z","time spent":"705.807442ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T15:04:00.987363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.510227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-22T15:04:00.987377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.169274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:04:00.987446Z","caller":"traceutil/trace.go:171","msg":"trace[2070234935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14031; }","duration":"699.657492ms","start":"2026-08-22T15:04:00.287767Z","end":"2026-08-22T15:04:00.987424Z","steps":["trace[2070234935] 'agreement among raft nodes before linearized reading' (duration: 699.392564ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:04:00.987471Z","caller":"traceutil/trace.go:171","msg":"trace[307565071] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:14031; }","duration":"338.324069ms","start":"2026-08-22T15:04:00.649119Z","end":"2026-08-22T15:04:00.987443Z","steps":["trace[307565071] 'agreement among raft nodes before linearized reading' (duration: 338.156314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:04:00.987505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.649089Z","time spent":"338.410172ms","remote":"127.0.0.1:49814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/daemonsets/openstack/ovn-controller\" "} {"level":"warn","ts":"2026-08-22T15:04:00.987501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.287705Z","time spent":"699.782096ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T15:04:00.987502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.693087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T15:04:00.987564Z","caller":"traceutil/trace.go:171","msg":"trace[1325476633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14031; }","duration":"692.79142ms","start":"2026-08-22T15:04:00.294755Z","end":"2026-08-22T15:04:00.987547Z","steps":["trace[1325476633] 'agreement among raft nodes before linearized reading' (duration: 692.563283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:04:00.987604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.294726Z","time spent":"692.869873ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T15:04:00.987565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.580731ms","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-08-22T15:04:00.987667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.53354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2tx8g\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-22T15:04:00.987687Z","caller":"traceutil/trace.go:171","msg":"trace[936612586] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14031; }","duration":"469.821728ms","start":"2026-08-22T15:04:00.517833Z","end":"2026-08-22T15:04:00.987655Z","steps":["trace[936612586] 'agreement among raft nodes before linearized reading' (duration: 469.364403ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:04:00.987736Z","caller":"traceutil/trace.go:171","msg":"trace[1744611860] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2tx8g; range_end:; response_count:1; response_revision:14031; }","duration":"624.653863ms","start":"2026-08-22T15:04:00.363063Z","end":"2026-08-22T15:04:00.987716Z","steps":["trace[1744611860] 'agreement among raft nodes before linearized reading' (duration: 624.207418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:04:00.987748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.517808Z","time spent":"469.928021ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T15:04:00.987776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:00.36303Z","time spent":"624.736345ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-2tx8g\" "} {"level":"warn","ts":"2026-08-22T15:04:09.995974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.253609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T15:04:09.99635Z","caller":"traceutil/trace.go:171","msg":"trace[523894072] linearizableReadLoop","detail":"{readStateIndex:14681; appliedIndex:14679; }","duration":"280.478475ms","start":"2026-08-22T15:04:09.71585Z","end":"2026-08-22T15:04:09.996329Z","steps":["trace[523894072] 'read index received' (duration: 114.410576ms)","trace[523894072] 'applied index is now lower than readState.Index' (duration: 166.066399ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:04:09.99636Z","caller":"traceutil/trace.go:171","msg":"trace[1448863039] transaction","detail":"{read_only:false; response_revision:14191; number_of_response:1; }","duration":"347.686982ms","start":"2026-08-22T15:04:09.648634Z","end":"2026-08-22T15:04:09.996321Z","steps":["trace[1448863039] 'process raft request' (duration: 32.923406ms)","trace[1448863039] 'compare' (duration: 313.791724ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:04:09.996447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.589058ms","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-08-22T15:04:09.996491Z","caller":"traceutil/trace.go:171","msg":"trace[1415368685] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:14192; }","duration":"280.670161ms","start":"2026-08-22T15:04:09.715807Z","end":"2026-08-22T15:04:09.996477Z","steps":["trace[1415368685] 'agreement among raft nodes before linearized reading' (duration: 280.584078ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:04:09.996485Z","caller":"traceutil/trace.go:171","msg":"trace[566151632] transaction","detail":"{read_only:false; response_revision:14192; number_of_response:1; }","duration":"345.892173ms","start":"2026-08-22T15:04:09.650541Z","end":"2026-08-22T15:04:09.996434Z","steps":["trace[566151632] 'process raft request' (duration: 345.593274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:04:09.996564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:09.64859Z","time spent":"347.859458ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:04:09.996697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:04:09.650528Z","time spent":"346.060429ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:04:10.276087Z","caller":"traceutil/trace.go:171","msg":"trace[294987330] linearizableReadLoop","detail":"{readStateIndex:14682; appliedIndex:14681; }","duration":"209.377862ms","start":"2026-08-22T15:04:10.066684Z","end":"2026-08-22T15:04:10.276062Z","steps":["trace[294987330] 'read index received' (duration: 209.118934ms)","trace[294987330] 'applied index is now lower than readState.Index' (duration: 257.718µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:04:10.276142Z","caller":"traceutil/trace.go:171","msg":"trace[1379047276] transaction","detail":"{read_only:false; response_revision:14193; number_of_response:1; }","duration":"265.892972ms","start":"2026-08-22T15:04:10.010209Z","end":"2026-08-22T15:04:10.276102Z","steps":["trace[1379047276] 'process raft request' (duration: 265.675475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:04:10.27633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.580021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:04:10.276385Z","caller":"traceutil/trace.go:171","msg":"trace[1618761148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14193; }","duration":"114.742467ms","start":"2026-08-22T15:04:10.16163Z","end":"2026-08-22T15:04:10.276373Z","steps":["trace[1618761148] 'agreement among raft nodes before linearized reading' (duration: 114.590342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:04:10.276318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.585699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T15:04:10.276499Z","caller":"traceutil/trace.go:171","msg":"trace[343708439] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:14193; }","duration":"209.862757ms","start":"2026-08-22T15:04:10.066612Z","end":"2026-08-22T15:04:10.276475Z","steps":["trace[343708439] 'agreement among raft nodes before linearized reading' (duration: 209.566868ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:05:15.054679Z","caller":"traceutil/trace.go:171","msg":"trace[1162341768] transaction","detail":"{read_only:false; response_revision:15124; number_of_response:1; }","duration":"120.442401ms","start":"2026-08-22T15:05:14.93421Z","end":"2026-08-22T15:05:15.054653Z","steps":["trace[1162341768] 'process raft request' (duration: 119.926244ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:05:50.553581Z","caller":"traceutil/trace.go:171","msg":"trace[1785292958] transaction","detail":"{read_only:false; response_revision:15311; number_of_response:1; }","duration":"178.384881ms","start":"2026-08-22T15:05:50.375164Z","end":"2026-08-22T15:05:50.553549Z","steps":["trace[1785292958] 'process raft request' (duration: 178.208315ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:05:50.558247Z","caller":"traceutil/trace.go:171","msg":"trace[2144892855] linearizableReadLoop","detail":"{readStateIndex:15837; appliedIndex:15836; }","duration":"113.968301ms","start":"2026-08-22T15:05:50.444196Z","end":"2026-08-22T15:05:50.558164Z","steps":["trace[2144892855] 'read index received' (duration: 109.409964ms)","trace[2144892855] 'applied index is now lower than readState.Index' (duration: 4.556707ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:05:50.558412Z","caller":"traceutil/trace.go:171","msg":"trace[230075914] transaction","detail":"{read_only:false; response_revision:15312; number_of_response:1; }","duration":"181.033817ms","start":"2026-08-22T15:05:50.377362Z","end":"2026-08-22T15:05:50.558396Z","steps":["trace[230075914] 'process raft request' (duration: 180.602743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:05:50.558495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.26149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:05:50.558558Z","caller":"traceutil/trace.go:171","msg":"trace[1145756175] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15312; }","duration":"114.388474ms","start":"2026-08-22T15:05:50.44415Z","end":"2026-08-22T15:05:50.558539Z","steps":["trace[1145756175] 'agreement among raft nodes before linearized reading' (duration: 114.25305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:05:50.558633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.311182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:05:50.558678Z","caller":"traceutil/trace.go:171","msg":"trace[1267230780] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15312; }","duration":"114.418035ms","start":"2026-08-22T15:05:50.444247Z","end":"2026-08-22T15:05:50.558665Z","steps":["trace[1267230780] 'agreement among raft nodes before linearized reading' (duration: 114.323432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:05:50.558974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.065564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-22T15:05:50.558997Z","caller":"traceutil/trace.go:171","msg":"trace[548105153] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15312; }","duration":"114.107866ms","start":"2026-08-22T15:05:50.444882Z","end":"2026-08-22T15:05:50.558989Z","steps":["trace[548105153] 'agreement among raft nodes before linearized reading' (duration: 114.040033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:05:50.55911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.782497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:05:50.559129Z","caller":"traceutil/trace.go:171","msg":"trace[1709138910] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15312; }","duration":"114.800568ms","start":"2026-08-22T15:05:50.444322Z","end":"2026-08-22T15:05:50.559123Z","steps":["trace[1709138910] 'agreement among raft nodes before linearized reading' (duration: 114.771207ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:06:12.313934Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12873} {"level":"info","ts":"2026-08-22T15:06:12.39432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12873,"took":"76.938555ms","hash":1039517821,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30306304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T15:06:12.39438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1039517821,"revision":12873,"compact-revision":10800} {"level":"info","ts":"2026-08-22T15:11:12.31994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15428} {"level":"info","ts":"2026-08-22T15:11:12.422357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15428,"took":"98.807496ms","hash":2446884479,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":31985664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T15:11:12.422414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2446884479,"revision":15428,"compact-revision":12873} {"level":"info","ts":"2026-08-22T15:11:25.031489Z","caller":"traceutil/trace.go:171","msg":"trace[938245327] transaction","detail":"{read_only:false; response_revision:17817; number_of_response:1; }","duration":"229.940226ms","start":"2026-08-22T15:11:24.801505Z","end":"2026-08-22T15:11:25.031446Z","steps":["trace[938245327] 'process raft request' (duration: 229.693958ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:11:25.034758Z","caller":"traceutil/trace.go:171","msg":"trace[1885849388] linearizableReadLoop","detail":"{readStateIndex:18427; appliedIndex:18425; }","duration":"171.655756ms","start":"2026-08-22T15:11:24.863073Z","end":"2026-08-22T15:11:25.034728Z","steps":["trace[1885849388] 'read index received' (duration: 168.096551ms)","trace[1885849388] 'applied index is now lower than readState.Index' (duration: 3.557795ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:11:25.03503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.929174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3227"} {"level":"info","ts":"2026-08-22T15:11:25.035083Z","caller":"traceutil/trace.go:171","msg":"trace[1483629595] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17819; }","duration":"172.062239ms","start":"2026-08-22T15:11:24.863001Z","end":"2026-08-22T15:11:25.035063Z","steps":["trace[1483629595] 'agreement among raft nodes before linearized reading' (duration: 171.845792ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:11:25.035108Z","caller":"traceutil/trace.go:171","msg":"trace[1869783369] transaction","detail":"{read_only:false; response_revision:17818; number_of_response:1; }","duration":"226.097572ms","start":"2026-08-22T15:11:24.808932Z","end":"2026-08-22T15:11:25.035029Z","steps":["trace[1869783369] 'process raft request' (duration: 225.581036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:11:31.052555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.741798ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T15:11:31.052695Z","caller":"traceutil/trace.go:171","msg":"trace[1581576817] linearizableReadLoop","detail":"{readStateIndex:18480; appliedIndex:18479; }","duration":"127.562485ms","start":"2026-08-22T15:11:30.92511Z","end":"2026-08-22T15:11:31.052673Z","steps":["trace[1581576817] 'read index received' (duration: 50.292µs)","trace[1581576817] 'applied index is now lower than readState.Index' (duration: 127.510063ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:11:31.052832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.717939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:11:31.052941Z","caller":"traceutil/trace.go:171","msg":"trace[435600835] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:17869; }","duration":"127.860654ms","start":"2026-08-22T15:11:30.925048Z","end":"2026-08-22T15:11:31.052908Z","steps":["trace[435600835] 'agreement among raft nodes before linearized reading' (duration: 127.702169ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:14:26.651109Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T15:14:26.657158Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T15:14:26.65728Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T15:16:12.329587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17724} {"level":"info","ts":"2026-08-22T15:16:12.42726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17724,"took":"93.549197ms","hash":1485909247,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":33202176,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T15:16:12.427331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1485909247,"revision":17724,"compact-revision":15428} {"level":"info","ts":"2026-08-22T15:16:15.478806Z","caller":"traceutil/trace.go:171","msg":"trace[1296312691] transaction","detail":"{read_only:false; response_revision:20135; number_of_response:1; }","duration":"257.448533ms","start":"2026-08-22T15:16:15.221314Z","end":"2026-08-22T15:16:15.478762Z","steps":["trace[1296312691] 'process raft request' (duration: 173.909709ms)","trace[1296312691] 'compare' (duration: 83.173092ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:16:15.478882Z","caller":"traceutil/trace.go:171","msg":"trace[1399876556] linearizableReadLoop","detail":"{readStateIndex:20820; appliedIndex:20819; }","duration":"249.038611ms","start":"2026-08-22T15:16:15.229738Z","end":"2026-08-22T15:16:15.478777Z","steps":["trace[1399876556] 'read index received' (duration: 165.506058ms)","trace[1399876556] 'applied index is now lower than readState.Index' (duration: 83.531423ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:16:15.479045Z","caller":"traceutil/trace.go:171","msg":"trace[1931383579] transaction","detail":"{read_only:false; response_revision:20136; number_of_response:1; }","duration":"190.209784ms","start":"2026-08-22T15:16:15.288804Z","end":"2026-08-22T15:16:15.479014Z","steps":["trace[1931383579] 'process raft request' (duration: 189.923805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:15.479138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.265036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:16:15.479179Z","caller":"traceutil/trace.go:171","msg":"trace[1738495931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20136; }","duration":"248.358499ms","start":"2026-08-22T15:16:15.230808Z","end":"2026-08-22T15:16:15.479166Z","steps":["trace[1738495931] 'agreement among raft nodes before linearized reading' (duration: 248.204644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:15.479377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.513047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T15:16:15.479426Z","caller":"traceutil/trace.go:171","msg":"trace[1479851656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20136; }","duration":"258.60936ms","start":"2026-08-22T15:16:15.220803Z","end":"2026-08-22T15:16:15.479412Z","steps":["trace[1479851656] 'agreement among raft nodes before linearized reading' (duration: 258.073202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:15.479507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.007379ms","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":"info","ts":"2026-08-22T15:16:15.479559Z","caller":"traceutil/trace.go:171","msg":"trace[1889033423] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20136; }","duration":"155.110602ms","start":"2026-08-22T15:16:15.324434Z","end":"2026-08-22T15:16:15.479545Z","steps":["trace[1889033423] 'agreement among raft nodes before linearized reading' (duration: 154.966547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:15.480195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.409574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T15:16:15.480379Z","caller":"traceutil/trace.go:171","msg":"trace[1798559899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20136; }","duration":"214.650502ms","start":"2026-08-22T15:16:15.265704Z","end":"2026-08-22T15:16:15.480355Z","steps":["trace[1798559899] 'agreement among raft nodes before linearized reading' (duration: 214.253569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:15.480517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.587997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-22T15:16:15.480579Z","caller":"traceutil/trace.go:171","msg":"trace[1992850681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20136; }","duration":"217.676659ms","start":"2026-08-22T15:16:15.262889Z","end":"2026-08-22T15:16:15.480565Z","steps":["trace[1992850681] 'agreement among raft nodes before linearized reading' (duration: 217.176443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:25.826463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.671934ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:16:25.826755Z","caller":"traceutil/trace.go:171","msg":"trace[721927064] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:20191; }","duration":"170.984694ms","start":"2026-08-22T15:16:25.655736Z","end":"2026-08-22T15:16:25.826721Z","steps":["trace[721927064] 'range keys from in-memory index tree' (duration: 170.649054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.604907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.85637ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T15:16:31.605293Z","caller":"traceutil/trace.go:171","msg":"trace[1057990298] transaction","detail":"{read_only:false; response_revision:20214; number_of_response:1; }","duration":"321.025994ms","start":"2026-08-22T15:16:31.284235Z","end":"2026-08-22T15:16:31.605261Z","steps":["trace[1057990298] 'process raft request' (duration: 320.813767ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:31.605272Z","caller":"traceutil/trace.go:171","msg":"trace[139942433] linearizableReadLoop","detail":"{readStateIndex:20902; appliedIndex:20901; }","duration":"339.714376ms","start":"2026-08-22T15:16:31.2655Z","end":"2026-08-22T15:16:31.605215Z","steps":["trace[139942433] 'read index received' (duration: 146.584598ms)","trace[139942433] 'applied index is now lower than readState.Index' (duration: 193.123468ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:16:31.605456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.284211Z","time spent":"321.150778ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:16:31.605672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.003705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:16:31.605854Z","caller":"traceutil/trace.go:171","msg":"trace[2019198214] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:20214; }","duration":"340.398868ms","start":"2026-08-22T15:16:31.265421Z","end":"2026-08-22T15:16:31.605819Z","steps":["trace[2019198214] 'agreement among raft nodes before linearized reading' (duration: 339.960814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.605924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.265394Z","time spent":"340.507892ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-08-22T15:16:31.838807Z","caller":"traceutil/trace.go:171","msg":"trace[1663614002] linearizableReadLoop","detail":"{readStateIndex:20904; appliedIndex:20903; }","duration":"233.383427ms","start":"2026-08-22T15:16:31.605387Z","end":"2026-08-22T15:16:31.838771Z","steps":["trace[1663614002] 'read index received' (duration: 228.990075ms)","trace[1663614002] 'applied index is now lower than readState.Index' (duration: 4.391462ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:16:31.838823Z","caller":"traceutil/trace.go:171","msg":"trace[185239321] transaction","detail":"{read_only:false; response_revision:20215; number_of_response:1; }","duration":"292.790764ms","start":"2026-08-22T15:16:31.545993Z","end":"2026-08-22T15:16:31.838784Z","steps":["trace[185239321] 'process raft request' (duration: 288.380841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.838972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.324561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T15:16:31.839033Z","caller":"traceutil/trace.go:171","msg":"trace[264670850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20215; }","duration":"267.430705ms","start":"2026-08-22T15:16:31.571586Z","end":"2026-08-22T15:16:31.839017Z","steps":["trace[264670850] 'agreement among raft nodes before linearized reading' (duration: 267.191257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.83905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.151786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:16:31.839036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.575505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T15:16:31.839097Z","caller":"traceutil/trace.go:171","msg":"trace[835869823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20215; }","duration":"458.26948ms","start":"2026-08-22T15:16:31.380815Z","end":"2026-08-22T15:16:31.839085Z","steps":["trace[835869823] 'agreement among raft nodes before linearized reading' (duration: 458.145636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.839139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.380793Z","time spent":"458.337142ms","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T15:16:31.839136Z","caller":"traceutil/trace.go:171","msg":"trace[834604859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20215; }","duration":"269.735149ms","start":"2026-08-22T15:16:31.569374Z","end":"2026-08-22T15:16:31.839109Z","steps":["trace[834604859] 'agreement among raft nodes before linearized reading' (duration: 269.514292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.83923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.328263ms","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":"info","ts":"2026-08-22T15:16:31.839272Z","caller":"traceutil/trace.go:171","msg":"trace[1821845926] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20215; }","duration":"263.402625ms","start":"2026-08-22T15:16:31.575857Z","end":"2026-08-22T15:16:31.839259Z","steps":["trace[1821845926] 'agreement among raft nodes before linearized reading' (duration: 263.271171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.839281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.321412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T15:16:31.839431Z","caller":"traceutil/trace.go:171","msg":"trace[963280211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20215; }","duration":"263.555951ms","start":"2026-08-22T15:16:31.575852Z","end":"2026-08-22T15:16:31.839408Z","steps":["trace[963280211] 'agreement among raft nodes before linearized reading' (duration: 263.063005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.839286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.59805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T15:16:31.840004Z","caller":"traceutil/trace.go:171","msg":"trace[1528600469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20215; }","duration":"268.435688ms","start":"2026-08-22T15:16:31.571539Z","end":"2026-08-22T15:16:31.839975Z","steps":["trace[1528600469] 'agreement among raft nodes before linearized reading' (duration: 267.25302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:31.839474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.835602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:16:31.840245Z","caller":"traceutil/trace.go:171","msg":"trace[1516053099] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:20215; }","duration":"141.632508ms","start":"2026-08-22T15:16:31.698592Z","end":"2026-08-22T15:16:31.840224Z","steps":["trace[1516053099] 'agreement among raft nodes before linearized reading' (duration: 140.728909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:32.146346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.815219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:16:32.146636Z","caller":"traceutil/trace.go:171","msg":"trace[429781854] transaction","detail":"{read_only:false; response_revision:20216; number_of_response:1; }","duration":"302.107043ms","start":"2026-08-22T15:16:31.844441Z","end":"2026-08-22T15:16:32.146548Z","steps":["trace[429781854] 'process raft request' (duration: 161.982984ms)","trace[429781854] 'compare' (duration: 139.584662ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:16:32.146842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.844415Z","time spent":"302.30119ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:16:32.147361Z","caller":"traceutil/trace.go:171","msg":"trace[888734074] transaction","detail":"{read_only:false; response_revision:20217; number_of_response:1; }","duration":"301.542115ms","start":"2026-08-22T15:16:31.845504Z","end":"2026-08-22T15:16:32.147046Z","steps":["trace[888734074] 'process raft request' (duration: 300.947986ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:32.14775Z","caller":"traceutil/trace.go:171","msg":"trace[715981357] transaction","detail":"{read_only:false; response_revision:20219; number_of_response:1; }","duration":"302.110013ms","start":"2026-08-22T15:16:31.845623Z","end":"2026-08-22T15:16:32.147733Z","steps":["trace[715981357] 'process raft request' (duration: 302.00278ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:32.147829Z","caller":"traceutil/trace.go:171","msg":"trace[167840777] transaction","detail":"{read_only:false; response_revision:20218; number_of_response:1; }","duration":"302.209447ms","start":"2026-08-22T15:16:31.845602Z","end":"2026-08-22T15:16:32.147812Z","steps":["trace[167840777] 'process raft request' (duration: 300.937606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:32.148239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.845593Z","time spent":"302.546317ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:16:32.147858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.845604Z","time spent":"302.205077ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:16:32.147745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.845475Z","time spent":"302.00913ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:16:32.147899Z","caller":"traceutil/trace.go:171","msg":"trace[1757617839] transaction","detail":"{read_only:false; response_revision:20220; number_of_response:1; }","duration":"302.233487ms","start":"2026-08-22T15:16:31.84565Z","end":"2026-08-22T15:16:32.147884Z","steps":["trace[1757617839] 'process raft request' (duration: 302.035691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:32.148763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:31.845645Z","time spent":"303.063934ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:16:32.538484Z","caller":"traceutil/trace.go:171","msg":"trace[858113748] transaction","detail":"{read_only:false; response_revision:20221; number_of_response:1; }","duration":"260.12644ms","start":"2026-08-22T15:16:32.278323Z","end":"2026-08-22T15:16:32.53845Z","steps":["trace[858113748] 'process raft request' (duration: 259.925493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:32.90731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.646733ms","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-08-22T15:16:32.907397Z","caller":"traceutil/trace.go:171","msg":"trace[51511112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20221; }","duration":"192.784897ms","start":"2026-08-22T15:16:32.714591Z","end":"2026-08-22T15:16:32.907376Z","steps":["trace[51511112] 'range keys from in-memory index tree' (duration: 192.440097ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:20:15.280709Z","caller":"traceutil/trace.go:171","msg":"trace[1273158056] transaction","detail":"{read_only:false; response_revision:22598; number_of_response:1; }","duration":"101.988091ms","start":"2026-08-22T15:20:15.178683Z","end":"2026-08-22T15:20:15.280671Z","steps":["trace[1273158056] 'process raft request' (duration: 101.62859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:20:15.561575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.042199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-00b506c7-ed3f-4f0e-bad2-fdca7104d5ed\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-08-22T15:20:15.561713Z","caller":"traceutil/trace.go:171","msg":"trace[883991748] range","detail":"{range_begin:/registry/persistentvolumes/pvc-00b506c7-ed3f-4f0e-bad2-fdca7104d5ed; range_end:; response_count:1; response_revision:22598; }","duration":"246.257747ms","start":"2026-08-22T15:20:15.31543Z","end":"2026-08-22T15:20:15.561688Z","steps":["trace[883991748] 'range keys from in-memory index tree' (duration: 245.75003ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:20:15.56177Z","caller":"traceutil/trace.go:171","msg":"trace[1580358077] transaction","detail":"{read_only:false; response_revision:22599; number_of_response:1; }","duration":"100.638709ms","start":"2026-08-22T15:20:15.461091Z","end":"2026-08-22T15:20:15.56173Z","steps":["trace[1580358077] 'process raft request' (duration: 92.481177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:20:15.574587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.312574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:20:15.574661Z","caller":"traceutil/trace.go:171","msg":"trace[2021122279] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:22600; }","duration":"107.474039ms","start":"2026-08-22T15:20:15.467168Z","end":"2026-08-22T15:20:15.574642Z","steps":["trace[2021122279] 'agreement among raft nodes before linearized reading' (duration: 107.281793ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:21:12.337081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20120} {"level":"info","ts":"2026-08-22T15:21:12.451337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20120,"took":"109.239197ms","hash":3690763462,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":42397696,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-22T15:21:12.451447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3690763462,"revision":20120,"compact-revision":17724} {"level":"info","ts":"2026-08-22T15:26:12.352014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23164} {"level":"info","ts":"2026-08-22T15:26:12.491705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23164,"took":"134.453248ms","hash":3325427281,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T15:26:12.491768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3325427281,"revision":23164,"compact-revision":20120}