{"level":"warn","ts":"2026-08-28T04:11:49.755322Z","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-28T04:11:49.755481Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.71: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://162.253.55.71:2380","--initial-cluster=instance=https://162.253.55.71:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.71:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.71: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-28T04:11:49.755616Z","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-28T04:11:49.755643Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-08-28T04:11:49.755684Z","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-28T04:11:49.756893Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"]} {"level":"info","ts":"2026-08-28T04:11:49.757034Z","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://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.71: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-28T04:11:49.760788Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.354339ms"} {"level":"info","ts":"2026-08-28T04:11:49.767973Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54232768b5552f01","cluster-id":"66db39a47e9fb23b"} {"level":"info","ts":"2026-08-28T04:11:49.768082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T04:11:49.768132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 0"} {"level":"info","ts":"2026-08-28T04:11:49.768148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54232768b5552f01 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T04:11:49.768167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 1"} {"level":"info","ts":"2026-08-28T04:11:49.768227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"warn","ts":"2026-08-28T04:11:49.779796Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T04:11:49.781564Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T04:11:49.783077Z","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-28T04:11:49.786749Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54232768b5552f01","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T04:11:49.786959Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54232768b5552f01","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T04:11:49.78713Z","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-28T04:11:49.787297Z","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-28T04:11:49.787316Z","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-28T04:11:49.790151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"info","ts":"2026-08-28T04:11:49.790703Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","added-peer-id":"54232768b5552f01","added-peer-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-08-28T04:11:49.793057Z","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-28T04:11:49.79316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-08-28T04:11:49.793211Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-08-28T04:11:49.793424Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54232768b5552f01","initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T04:11:49.793467Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T04:11:49.86942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T04:11:49.86953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T04:11:49.869588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgPreVoteResp from 54232768b5552f01 at term 1"} {"level":"info","ts":"2026-08-28T04:11:49.869612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became candidate at term 2"} {"level":"info","ts":"2026-08-28T04:11:49.869624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgVoteResp from 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-08-28T04:11:49.869649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became leader at term 2"} {"level":"info","ts":"2026-08-28T04:11:49.869663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54232768b5552f01 elected leader 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-08-28T04:11:49.870887Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54232768b5552f01","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.71:2379]}","request-path":"/0/members/54232768b5552f01/attributes","cluster-id":"66db39a47e9fb23b","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T04:11:49.870896Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:11:49.870897Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:11:49.871129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:11:49.87127Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T04:11:49.871348Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T04:11:49.872011Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:11:49.872156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:11:49.8722Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:11:49.874025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.71:2379"} {"level":"info","ts":"2026-08-28T04:11:49.874743Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T04:12:14.569105Z","caller":"traceutil/trace.go:171","msg":"trace[1405046213] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"116.149574ms","start":"2026-08-28T04:12:14.452912Z","end":"2026-08-28T04:12:14.569061Z","steps":["trace[1405046213] 'process raft request' (duration: 116.015971ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:12:33.797261Z","caller":"traceutil/trace.go:171","msg":"trace[716519291] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"134.953298ms","start":"2026-08-28T04:12:33.662265Z","end":"2026-08-28T04:12:33.797218Z","steps":["trace[716519291] 'process raft request' (duration: 134.654423ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:12:34.222514Z","caller":"traceutil/trace.go:171","msg":"trace[932104000] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"185.769558ms","start":"2026-08-28T04:12:34.036697Z","end":"2026-08-28T04:12:34.222466Z","steps":["trace[932104000] 'process raft request' (duration: 117.724886ms)","trace[932104000] 'compare' (duration: 67.802506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:12:34.665839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.99551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:12:34.666032Z","caller":"traceutil/trace.go:171","msg":"trace[1547411283] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"387.322387ms","start":"2026-08-28T04:12:34.278686Z","end":"2026-08-28T04:12:34.666008Z","steps":["trace[1547411283] 'process raft request' (duration: 255.45707ms)","trace[1547411283] 'compare' (duration: 130.684003ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:12:34.666197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:12:34.27866Z","time spent":"387.447329ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:12:34.68421Z","caller":"traceutil/trace.go:171","msg":"trace[873876114] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"302.740248ms","start":"2026-08-28T04:12:34.381449Z","end":"2026-08-28T04:12:34.684189Z","steps":["trace[873876114] 'process raft request' (duration: 300.722959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:12:34.684312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:12:34.381412Z","time spent":"302.84796ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:12:35.890142Z","caller":"traceutil/trace.go:171","msg":"trace[924829302] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"108.28684ms","start":"2026-08-28T04:12:35.781823Z","end":"2026-08-28T04:12:35.89011Z","steps":["trace[924829302] 'process raft request' (duration: 107.692278ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:12:51.031369Z","caller":"traceutil/trace.go:171","msg":"trace[1289221687] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:789; }","duration":"251.41541ms","start":"2026-08-28T04:12:50.779929Z","end":"2026-08-28T04:12:51.031345Z","steps":["trace[1289221687] 'read index received' (duration: 251.140605ms)","trace[1289221687] 'applied index is now lower than readState.Index' (duration: 273.795µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:12:51.031493Z","caller":"traceutil/trace.go:171","msg":"trace[1448549085] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"279.359484ms","start":"2026-08-28T04:12:50.7521Z","end":"2026-08-28T04:12:51.03146Z","steps":["trace[1448549085] 'process raft request' (duration: 279.052188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:12:51.03166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.679055ms","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-28T04:12:51.031698Z","caller":"traceutil/trace.go:171","msg":"trace[541477904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"251.804488ms","start":"2026-08-28T04:12:50.779884Z","end":"2026-08-28T04:12:51.031688Z","steps":["trace[541477904] 'agreement among raft nodes before linearized reading' (duration: 251.612654ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:12:51.031729Z","caller":"traceutil/trace.go:171","msg":"trace[410721787] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"279.60022ms","start":"2026-08-28T04:12:50.752092Z","end":"2026-08-28T04:12:51.031693Z","steps":["trace[410721787] 'process raft request' (duration: 279.204362ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:20.514647Z","caller":"traceutil/trace.go:171","msg":"trace[2063714581] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"201.627081ms","start":"2026-08-28T04:13:20.312993Z","end":"2026-08-28T04:13:20.51462Z","steps":["trace[2063714581] 'process raft request' (duration: 201.294175ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:14:11.547244Z","caller":"traceutil/trace.go:171","msg":"trace[815137358] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"123.84071ms","start":"2026-08-28T04:14:11.423362Z","end":"2026-08-28T04:14:11.547203Z","steps":["trace[815137358] 'process raft request' (duration: 32.450999ms)","trace[815137358] 'compare' (duration: 91.044054ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:14:11.5474Z","caller":"traceutil/trace.go:171","msg":"trace[77691896] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"120.061622ms","start":"2026-08-28T04:14:11.427305Z","end":"2026-08-28T04:14:11.547366Z","steps":["trace[77691896] 'process raft request' (duration: 119.830177ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:14:11.762059Z","caller":"traceutil/trace.go:171","msg":"trace[1516170943] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"184.576111ms","start":"2026-08-28T04:14:11.577447Z","end":"2026-08-28T04:14:11.762023Z","steps":["trace[1516170943] 'process raft request' (duration: 182.665082ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:15:07.762937Z","caller":"traceutil/trace.go:171","msg":"trace[1073129397] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"151.889871ms","start":"2026-08-28T04:15:07.61101Z","end":"2026-08-28T04:15:07.7629Z","steps":["trace[1073129397] 'process raft request' (duration: 151.645717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:15:07.902975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.286485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:15:07.903123Z","caller":"traceutil/trace.go:171","msg":"trace[1161357106] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"137.807664ms","start":"2026-08-28T04:15:07.765293Z","end":"2026-08-28T04:15:07.903101Z","steps":["trace[1161357106] 'process raft request' (duration: 32.308116ms)","trace[1161357106] 'compare' (duration: 105.163373ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:15:08.180949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.188574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:15:08.181078Z","caller":"traceutil/trace.go:171","msg":"trace[433518210] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"273.092334ms","start":"2026-08-28T04:15:07.907965Z","end":"2026-08-28T04:15:08.181058Z","steps":["trace[433518210] 'process raft request' (duration: 135.688727ms)","trace[433518210] 'compare' (duration: 137.025221ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:15:08.189179Z","caller":"traceutil/trace.go:171","msg":"trace[462697904] linearizableReadLoop","detail":"{readStateIndex:2298; appliedIndex:2297; }","duration":"116.964199ms","start":"2026-08-28T04:15:08.072191Z","end":"2026-08-28T04:15:08.189155Z","steps":["trace[462697904] 'read index received' (duration: 116.784546ms)","trace[462697904] 'applied index is now lower than readState.Index' (duration: 178.443µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:15:08.189359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.146722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T04:15:08.189448Z","caller":"traceutil/trace.go:171","msg":"trace[415818364] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2214; }","duration":"117.299085ms","start":"2026-08-28T04:15:08.072128Z","end":"2026-08-28T04:15:08.189427Z","steps":["trace[415818364] 'agreement among raft nodes before linearized reading' (duration: 117.165193ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:15:08.189448Z","caller":"traceutil/trace.go:171","msg":"trace[1133289972] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"187.048147ms","start":"2026-08-28T04:15:08.002289Z","end":"2026-08-28T04:15:08.189337Z","steps":["trace[1133289972] 'process raft request' (duration: 186.754381ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:15:46.230298Z","caller":"traceutil/trace.go:171","msg":"trace[1298237401] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"135.017741ms","start":"2026-08-28T04:15:46.095246Z","end":"2026-08-28T04:15:46.230264Z","steps":["trace[1298237401] 'process raft request' (duration: 121.195026ms)","trace[1298237401] 'compare' (duration: 13.274467ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:20:56.970569Z","caller":"traceutil/trace.go:171","msg":"trace[2104104994] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"109.599672ms","start":"2026-08-28T04:20:56.860934Z","end":"2026-08-28T04:20:56.970534Z","steps":["trace[2104104994] 'process raft request' (duration: 109.405098ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:21:14.773563Z","caller":"traceutil/trace.go:171","msg":"trace[2011515474] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"262.651714ms","start":"2026-08-28T04:21:14.510888Z","end":"2026-08-28T04:21:14.77354Z","steps":["trace[2011515474] 'process raft request' (duration: 262.288537ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:21:14.774034Z","caller":"traceutil/trace.go:171","msg":"trace[1053887014] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"253.563748ms","start":"2026-08-28T04:21:14.520443Z","end":"2026-08-28T04:21:14.774007Z","steps":["trace[1053887014] 'process raft request' (duration: 252.977756ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:21:23.702772Z","caller":"traceutil/trace.go:171","msg":"trace[1702325076] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"109.994618ms","start":"2026-08-28T04:21:23.592735Z","end":"2026-08-28T04:21:23.702729Z","steps":["trace[1702325076] 'process raft request' (duration: 109.762703ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:21:23.718741Z","caller":"traceutil/trace.go:171","msg":"trace[689301091] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"109.124929ms","start":"2026-08-28T04:21:23.609586Z","end":"2026-08-28T04:21:23.718711Z","steps":["trace[689301091] 'process raft request' (duration: 108.837803ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:21:23.910065Z","caller":"traceutil/trace.go:171","msg":"trace[494645742] linearizableReadLoop","detail":"{readStateIndex:5826; appliedIndex:5825; }","duration":"121.863233ms","start":"2026-08-28T04:21:23.788165Z","end":"2026-08-28T04:21:23.910028Z","steps":["trace[494645742] 'read index received' (duration: 47.737978ms)","trace[494645742] 'applied index is now lower than readState.Index' (duration: 74.123195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:21:23.910285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.070447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:21:23.910341Z","caller":"traceutil/trace.go:171","msg":"trace[668255709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5614; }","duration":"122.224481ms","start":"2026-08-28T04:21:23.788102Z","end":"2026-08-28T04:21:23.910326Z","steps":["trace[668255709] 'agreement among raft nodes before linearized reading' (duration: 122.081928ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:21:23.993692Z","caller":"traceutil/trace.go:171","msg":"trace[1888140535] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"136.518059ms","start":"2026-08-28T04:21:23.857149Z","end":"2026-08-28T04:21:23.993667Z","steps":["trace[1888140535] 'process raft request' (duration: 136.328355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:21:23.994036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.323503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T04:21:23.99411Z","caller":"traceutil/trace.go:171","msg":"trace[344905789] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5615; }","duration":"174.431655ms","start":"2026-08-28T04:21:23.819655Z","end":"2026-08-28T04:21:23.994086Z","steps":["trace[344905789] 'agreement among raft nodes before linearized reading' (duration: 174.289722ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:21:24.049107Z","caller":"traceutil/trace.go:171","msg":"trace[291708744] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"137.707794ms","start":"2026-08-28T04:21:23.91136Z","end":"2026-08-28T04:21:24.049068Z","steps":["trace[291708744] 'process raft request' (duration: 117.99761ms)","trace[291708744] 'compare' (duration: 19.52323ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:21:50.342814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-08-28T04:21:50.451989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"107.705669ms","hash":266861481,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":32452608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-28T04:21:50.452123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":266861481,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-08-28T04:26:50.350778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5918} {"level":"info","ts":"2026-08-28T04:26:50.456463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5918,"took":"103.268499ms","hash":2132021521,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":25960448,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-28T04:26:50.456718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2132021521,"revision":5918,"compact-revision":2964} {"level":"warn","ts":"2026-08-28T04:27:49.355774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.927416ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-28T04:27:49.35588Z","caller":"traceutil/trace.go:171","msg":"trace[1858873387] linearizableReadLoop","detail":"{readStateIndex:8896; appliedIndex:8895; }","duration":"357.475107ms","start":"2026-08-28T04:27:48.998387Z","end":"2026-08-28T04:27:49.355862Z","steps":["trace[1858873387] 'read index received' (duration: 195.46847ms)","trace[1858873387] 'applied index is now lower than readState.Index' (duration: 162.005167ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:27:49.356114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.718432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-28T04:27:49.356166Z","caller":"traceutil/trace.go:171","msg":"trace[1660021058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8586; }","duration":"357.828214ms","start":"2026-08-28T04:27:48.998324Z","end":"2026-08-28T04:27:49.356152Z","steps":["trace[1660021058] 'agreement among raft nodes before linearized reading' (duration: 357.61063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:27:49.356208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:27:48.998295Z","time spent":"357.902066ms","remote":"127.0.0.1:35840","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-28T04:27:49.388585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.908893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-28T04:27:49.388674Z","caller":"traceutil/trace.go:171","msg":"trace[802586748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8587; }","duration":"230.053986ms","start":"2026-08-28T04:27:49.158603Z","end":"2026-08-28T04:27:49.388657Z","steps":["trace[802586748] 'agreement among raft nodes before linearized reading' (duration: 229.836772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:00.142497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.385531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:28:00.142661Z","caller":"traceutil/trace.go:171","msg":"trace[305463199] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"650.516543ms","start":"2026-08-28T04:27:59.492112Z","end":"2026-08-28T04:28:00.142628Z","steps":["trace[305463199] 'process raft request' (duration: 143.924987ms)","trace[305463199] 'compare' (duration: 506.106135ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:00.142752Z","caller":"traceutil/trace.go:171","msg":"trace[1501654387] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8952; }","duration":"435.271686ms","start":"2026-08-28T04:27:59.707472Z","end":"2026-08-28T04:28:00.142744Z","steps":["trace[1501654387] 'read index received' (duration: 37.341µs)","trace[1501654387] 'applied index is now lower than readState.Index' (duration: 435.233555ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:28:00.14282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.349358ms","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-28T04:28:00.142852Z","caller":"traceutil/trace.go:171","msg":"trace[951675830] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8642; }","duration":"435.412979ms","start":"2026-08-28T04:27:59.707425Z","end":"2026-08-28T04:28:00.142838Z","steps":["trace[951675830] 'agreement among raft nodes before linearized reading' (duration: 435.365048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:00.142848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:27:59.492096Z","time spent":"650.658125ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:28:00.142716Z","caller":"traceutil/trace.go:171","msg":"trace[219471990] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"214.767476ms","start":"2026-08-28T04:27:59.927932Z","end":"2026-08-28T04:28:00.1427Z","steps":["trace[219471990] 'process raft request' (duration: 214.703955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:00.142881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:27:59.707405Z","time spent":"435.46731ms","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-08-28T04:28:00.343149Z","caller":"traceutil/trace.go:171","msg":"trace[1190366942] linearizableReadLoop","detail":"{readStateIndex:8955; appliedIndex:8954; }","duration":"200.351104ms","start":"2026-08-28T04:28:00.142773Z","end":"2026-08-28T04:28:00.343124Z","steps":["trace[1190366942] 'read index received' (duration: 115.272798ms)","trace[1190366942] 'applied index is now lower than readState.Index' (duration: 85.077176ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:00.343203Z","caller":"traceutil/trace.go:171","msg":"trace[1343191319] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"311.847842ms","start":"2026-08-28T04:28:00.031307Z","end":"2026-08-28T04:28:00.343155Z","steps":["trace[1343191319] 'process raft request' (duration: 226.729605ms)","trace[1343191319] 'compare' (duration: 84.974744ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:28:00.343386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:28:00.031283Z","time spent":"312.018595ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:28:00.343418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.382809ms","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-28T04:28:00.34341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.146724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-28T04:28:00.343504Z","caller":"traceutil/trace.go:171","msg":"trace[1313383456] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8643; }","duration":"247.291598ms","start":"2026-08-28T04:28:00.096187Z","end":"2026-08-28T04:28:00.343479Z","steps":["trace[1313383456] 'agreement among raft nodes before linearized reading' (duration: 246.971641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:00.343536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.74059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:00.343602Z","caller":"traceutil/trace.go:171","msg":"trace[581581898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8643; }","duration":"583.842552ms","start":"2026-08-28T04:27:59.75974Z","end":"2026-08-28T04:28:00.343583Z","steps":["trace[581581898] 'agreement among raft nodes before linearized reading' (duration: 583.74233ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:00.343454Z","caller":"traceutil/trace.go:171","msg":"trace[953748914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8643; }","duration":"303.476471ms","start":"2026-08-28T04:28:00.039967Z","end":"2026-08-28T04:28:00.343444Z","steps":["trace[953748914] 'agreement among raft nodes before linearized reading' (duration: 303.320787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:00.343684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:27:59.75972Z","time spent":"583.951415ms","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-28T04:28:00.343695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:28:00.039946Z","time spent":"303.737976ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-28T04:28:00.646529Z","caller":"traceutil/trace.go:171","msg":"trace[50881127] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"298.652626ms","start":"2026-08-28T04:28:00.34784Z","end":"2026-08-28T04:28:00.646492Z","steps":["trace[50881127] 'process raft request' (duration: 257.462427ms)","trace[50881127] 'compare' (duration: 40.77171ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:00.646561Z","caller":"traceutil/trace.go:171","msg":"trace[2035279929] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"298.523553ms","start":"2026-08-28T04:28:00.348009Z","end":"2026-08-28T04:28:00.646533Z","steps":["trace[2035279929] 'process raft request' (duration: 298.40701ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:00.646523Z","caller":"traceutil/trace.go:171","msg":"trace[1032833268] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8956; }","duration":"198.722309ms","start":"2026-08-28T04:28:00.447765Z","end":"2026-08-28T04:28:00.646487Z","steps":["trace[1032833268] 'read index received' (duration: 157.426068ms)","trace[1032833268] 'applied index is now lower than readState.Index' (duration: 41.294551ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:28:00.646679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.882053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:00.646735Z","caller":"traceutil/trace.go:171","msg":"trace[854377095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8646; }","duration":"198.992115ms","start":"2026-08-28T04:28:00.447729Z","end":"2026-08-28T04:28:00.646721Z","steps":["trace[854377095] 'agreement among raft nodes before linearized reading' (duration: 198.876432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:00.688912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.312453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hjmnx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-28T04:28:00.689032Z","caller":"traceutil/trace.go:171","msg":"trace[1776502638] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hjmnx; range_end:; response_count:1; response_revision:8647; }","duration":"136.481646ms","start":"2026-08-28T04:28:00.5525Z","end":"2026-08-28T04:28:00.688982Z","steps":["trace[1776502638] 'agreement among raft nodes before linearized reading' (duration: 136.20398ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:01.27457Z","caller":"traceutil/trace.go:171","msg":"trace[2014764461] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"273.856242ms","start":"2026-08-28T04:28:01.00069Z","end":"2026-08-28T04:28:01.274546Z","steps":["trace[2014764461] 'process raft request' (duration: 273.714329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.638976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.886225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T04:28:01.639239Z","caller":"traceutil/trace.go:171","msg":"trace[1096256678] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:8648; }","duration":"231.189531ms","start":"2026-08-28T04:28:01.40802Z","end":"2026-08-28T04:28:01.639209Z","steps":["trace[1096256678] 'count revisions from in-memory index tree' (duration: 230.820013ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:01.639549Z","caller":"traceutil/trace.go:171","msg":"trace[1699532063] linearizableReadLoop","detail":"{readStateIndex:8961; appliedIndex:8960; }","duration":"157.655862ms","start":"2026-08-28T04:28:01.481885Z","end":"2026-08-28T04:28:01.639541Z","steps":["trace[1699532063] 'read index received' (duration: 78.857841ms)","trace[1699532063] 'applied index is now lower than readState.Index' (duration: 78.797411ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:28:01.639681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.807136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-28T04:28:01.639704Z","caller":"traceutil/trace.go:171","msg":"trace[1754831099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8649; }","duration":"157.875438ms","start":"2026-08-28T04:28:01.481823Z","end":"2026-08-28T04:28:01.639699Z","steps":["trace[1754831099] 'agreement among raft nodes before linearized reading' (duration: 157.774656ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:01.639806Z","caller":"traceutil/trace.go:171","msg":"trace[64305362] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"190.39348ms","start":"2026-08-28T04:28:01.449205Z","end":"2026-08-28T04:28:01.639599Z","steps":["trace[64305362] 'process raft request' (duration: 111.68052ms)","trace[64305362] 'compare' (duration: 78.328511ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:01.844471Z","caller":"traceutil/trace.go:171","msg":"trace[1507977145] linearizableReadLoop","detail":"{readStateIndex:8962; appliedIndex:8961; }","duration":"169.035358ms","start":"2026-08-28T04:28:01.675414Z","end":"2026-08-28T04:28:01.844449Z","steps":["trace[1507977145] 'read index received' (duration: 98.901044ms)","trace[1507977145] 'applied index is now lower than readState.Index' (duration: 70.133504ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:01.84458Z","caller":"traceutil/trace.go:171","msg":"trace[119195668] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"200.993319ms","start":"2026-08-28T04:28:01.64353Z","end":"2026-08-28T04:28:01.844523Z","steps":["trace[119195668] 'process raft request' (duration: 130.844465ms)","trace[119195668] 'compare' (duration: 69.96916ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:28:01.844702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.14375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.844759Z","caller":"traceutil/trace.go:171","msg":"trace[721344727] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8650; }","duration":"169.237812ms","start":"2026-08-28T04:28:01.675504Z","end":"2026-08-28T04:28:01.844742Z","steps":["trace[721344727] 'agreement among raft nodes before linearized reading' (duration: 169.14536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.844814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.452008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.844882Z","caller":"traceutil/trace.go:171","msg":"trace[1753549615] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8650; }","duration":"119.55345ms","start":"2026-08-28T04:28:01.725296Z","end":"2026-08-28T04:28:01.84485Z","steps":["trace[1753549615] 'agreement among raft nodes before linearized reading' (duration: 119.440857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.844989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.668952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.845037Z","caller":"traceutil/trace.go:171","msg":"trace[284736557] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8650; }","duration":"119.782244ms","start":"2026-08-28T04:28:01.725241Z","end":"2026-08-28T04:28:01.845023Z","steps":["trace[284736557] 'agreement among raft nodes before linearized reading' (duration: 119.708363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.845187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.355266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.845218Z","caller":"traceutil/trace.go:171","msg":"trace[1810320909] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8650; }","duration":"119.387666ms","start":"2026-08-28T04:28:01.725821Z","end":"2026-08-28T04:28:01.845208Z","steps":["trace[1810320909] 'agreement among raft nodes before linearized reading' (duration: 119.345485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.845346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.329382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.845373Z","caller":"traceutil/trace.go:171","msg":"trace[1101790501] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8650; }","duration":"148.372033ms","start":"2026-08-28T04:28:01.696991Z","end":"2026-08-28T04:28:01.845363Z","steps":["trace[1101790501] 'agreement among raft nodes before linearized reading' (duration: 148.330682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.845374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.367195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T04:28:01.845386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.555117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.845431Z","caller":"traceutil/trace.go:171","msg":"trace[1653307214] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8650; }","duration":"169.444667ms","start":"2026-08-28T04:28:01.67597Z","end":"2026-08-28T04:28:01.845415Z","steps":["trace[1653307214] 'agreement among raft nodes before linearized reading' (duration: 169.350905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.845468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.607177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.84552Z","caller":"traceutil/trace.go:171","msg":"trace[297547638] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8650; }","duration":"148.71054ms","start":"2026-08-28T04:28:01.696757Z","end":"2026-08-28T04:28:01.845468Z","steps":["trace[297547638] 'agreement among raft nodes before linearized reading' (duration: 148.504895ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:01.845544Z","caller":"traceutil/trace.go:171","msg":"trace[938321433] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8650; }","duration":"148.72687ms","start":"2026-08-28T04:28:01.696801Z","end":"2026-08-28T04:28:01.845528Z","steps":["trace[938321433] 'agreement among raft nodes before linearized reading' (duration: 148.623018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:01.844638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.15286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:01.847137Z","caller":"traceutil/trace.go:171","msg":"trace[1648254106] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8650; }","duration":"171.745456ms","start":"2026-08-28T04:28:01.67536Z","end":"2026-08-28T04:28:01.847106Z","steps":["trace[1648254106] 'agreement among raft nodes before linearized reading' (duration: 169.158621ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:02.629753Z","caller":"traceutil/trace.go:171","msg":"trace[963873046] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"197.319599ms","start":"2026-08-28T04:28:02.43238Z","end":"2026-08-28T04:28:02.6297Z","steps":["trace[963873046] 'process raft request' (duration: 153.526565ms)","trace[963873046] 'compare' (duration: 43.545809ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:02.896971Z","caller":"traceutil/trace.go:171","msg":"trace[753567189] linearizableReadLoop","detail":"{readStateIndex:8971; appliedIndex:8969; }","duration":"195.368277ms","start":"2026-08-28T04:28:02.701563Z","end":"2026-08-28T04:28:02.896931Z","steps":["trace[753567189] 'read index received' (duration: 6.556861ms)","trace[753567189] 'applied index is now lower than readState.Index' (duration: 188.809416ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:02.897059Z","caller":"traceutil/trace.go:171","msg":"trace[1173993061] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"202.263026ms","start":"2026-08-28T04:28:02.694758Z","end":"2026-08-28T04:28:02.897021Z","steps":["trace[1173993061] 'process raft request' (duration: 113.711674ms)","trace[1173993061] 'compare' (duration: 88.249416ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:28:02.897414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.818957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hjmnx\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-08-28T04:28:02.897405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.740063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:02.897487Z","caller":"traceutil/trace.go:171","msg":"trace[2116624849] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hjmnx; range_end:; response_count:1; response_revision:8659; }","duration":"195.948429ms","start":"2026-08-28T04:28:02.701522Z","end":"2026-08-28T04:28:02.897471Z","steps":["trace[2116624849] 'agreement among raft nodes before linearized reading' (duration: 195.632983ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:02.897517Z","caller":"traceutil/trace.go:171","msg":"trace[1714867006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8659; }","duration":"137.912367ms","start":"2026-08-28T04:28:02.759578Z","end":"2026-08-28T04:28:02.89749Z","steps":["trace[1714867006] 'agreement among raft nodes before linearized reading' (duration: 137.733503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:03.341473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.515185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T04:28:03.341595Z","caller":"traceutil/trace.go:171","msg":"trace[104894103] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8659; }","duration":"330.711879ms","start":"2026-08-28T04:28:03.010862Z","end":"2026-08-28T04:28:03.341574Z","steps":["trace[104894103] 'count revisions from in-memory index tree' (duration: 330.419061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:03.341645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:28:03.010838Z","time spent":"330.795321ms","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-08-28T04:28:03.341774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.716057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:03.341866Z","caller":"traceutil/trace.go:171","msg":"trace[947764356] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8659; }","duration":"293.160608ms","start":"2026-08-28T04:28:03.048688Z","end":"2026-08-28T04:28:03.341849Z","steps":["trace[947764356] 'range keys from in-memory index tree' (duration: 292.618716ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:03.625988Z","caller":"traceutil/trace.go:171","msg":"trace[592993285] linearizableReadLoop","detail":"{readStateIndex:8972; appliedIndex:8971; }","duration":"277.440838ms","start":"2026-08-28T04:28:03.348518Z","end":"2026-08-28T04:28:03.625959Z","steps":["trace[592993285] 'read index received' (duration: 277.259084ms)","trace[592993285] 'applied index is now lower than readState.Index' (duration: 179.614µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:03.626061Z","caller":"traceutil/trace.go:171","msg":"trace[2102384759] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"280.040455ms","start":"2026-08-28T04:28:03.345979Z","end":"2026-08-28T04:28:03.62602Z","steps":["trace[2102384759] 'process raft request' (duration: 279.790739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:03.626273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.702724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-08-28T04:28:03.626237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.051744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:03.626324Z","caller":"traceutil/trace.go:171","msg":"trace[587540104] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8660; }","duration":"277.821407ms","start":"2026-08-28T04:28:03.348481Z","end":"2026-08-28T04:28:03.626302Z","steps":["trace[587540104] 'agreement among raft nodes before linearized reading' (duration: 277.621472ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:03.626346Z","caller":"traceutil/trace.go:171","msg":"trace[1705261801] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8660; }","duration":"210.232058ms","start":"2026-08-28T04:28:03.416085Z","end":"2026-08-28T04:28:03.626318Z","steps":["trace[1705261801] 'agreement among raft nodes before linearized reading' (duration: 210.053324ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:03.778482Z","caller":"traceutil/trace.go:171","msg":"trace[710017061] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"132.453249ms","start":"2026-08-28T04:28:03.645974Z","end":"2026-08-28T04:28:03.778427Z","steps":["trace[710017061] 'process raft request' (duration: 132.177124ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:04.792047Z","caller":"traceutil/trace.go:171","msg":"trace[298792330] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"318.603977ms","start":"2026-08-28T04:28:04.473417Z","end":"2026-08-28T04:28:04.792021Z","steps":["trace[298792330] 'process raft request' (duration: 318.386362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:04.792181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:28:04.473397Z","time spent":"318.711609ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:28:05.143075Z","caller":"traceutil/trace.go:171","msg":"trace[267886434] linearizableReadLoop","detail":"{readStateIndex:8982; appliedIndex:8981; }","duration":"232.631341ms","start":"2026-08-28T04:28:04.910418Z","end":"2026-08-28T04:28:05.143049Z","steps":["trace[267886434] 'read index received' (duration: 205.159788ms)","trace[267886434] 'applied index is now lower than readState.Index' (duration: 27.470473ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:05.14313Z","caller":"traceutil/trace.go:171","msg":"trace[114886982] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"240.530772ms","start":"2026-08-28T04:28:04.902567Z","end":"2026-08-28T04:28:05.143098Z","steps":["trace[114886982] 'process raft request' (duration: 213.1218ms)","trace[114886982] 'compare' (duration: 27.058864ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:28:05.143278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.714913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:28:05.143314Z","caller":"traceutil/trace.go:171","msg":"trace[108071702] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8669; }","duration":"175.841816ms","start":"2026-08-28T04:28:04.96746Z","end":"2026-08-28T04:28:05.143301Z","steps":["trace[108071702] 'agreement among raft nodes before linearized reading' (duration: 175.725783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:05.14332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.889117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hjmnx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-28T04:28:05.143387Z","caller":"traceutil/trace.go:171","msg":"trace[983501487] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hjmnx; range_end:; response_count:1; response_revision:8669; }","duration":"232.993379ms","start":"2026-08-28T04:28:04.910376Z","end":"2026-08-28T04:28:05.143369Z","steps":["trace[983501487] 'agreement among raft nodes before linearized reading' (duration: 232.785845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:43.543861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.109753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hjmnx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-28T04:28:43.543984Z","caller":"traceutil/trace.go:171","msg":"trace[1824821000] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hjmnx; range_end:; response_count:1; response_revision:8926; }","duration":"107.281448ms","start":"2026-08-28T04:28:43.436677Z","end":"2026-08-28T04:28:43.543958Z","steps":["trace[1824821000] 'range keys from in-memory index tree' (duration: 106.842939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:28:46.749687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.925644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:28:46.750134Z","caller":"traceutil/trace.go:171","msg":"trace[437754808] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"211.580572ms","start":"2026-08-28T04:28:46.538526Z","end":"2026-08-28T04:28:46.750107Z","steps":["trace[437754808] 'process raft request' (duration: 55.136777ms)","trace[437754808] 'compare' (duration: 155.781041ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:28:46.764741Z","caller":"traceutil/trace.go:171","msg":"trace[323455781] transaction","detail":"{read_only:false; response_revision:8957; number_of_response:1; }","duration":"141.563755ms","start":"2026-08-28T04:28:46.623154Z","end":"2026-08-28T04:28:46.764717Z","steps":["trace[323455781] 'process raft request' (duration: 141.379861ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:28:49.561188Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002466.wal"} {"level":"info","ts":"2026-08-28T04:29:53.641286Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T04:29:53.648351Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-28T04:29:53.648468Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-28T04:31:50.358398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8074} {"level":"info","ts":"2026-08-28T04:31:50.42855Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8074,"took":"67.893988ms","hash":1782118507,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":27992064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-28T04:31:50.428617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1782118507,"revision":8074,"compact-revision":5918} {"level":"info","ts":"2026-08-28T04:36:50.366565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10455} {"level":"info","ts":"2026-08-28T04:36:50.45666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10455,"took":"87.662892ms","hash":379490714,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":25751552,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-28T04:36:50.456722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":379490714,"revision":10455,"compact-revision":8074} {"level":"info","ts":"2026-08-28T04:41:50.374651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12609} {"level":"info","ts":"2026-08-28T04:41:50.45885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12609,"took":"81.665233ms","hash":4187910094,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":27770880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-28T04:41:50.45893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4187910094,"revision":12609,"compact-revision":10455} {"level":"warn","ts":"2026-08-28T04:42:19.427435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.62692ms","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-28T04:42:19.427536Z","caller":"traceutil/trace.go:171","msg":"trace[1453270578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14810; }","duration":"133.789093ms","start":"2026-08-28T04:42:19.293727Z","end":"2026-08-28T04:42:19.427517Z","steps":["trace[1453270578] 'range keys from in-memory index tree' (duration: 133.377964ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:42:36.347284Z","caller":"traceutil/trace.go:171","msg":"trace[2011902148] linearizableReadLoop","detail":"{readStateIndex:15436; appliedIndex:15434; }","duration":"113.578739ms","start":"2026-08-28T04:42:36.233681Z","end":"2026-08-28T04:42:36.34726Z","steps":["trace[2011902148] 'read index received' (duration: 75.145363ms)","trace[2011902148] 'applied index is now lower than readState.Index' (duration: 38.432166ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:42:36.347327Z","caller":"traceutil/trace.go:171","msg":"trace[632576179] transaction","detail":"{read_only:false; response_revision:14912; number_of_response:1; }","duration":"115.099051ms","start":"2026-08-28T04:42:36.232207Z","end":"2026-08-28T04:42:36.347306Z","steps":["trace[632576179] 'process raft request' (duration: 114.918537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:42:36.347861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.179412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:595097"} {"level":"info","ts":"2026-08-28T04:42:36.347894Z","caller":"traceutil/trace.go:171","msg":"trace[685939267] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14912; }","duration":"114.268254ms","start":"2026-08-28T04:42:36.233617Z","end":"2026-08-28T04:42:36.347886Z","steps":["trace[685939267] 'agreement among raft nodes before linearized reading' (duration: 113.735543ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:43:01.758432Z","caller":"traceutil/trace.go:171","msg":"trace[1057671613] transaction","detail":"{read_only:false; response_revision:15239; number_of_response:1; }","duration":"113.056048ms","start":"2026-08-28T04:43:01.645335Z","end":"2026-08-28T04:43:01.758391Z","steps":["trace[1057671613] 'process raft request' (duration: 99.008256ms)","trace[1057671613] 'compare' (duration: 13.641753ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:43:02.0355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.27114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T04:43:02.035623Z","caller":"traceutil/trace.go:171","msg":"trace[1087938929] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:15240; }","duration":"125.487024ms","start":"2026-08-28T04:43:01.910111Z","end":"2026-08-28T04:43:02.035598Z","steps":["trace[1087938929] 'count revisions from in-memory index tree' (duration: 125.130237ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:44:34.030261Z","caller":"traceutil/trace.go:171","msg":"trace[451588576] linearizableReadLoop","detail":"{readStateIndex:16641; appliedIndex:16641; }","duration":"167.726541ms","start":"2026-08-28T04:44:33.862496Z","end":"2026-08-28T04:44:34.030223Z","steps":["trace[451588576] 'read index received' (duration: 167.715431ms)","trace[451588576] 'applied index is now lower than readState.Index' (duration: 8.61µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:44:34.03073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.203581ms","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-28T04:44:34.030803Z","caller":"traceutil/trace.go:171","msg":"trace[954200451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16079; }","duration":"168.321404ms","start":"2026-08-28T04:44:33.862461Z","end":"2026-08-28T04:44:34.030782Z","steps":["trace[954200451] 'agreement among raft nodes before linearized reading' (duration: 167.915046ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:46:50.382025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14622} {"level":"info","ts":"2026-08-28T04:46:50.472067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14622,"took":"86.569474ms","hash":711986910,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":31150080,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T04:46:50.472156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":711986910,"revision":14622,"compact-revision":12609} {"level":"info","ts":"2026-08-28T04:50:30.413958Z","caller":"traceutil/trace.go:171","msg":"trace[1589250867] linearizableReadLoop","detail":"{readStateIndex:19355; appliedIndex:19354; }","duration":"174.239779ms","start":"2026-08-28T04:50:30.239687Z","end":"2026-08-28T04:50:30.413927Z","steps":["trace[1589250867] 'read index received' (duration: 174.089616ms)","trace[1589250867] 'applied index is now lower than readState.Index' (duration: 146.383µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:50:30.414061Z","caller":"traceutil/trace.go:171","msg":"trace[1619476026] transaction","detail":"{read_only:false; response_revision:18706; number_of_response:1; }","duration":"251.641554ms","start":"2026-08-28T04:50:30.162369Z","end":"2026-08-28T04:50:30.41401Z","steps":["trace[1619476026] 'process raft request' (duration: 251.356668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:30.414103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.389082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T04:50:30.414164Z","caller":"traceutil/trace.go:171","msg":"trace[1192762661] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:18706; }","duration":"174.500145ms","start":"2026-08-28T04:50:30.239648Z","end":"2026-08-28T04:50:30.414148Z","steps":["trace[1192762661] 'agreement among raft nodes before linearized reading' (duration: 174.385173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:37.150886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.553125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T04:50:37.151106Z","caller":"traceutil/trace.go:171","msg":"trace[1148946954] transaction","detail":"{read_only:false; response_revision:18744; number_of_response:1; }","duration":"223.255333ms","start":"2026-08-28T04:50:36.927832Z","end":"2026-08-28T04:50:37.151087Z","steps":["trace[1148946954] 'process raft request' (duration: 41.3756ms)","trace[1148946954] 'compare' (duration: 181.430993ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:50:37.151818Z","caller":"traceutil/trace.go:171","msg":"trace[932900983] transaction","detail":"{read_only:false; response_revision:18745; number_of_response:1; }","duration":"191.098781ms","start":"2026-08-28T04:50:36.960697Z","end":"2026-08-28T04:50:37.151796Z","steps":["trace[932900983] 'process raft request' (duration: 190.280104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:37.437474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.988974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T04:50:37.437567Z","caller":"traceutil/trace.go:171","msg":"trace[607430103] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:18746; }","duration":"141.115437ms","start":"2026-08-28T04:50:37.296422Z","end":"2026-08-28T04:50:37.437538Z","steps":["trace[607430103] 'count revisions from in-memory index tree' (duration: 140.920341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:37.437608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.644401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T04:50:37.437686Z","caller":"traceutil/trace.go:171","msg":"trace[783885597] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:18746; }","duration":"104.784584ms","start":"2026-08-28T04:50:37.332882Z","end":"2026-08-28T04:50:37.437667Z","steps":["trace[783885597] 'count revisions from in-memory index tree' (duration: 104.557019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:37.974631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.997839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x25s9\" ","response":"range_response_count:1 size:22129"} {"level":"warn","ts":"2026-08-28T04:50:37.974682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.96048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-qrdsj\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-08-28T04:50:37.974716Z","caller":"traceutil/trace.go:171","msg":"trace[1195396938] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-qrdsj; range_end:; response_count:1; response_revision:18747; }","duration":"311.042082ms","start":"2026-08-28T04:50:37.663665Z","end":"2026-08-28T04:50:37.974707Z","steps":["trace[1195396938] 'range keys from in-memory index tree' (duration: 310.864078ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:50:37.974712Z","caller":"traceutil/trace.go:171","msg":"trace[1930127723] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x25s9; range_end:; response_count:1; response_revision:18747; }","duration":"382.099881ms","start":"2026-08-28T04:50:37.592588Z","end":"2026-08-28T04:50:37.974688Z","steps":["trace[1930127723] 'range keys from in-memory index tree' (duration: 381.814825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:37.974727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.311963ms","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":"warn","ts":"2026-08-28T04:50:37.974739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:50:37.663651Z","time spent":"311.082322ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9821,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-qrdsj\" "} {"level":"info","ts":"2026-08-28T04:50:37.974756Z","caller":"traceutil/trace.go:171","msg":"trace[1847306285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18747; }","duration":"433.405425ms","start":"2026-08-28T04:50:37.541341Z","end":"2026-08-28T04:50:37.974747Z","steps":["trace[1847306285] 'range keys from in-memory index tree' (duration: 433.21409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:37.974759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:50:37.592567Z","time spent":"382.180183ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22151,"request content":"key:\"/registry/pods/openstack/nova-compute-default-x25s9\" "} {"level":"warn","ts":"2026-08-28T04:50:37.974774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:50:37.541319Z","time spent":"433.450125ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-28T04:50:37.974616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.126642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-08-28T04:50:37.974983Z","caller":"traceutil/trace.go:171","msg":"trace[33765000] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18747; }","duration":"402.548291ms","start":"2026-08-28T04:50:37.572417Z","end":"2026-08-28T04:50:37.974965Z","steps":["trace[33765000] 'range keys from in-memory index tree' (duration: 401.901267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:37.975041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:50:37.572394Z","time spent":"402.628342ms","remote":"127.0.0.1:35712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-28T04:50:38.047613Z","caller":"traceutil/trace.go:171","msg":"trace[273828517] linearizableReadLoop","detail":"{readStateIndex:19398; appliedIndex:19397; }","duration":"175.262251ms","start":"2026-08-28T04:50:37.872331Z","end":"2026-08-28T04:50:38.047593Z","steps":["trace[273828517] 'read index received' (duration: 175.087417ms)","trace[273828517] 'applied index is now lower than readState.Index' (duration: 174.114µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:50:38.047664Z","caller":"traceutil/trace.go:171","msg":"trace[929525195] transaction","detail":"{read_only:false; response_revision:18748; number_of_response:1; }","duration":"270.01273ms","start":"2026-08-28T04:50:37.77763Z","end":"2026-08-28T04:50:38.047643Z","steps":["trace[929525195] 'process raft request' (duration: 269.812975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:38.047721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.368983ms","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-28T04:50:38.047742Z","caller":"traceutil/trace.go:171","msg":"trace[428534102] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:18748; }","duration":"175.432725ms","start":"2026-08-28T04:50:37.872302Z","end":"2026-08-28T04:50:38.047735Z","steps":["trace[428534102] 'agreement among raft nodes before linearized reading' (duration: 175.369383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:50:38.064316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.969699ms","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-28T04:50:38.064424Z","caller":"traceutil/trace.go:171","msg":"trace[1239031875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18749; }","duration":"165.122742ms","start":"2026-08-28T04:50:37.89928Z","end":"2026-08-28T04:50:38.064403Z","steps":["trace[1239031875] 'agreement among raft nodes before linearized reading' (duration: 164.909688ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:51:23.392925Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T04:51:23.398522Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-28T04:51:23.398634Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-28T04:51:50.387682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17028} {"level":"info","ts":"2026-08-28T04:51:50.498815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17028,"took":"108.293269ms","hash":4106133637,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":33345536,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-28T04:51:50.498866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4106133637,"revision":17028,"compact-revision":14622} {"level":"warn","ts":"2026-08-28T04:56:28.098538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.407543ms","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-28T04:56:28.099437Z","caller":"traceutil/trace.go:171","msg":"trace[302595229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21450; }","duration":"229.353004ms","start":"2026-08-28T04:56:27.87006Z","end":"2026-08-28T04:56:28.099413Z","steps":["trace[302595229] 'range keys from in-memory index tree' (duration: 228.228039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:56:28.098576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.844506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T04:56:28.099901Z","caller":"traceutil/trace.go:171","msg":"trace[1732512394] linearizableReadLoop","detail":"{readStateIndex:22191; appliedIndex:22190; }","duration":"192.764175ms","start":"2026-08-28T04:56:27.907103Z","end":"2026-08-28T04:56:28.099867Z","steps":["trace[1732512394] 'read index received' (duration: 24.57444ms)","trace[1732512394] 'applied index is now lower than readState.Index' (duration: 168.185725ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:56:28.099985Z","caller":"traceutil/trace.go:171","msg":"trace[2003037770] transaction","detail":"{read_only:false; response_revision:21451; number_of_response:1; }","duration":"225.346506ms","start":"2026-08-28T04:56:27.874569Z","end":"2026-08-28T04:56:28.099915Z","steps":["trace[2003037770] 'process raft request' (duration: 57.07766ms)","trace[2003037770] 'compare' (duration: 166.669542ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:56:28.100076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.972059ms","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-28T04:56:28.100139Z","caller":"traceutil/trace.go:171","msg":"trace[1897054914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21451; }","duration":"193.073202ms","start":"2026-08-28T04:56:27.907047Z","end":"2026-08-28T04:56:28.10012Z","steps":["trace[1897054914] 'agreement among raft nodes before linearized reading' (duration: 192.902638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:56:28.100231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.514831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-28T04:56:28.100278Z","caller":"traceutil/trace.go:171","msg":"trace[2102050052] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21451; }","duration":"129.623764ms","start":"2026-08-28T04:56:27.970643Z","end":"2026-08-28T04:56:28.100267Z","steps":["trace[2102050052] 'agreement among raft nodes before linearized reading' (duration: 129.415899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:56:28.435632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.632106ms","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-28T04:56:28.435763Z","caller":"traceutil/trace.go:171","msg":"trace[307929317] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21453; }","duration":"188.776269ms","start":"2026-08-28T04:56:28.246969Z","end":"2026-08-28T04:56:28.435746Z","steps":["trace[307929317] 'range keys from in-memory index tree' (duration: 188.614636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:56:28.436055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.932296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-28T04:56:28.436089Z","caller":"traceutil/trace.go:171","msg":"trace[387383936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21453; }","duration":"184.994217ms","start":"2026-08-28T04:56:28.251086Z","end":"2026-08-28T04:56:28.43608Z","steps":["trace[387383936] 'range keys from in-memory index tree' (duration: 184.800233ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:56:28.607482Z","caller":"traceutil/trace.go:171","msg":"trace[1680671744] transaction","detail":"{read_only:false; response_revision:21454; number_of_response:1; }","duration":"164.620798ms","start":"2026-08-28T04:56:28.442834Z","end":"2026-08-28T04:56:28.607455Z","steps":["trace[1680671744] 'process raft request' (duration: 164.440394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:56:28.780629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.606005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T04:56:28.780751Z","caller":"traceutil/trace.go:171","msg":"trace[386270358] linearizableReadLoop","detail":"{readStateIndex:22195; appliedIndex:22193; }","duration":"185.272574ms","start":"2026-08-28T04:56:28.595459Z","end":"2026-08-28T04:56:28.780732Z","steps":["trace[386270358] 'read index received' (duration: 11.795235ms)","trace[386270358] 'applied index is now lower than readState.Index' (duration: 173.475749ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:56:28.780836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.368086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T04:56:28.780824Z","caller":"traceutil/trace.go:171","msg":"trace[179894318] transaction","detail":"{read_only:false; response_revision:21455; number_of_response:1; }","duration":"322.355118ms","start":"2026-08-28T04:56:28.458433Z","end":"2026-08-28T04:56:28.780788Z","steps":["trace[179894318] 'process raft request' (duration: 178.446136ms)","trace[179894318] 'compare' (duration: 143.430802ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:56:28.780864Z","caller":"traceutil/trace.go:171","msg":"trace[473921188] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21455; }","duration":"185.435707ms","start":"2026-08-28T04:56:28.595416Z","end":"2026-08-28T04:56:28.780852Z","steps":["trace[473921188] 'agreement among raft nodes before linearized reading' (duration: 185.362306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:56:28.781017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:56:28.45841Z","time spent":"322.484141ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:56:29.11091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.611295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-28T04:56:29.110909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.704543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T04:56:29.111005Z","caller":"traceutil/trace.go:171","msg":"trace[121808952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21456; }","duration":"175.766069ms","start":"2026-08-28T04:56:28.935217Z","end":"2026-08-28T04:56:29.110984Z","steps":["trace[121808952] 'range keys from in-memory index tree' (duration: 175.39119ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:56:29.111033Z","caller":"traceutil/trace.go:171","msg":"trace[1534610233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21456; }","duration":"153.883407ms","start":"2026-08-28T04:56:28.957122Z","end":"2026-08-28T04:56:29.111006Z","steps":["trace[1534610233] 'range keys from in-memory index tree' (duration: 153.408336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:56:29.229449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.451229ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-28T04:56:29.229936Z","caller":"traceutil/trace.go:171","msg":"trace[1767546540] transaction","detail":"{read_only:false; response_revision:21458; number_of_response:1; }","duration":"113.371624ms","start":"2026-08-28T04:56:29.116541Z","end":"2026-08-28T04:56:29.229913Z","steps":["trace[1767546540] 'process raft request' (duration: 113.171038ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:56:29.229772Z","caller":"traceutil/trace.go:171","msg":"trace[938626610] transaction","detail":"{read_only:false; response_revision:21457; number_of_response:1; }","duration":"114.702902ms","start":"2026-08-28T04:56:29.11505Z","end":"2026-08-28T04:56:29.229753Z","steps":["trace[938626610] 'process raft request' (duration: 114.515877ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:56:29.918313Z","caller":"traceutil/trace.go:171","msg":"trace[1631875039] transaction","detail":"{read_only:false; response_revision:21460; number_of_response:1; }","duration":"103.132453ms","start":"2026-08-28T04:56:29.815156Z","end":"2026-08-28T04:56:29.918289Z","steps":["trace[1631875039] 'process raft request' (duration: 102.949199ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:56:50.39412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19539} {"level":"info","ts":"2026-08-28T04:56:50.555462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19539,"took":"157.29953ms","hash":1779402408,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":29487104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-28T04:56:50.55558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1779402408,"revision":19539,"compact-revision":17028} {"level":"info","ts":"2026-08-28T05:00:33.577258Z","caller":"traceutil/trace.go:171","msg":"trace[870621912] transaction","detail":"{read_only:false; response_revision:24031; number_of_response:1; }","duration":"298.990065ms","start":"2026-08-28T05:00:33.278243Z","end":"2026-08-28T05:00:33.577233Z","steps":["trace[870621912] 'process raft request' (duration: 279.331181ms)","trace[870621912] 'compare' (duration: 19.156594ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:00:33.57739Z","caller":"traceutil/trace.go:171","msg":"trace[637256218] linearizableReadLoop","detail":"{readStateIndex:24847; appliedIndex:24845; }","duration":"128.50741ms","start":"2026-08-28T05:00:33.448844Z","end":"2026-08-28T05:00:33.577351Z","steps":["trace[637256218] 'read index received' (duration: 108.744604ms)","trace[637256218] 'applied index is now lower than readState.Index' (duration: 19.760196ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:00:33.577681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.823137ms","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-28T05:00:33.577744Z","caller":"traceutil/trace.go:171","msg":"trace[1628337511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24032; }","duration":"128.935519ms","start":"2026-08-28T05:00:33.448791Z","end":"2026-08-28T05:00:33.577726Z","steps":["trace[1628337511] 'agreement among raft nodes before linearized reading' (duration: 128.716234ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:00:33.577466Z","caller":"traceutil/trace.go:171","msg":"trace[449422663] transaction","detail":"{read_only:false; response_revision:24032; number_of_response:1; }","duration":"293.19132ms","start":"2026-08-28T05:00:33.284246Z","end":"2026-08-28T05:00:33.577438Z","steps":["trace[449422663] 'process raft request' (duration: 292.919254ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:00:34.049979Z","caller":"traceutil/trace.go:171","msg":"trace[2140123722] linearizableReadLoop","detail":"{readStateIndex:24861; appliedIndex:24859; }","duration":"139.178741ms","start":"2026-08-28T05:00:33.91078Z","end":"2026-08-28T05:00:34.049959Z","steps":["trace[2140123722] 'read index received' (duration: 137.348461ms)","trace[2140123722] 'applied index is now lower than readState.Index' (duration: 1.82964ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:00:34.0501Z","caller":"traceutil/trace.go:171","msg":"trace[62702079] transaction","detail":"{read_only:false; response_revision:24046; number_of_response:1; }","duration":"204.347724ms","start":"2026-08-28T05:00:33.845742Z","end":"2026-08-28T05:00:34.05009Z","steps":["trace[62702079] 'process raft request' (duration: 204.13672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:00:34.050381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.610459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.71\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-28T05:00:34.050431Z","caller":"traceutil/trace.go:171","msg":"trace[1530761761] range","detail":"{range_begin:/registry/masterleases/162.253.55.71; range_end:; response_count:1; response_revision:24046; }","duration":"139.691671ms","start":"2026-08-28T05:00:33.910721Z","end":"2026-08-28T05:00:34.050413Z","steps":["trace[1530761761] 'agreement among raft nodes before linearized reading' (duration: 139.593189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:00:34.050342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.294265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-28T05:00:34.050589Z","caller":"traceutil/trace.go:171","msg":"trace[730806470] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24046; }","duration":"121.587662ms","start":"2026-08-28T05:00:33.928985Z","end":"2026-08-28T05:00:34.050572Z","steps":["trace[730806470] 'agreement among raft nodes before linearized reading' (duration: 121.193103ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:00:34.050071Z","caller":"traceutil/trace.go:171","msg":"trace[276961573] transaction","detail":"{read_only:false; response_revision:24045; number_of_response:1; }","duration":"257.348687ms","start":"2026-08-28T05:00:33.792698Z","end":"2026-08-28T05:00:34.050047Z","steps":["trace[276961573] 'process raft request' (duration: 255.420396ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:01:50.400193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21566} {"level":"info","ts":"2026-08-28T05:01:50.53061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21566,"took":"126.805223ms","hash":3016328066,"current-db-size-bytes":56401920,"current-db-size":"56 MB","current-db-size-in-use-bytes":43442176,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-28T05:01:50.530701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3016328066,"revision":21566,"compact-revision":19539} {"level":"info","ts":"2026-08-28T05:03:58.696196Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068e1.wal"} {"level":"info","ts":"2026-08-28T05:04:08.299488Z","caller":"traceutil/trace.go:171","msg":"trace[137762865] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"182.56072ms","start":"2026-08-28T05:04:08.116894Z","end":"2026-08-28T05:04:08.299455Z","steps":["trace[137762865] 'process raft request' (duration: 182.346165ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:13.931626Z","caller":"traceutil/trace.go:171","msg":"trace[115044849] linearizableReadLoop","detail":"{readStateIndex:27076; appliedIndex:27075; }","duration":"194.384185ms","start":"2026-08-28T05:04:13.737195Z","end":"2026-08-28T05:04:13.93158Z","steps":["trace[115044849] 'read index received' (duration: 194.301213ms)","trace[115044849] 'applied index is now lower than readState.Index' (duration: 81.802µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:13.931697Z","caller":"traceutil/trace.go:171","msg":"trace[345323003] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"237.000222ms","start":"2026-08-28T05:04:13.694671Z","end":"2026-08-28T05:04:13.931671Z","steps":["trace[345323003] 'process raft request' (duration: 236.746726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:13.931885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.672491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-28T05:04:13.931927Z","caller":"traceutil/trace.go:171","msg":"trace[864100477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26203; }","duration":"194.735862ms","start":"2026-08-28T05:04:13.73718Z","end":"2026-08-28T05:04:13.931916Z","steps":["trace[864100477] 'agreement among raft nodes before linearized reading' (duration: 194.577499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:13.978729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.419976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:13.978821Z","caller":"traceutil/trace.go:171","msg":"trace[814029759] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:26204; }","duration":"149.55413ms","start":"2026-08-28T05:04:13.829245Z","end":"2026-08-28T05:04:13.978799Z","steps":["trace[814029759] 'agreement among raft nodes before linearized reading' (duration: 149.369956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:13.978773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.757391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-28T05:04:13.978923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.175512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:13.979004Z","caller":"traceutil/trace.go:171","msg":"trace[1447272115] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26204; }","duration":"169.272854ms","start":"2026-08-28T05:04:13.809703Z","end":"2026-08-28T05:04:13.978976Z","steps":["trace[1447272115] 'agreement among raft nodes before linearized reading' (duration: 169.153421ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:13.978927Z","caller":"traceutil/trace.go:171","msg":"trace[508909887] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26204; }","duration":"153.972805ms","start":"2026-08-28T05:04:13.824922Z","end":"2026-08-28T05:04:13.978895Z","steps":["trace[508909887] 'agreement among raft nodes before linearized reading' (duration: 153.714629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:13.979111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.367613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T05:04:13.979216Z","caller":"traceutil/trace.go:171","msg":"trace[1395949856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26204; }","duration":"232.520266ms","start":"2026-08-28T05:04:13.74667Z","end":"2026-08-28T05:04:13.97919Z","steps":["trace[1395949856] 'agreement among raft nodes before linearized reading' (duration: 232.2328ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:14.153115Z","caller":"traceutil/trace.go:171","msg":"trace[148263656] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"169.009078ms","start":"2026-08-28T05:04:13.983968Z","end":"2026-08-28T05:04:14.152977Z","steps":["trace[148263656] 'process raft request' (duration: 73.608115ms)","trace[148263656] 'compare' (duration: 94.875982ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:14.153287Z","caller":"traceutil/trace.go:171","msg":"trace[411262013] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"142.399215ms","start":"2026-08-28T05:04:14.010837Z","end":"2026-08-28T05:04:14.153236Z","steps":["trace[411262013] 'process raft request' (duration: 141.963826ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:19.050165Z","caller":"traceutil/trace.go:171","msg":"trace[33641470] linearizableReadLoop","detail":"{readStateIndex:27121; appliedIndex:27120; }","duration":"223.830597ms","start":"2026-08-28T05:04:18.826304Z","end":"2026-08-28T05:04:19.050135Z","steps":["trace[33641470] 'read index received' (duration: 223.644964ms)","trace[33641470] 'applied index is now lower than readState.Index' (duration: 183.643µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:19.050574Z","caller":"traceutil/trace.go:171","msg":"trace[624642726] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"239.566568ms","start":"2026-08-28T05:04:18.81099Z","end":"2026-08-28T05:04:19.050557Z","steps":["trace[624642726] 'process raft request' (duration: 238.932214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.050841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.510124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:19.05089Z","caller":"traceutil/trace.go:171","msg":"trace[2039457315] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:26247; }","duration":"224.614055ms","start":"2026-08-28T05:04:18.82626Z","end":"2026-08-28T05:04:19.050874Z","steps":["trace[2039457315] 'agreement among raft nodes before linearized reading' (duration: 224.514813ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:19.177539Z","caller":"traceutil/trace.go:171","msg":"trace[1672149552] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"220.357984ms","start":"2026-08-28T05:04:18.957166Z","end":"2026-08-28T05:04:19.177524Z","steps":["trace[1672149552] 'process raft request' (duration: 220.225082ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:19.17746Z","caller":"traceutil/trace.go:171","msg":"trace[537072508] linearizableReadLoop","detail":"{readStateIndex:27123; appliedIndex:27121; }","duration":"127.187498ms","start":"2026-08-28T05:04:19.050248Z","end":"2026-08-28T05:04:19.177435Z","steps":["trace[537072508] 'read index received' (duration: 113.83916ms)","trace[537072508] 'applied index is now lower than readState.Index' (duration: 13.347307ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:19.177784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.610532ms","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-28T05:04:19.177867Z","caller":"traceutil/trace.go:171","msg":"trace[463074196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26249; }","duration":"326.741245ms","start":"2026-08-28T05:04:18.85111Z","end":"2026-08-28T05:04:19.177851Z","steps":["trace[463074196] 'agreement among raft nodes before linearized reading' (duration: 326.50562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.177912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:18.851095Z","time spent":"326.808726ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-28T05:04:19.177544Z","caller":"traceutil/trace.go:171","msg":"trace[1219571264] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"252.000435ms","start":"2026-08-28T05:04:18.925479Z","end":"2026-08-28T05:04:19.177479Z","steps":["trace[1219571264] 'process raft request' (duration: 238.592187ms)","trace[1219571264] 'compare' (duration: 13.194904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:19.610206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.561479ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-28T05:04:19.612162Z","caller":"traceutil/trace.go:171","msg":"trace[702940424] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"429.244941ms","start":"2026-08-28T05:04:19.182889Z","end":"2026-08-28T05:04:19.612134Z","steps":["trace[702940424] 'process raft request' (duration: 427.455772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.612289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.182867Z","time spent":"429.353353ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:19.700099Z","caller":"traceutil/trace.go:171","msg":"trace[1916374206] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"506.492153ms","start":"2026-08-28T05:04:19.193559Z","end":"2026-08-28T05:04:19.700051Z","steps":["trace[1916374206] 'process raft request' (duration: 418.563331ms)","trace[1916374206] 'compare' (duration: 87.456532ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:19.700247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.193518Z","time spent":"506.652268ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:19.700232Z","caller":"traceutil/trace.go:171","msg":"trace[44120962] linearizableReadLoop","detail":"{readStateIndex:27126; appliedIndex:27123; }","duration":"501.546248ms","start":"2026-08-28T05:04:19.198648Z","end":"2026-08-28T05:04:19.700195Z","steps":["trace[44120962] 'read index received' (duration: 125.045492ms)","trace[44120962] 'applied index is now lower than readState.Index' (duration: 376.497546ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:19.700409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.705273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:19.700504Z","caller":"traceutil/trace.go:171","msg":"trace[122113947] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26251; }","duration":"501.827975ms","start":"2026-08-28T05:04:19.198635Z","end":"2026-08-28T05:04:19.700463Z","steps":["trace[122113947] 'agreement among raft nodes before linearized reading' (duration: 501.658792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.700566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.1986Z","time spent":"501.947527ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:19.700914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.673919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:19.700971Z","caller":"traceutil/trace.go:171","msg":"trace[1975416571] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26253; }","duration":"448.759441ms","start":"2026-08-28T05:04:19.2522Z","end":"2026-08-28T05:04:19.700959Z","steps":["trace[1975416571] 'agreement among raft nodes before linearized reading' (duration: 448.653878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.701004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.252188Z","time spent":"448.807372ms","remote":"127.0.0.1:33234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"info","ts":"2026-08-28T05:04:19.701064Z","caller":"traceutil/trace.go:171","msg":"trace[1579298189] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"336.105846ms","start":"2026-08-28T05:04:19.364937Z","end":"2026-08-28T05:04:19.701043Z","steps":["trace[1579298189] 'process raft request' (duration: 335.874332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.701194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.364911Z","time spent":"336.209028ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:19.701315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.507773ms","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-28T05:04:19.701375Z","caller":"traceutil/trace.go:171","msg":"trace[1512611371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26253; }","duration":"348.592476ms","start":"2026-08-28T05:04:19.35275Z","end":"2026-08-28T05:04:19.701343Z","steps":["trace[1512611371] 'agreement among raft nodes before linearized reading' (duration: 348.500114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.701401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.352727Z","time spent":"348.667667ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-28T05:04:19.701438Z","caller":"traceutil/trace.go:171","msg":"trace[330677947] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"367.043422ms","start":"2026-08-28T05:04:19.334372Z","end":"2026-08-28T05:04:19.701416Z","steps":["trace[330677947] 'process raft request' (duration: 366.371688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.701552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.33434Z","time spent":"367.158554ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:19.701541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.372529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-28T05:04:19.701839Z","caller":"traceutil/trace.go:171","msg":"trace[1834036456] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26253; }","duration":"321.704226ms","start":"2026-08-28T05:04:19.380122Z","end":"2026-08-28T05:04:19.701827Z","steps":["trace[1834036456] 'agreement among raft nodes before linearized reading' (duration: 321.046322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:19.701877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.380099Z","time spent":"321.768488ms","remote":"127.0.0.1:35744","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":"info","ts":"2026-08-28T05:04:20.02077Z","caller":"traceutil/trace.go:171","msg":"trace[1757137092] linearizableReadLoop","detail":"{readStateIndex:27129; appliedIndex:27128; }","duration":"261.625042ms","start":"2026-08-28T05:04:19.759125Z","end":"2026-08-28T05:04:20.02075Z","steps":["trace[1757137092] 'read index received' (duration: 175.050268ms)","trace[1757137092] 'applied index is now lower than readState.Index' (duration: 86.573874ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:20.020885Z","caller":"traceutil/trace.go:171","msg":"trace[1304404623] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"303.554535ms","start":"2026-08-28T05:04:19.717319Z","end":"2026-08-28T05:04:20.020873Z","steps":["trace[1304404623] 'process raft request' (duration: 216.886869ms)","trace[1304404623] 'compare' (duration: 85.90488ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:20.021031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:19.717297Z","time spent":"303.612426ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:20.021673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.521541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:20.021778Z","caller":"traceutil/trace.go:171","msg":"trace[322124744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26255; }","duration":"262.680875ms","start":"2026-08-28T05:04:19.759071Z","end":"2026-08-28T05:04:20.021752Z","steps":["trace[322124744] 'agreement among raft nodes before linearized reading' (duration: 261.795786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:20.022068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.542154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:20.022136Z","caller":"traceutil/trace.go:171","msg":"trace[1922851074] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26257; }","duration":"250.633795ms","start":"2026-08-28T05:04:19.771483Z","end":"2026-08-28T05:04:20.022116Z","steps":["trace[1922851074] 'agreement among raft nodes before linearized reading' (duration: 250.467752ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:20.209899Z","caller":"traceutil/trace.go:171","msg":"trace[1794371164] linearizableReadLoop","detail":"{readStateIndex:27133; appliedIndex:27132; }","duration":"180.262781ms","start":"2026-08-28T05:04:20.029613Z","end":"2026-08-28T05:04:20.209875Z","steps":["trace[1794371164] 'read index received' (duration: 180.082807ms)","trace[1794371164] 'applied index is now lower than readState.Index' (duration: 178.894µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:20.210019Z","caller":"traceutil/trace.go:171","msg":"trace[937191859] transaction","detail":"{read_only:false; response_revision:26258; number_of_response:1; }","duration":"182.399177ms","start":"2026-08-28T05:04:20.027579Z","end":"2026-08-28T05:04:20.209978Z","steps":["trace[937191859] 'process raft request' (duration: 182.123371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:20.210174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.536408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-28T05:04:20.21022Z","caller":"traceutil/trace.go:171","msg":"trace[315768800] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26258; }","duration":"180.602309ms","start":"2026-08-28T05:04:20.029606Z","end":"2026-08-28T05:04:20.210209Z","steps":["trace[315768800] 'agreement among raft nodes before linearized reading' (duration: 180.412365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:20.519843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.986736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:20.520007Z","caller":"traceutil/trace.go:171","msg":"trace[614498013] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26259; }","duration":"187.143469ms","start":"2026-08-28T05:04:20.332777Z","end":"2026-08-28T05:04:20.51992Z","steps":["trace[614498013] 'count revisions from in-memory index tree' (duration: 186.878144ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:20.521051Z","caller":"traceutil/trace.go:171","msg":"trace[641428695] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"143.906969ms","start":"2026-08-28T05:04:20.377107Z","end":"2026-08-28T05:04:20.521014Z","steps":["trace[641428695] 'process raft request' (duration: 93.021863ms)","trace[641428695] 'compare' (duration: 49.913014ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:20.778877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.166213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:20.778959Z","caller":"traceutil/trace.go:171","msg":"trace[1675025162] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:26260; }","duration":"137.318836ms","start":"2026-08-28T05:04:20.641622Z","end":"2026-08-28T05:04:20.778941Z","steps":["trace[1675025162] 'count revisions from in-memory index tree' (duration: 137.067232ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:21.217601Z","caller":"traceutil/trace.go:171","msg":"trace[2013810967] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"153.937614ms","start":"2026-08-28T05:04:21.063638Z","end":"2026-08-28T05:04:21.217575Z","steps":["trace[2013810967] 'process raft request' (duration: 153.74707ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:21.330149Z","caller":"traceutil/trace.go:171","msg":"trace[1055161645] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"146.296859ms","start":"2026-08-28T05:04:21.183833Z","end":"2026-08-28T05:04:21.33013Z","steps":["trace[1055161645] 'process raft request' (duration: 146.136696ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:21.330313Z","caller":"traceutil/trace.go:171","msg":"trace[1533263028] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"146.174087ms","start":"2026-08-28T05:04:21.184133Z","end":"2026-08-28T05:04:21.330307Z","steps":["trace[1533263028] 'process raft request' (duration: 145.965382ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:21.810385Z","caller":"traceutil/trace.go:171","msg":"trace[824980266] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"105.263166ms","start":"2026-08-28T05:04:21.705076Z","end":"2026-08-28T05:04:21.81034Z","steps":["trace[824980266] 'process raft request' (duration: 103.14026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.135457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.211223ms","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-28T05:04:22.135537Z","caller":"traceutil/trace.go:171","msg":"trace[1041326703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26266; }","duration":"110.334736ms","start":"2026-08-28T05:04:22.025183Z","end":"2026-08-28T05:04:22.135518Z","steps":["trace[1041326703] 'range keys from in-memory index tree' (duration: 110.024499ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:22.182395Z","caller":"traceutil/trace.go:171","msg":"trace[1498949363] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"155.538289ms","start":"2026-08-28T05:04:22.026801Z","end":"2026-08-28T05:04:22.182339Z","steps":["trace[1498949363] 'process raft request' (duration: 155.315074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.595948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.932271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T05:04:22.596206Z","caller":"traceutil/trace.go:171","msg":"trace[2048448323] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"569.109673ms","start":"2026-08-28T05:04:22.027053Z","end":"2026-08-28T05:04:22.596162Z","steps":["trace[2048448323] 'process raft request' (duration: 416.843145ms)","trace[2048448323] 'compare' (duration: 151.710786ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:22.59642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.027037Z","time spent":"569.249446ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:22.596549Z","caller":"traceutil/trace.go:171","msg":"trace[178845797] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"454.937504ms","start":"2026-08-28T05:04:22.141566Z","end":"2026-08-28T05:04:22.596504Z","steps":["trace[178845797] 'process raft request' (duration: 454.617977ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:22.596586Z","caller":"traceutil/trace.go:171","msg":"trace[314923057] linearizableReadLoop","detail":"{readStateIndex:27144; appliedIndex:27141; }","duration":"520.008955ms","start":"2026-08-28T05:04:22.076535Z","end":"2026-08-28T05:04:22.596544Z","steps":["trace[314923057] 'read index received' (duration: 105.744757ms)","trace[314923057] 'applied index is now lower than readState.Index' (duration: 414.258548ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:22.59661Z","caller":"traceutil/trace.go:171","msg":"trace[735087524] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"569.507911ms","start":"2026-08-28T05:04:22.027071Z","end":"2026-08-28T05:04:22.596579Z","steps":["trace[735087524] 'process raft request' (duration: 568.99701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.596703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.158318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T05:04:22.596734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.141547Z","time spent":"455.097377ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:22.596759Z","caller":"traceutil/trace.go:171","msg":"trace[1328778075] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26271; }","duration":"520.228609ms","start":"2026-08-28T05:04:22.076509Z","end":"2026-08-28T05:04:22.596737Z","steps":["trace[1328778075] 'agreement among raft nodes before linearized reading' (duration: 520.140257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.596769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.027057Z","time spent":"569.636553ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:22.596807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.076496Z","time spent":"520.298612ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:22.596873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.884027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T05:04:22.596902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.161175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:22.596924Z","caller":"traceutil/trace.go:171","msg":"trace[1097243250] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:26271; }","duration":"424.03706ms","start":"2026-08-28T05:04:22.172873Z","end":"2026-08-28T05:04:22.59691Z","steps":["trace[1097243250] 'agreement among raft nodes before linearized reading' (duration: 423.894566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.596893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.781295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:22.596945Z","caller":"traceutil/trace.go:171","msg":"trace[279397478] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26271; }","duration":"334.228166ms","start":"2026-08-28T05:04:22.262702Z","end":"2026-08-28T05:04:22.59693Z","steps":["trace[279397478] 'agreement among raft nodes before linearized reading' (duration: 334.149175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.596934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.012377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-28T05:04:22.596971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.172843Z","time spent":"424.115662ms","remote":"127.0.0.1:53790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:22.596988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.262683Z","time spent":"334.297988ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-08-28T05:04:22.597007Z","caller":"traceutil/trace.go:171","msg":"trace[867331128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26271; }","duration":"370.11269ms","start":"2026-08-28T05:04:22.226877Z","end":"2026-08-28T05:04:22.59699Z","steps":["trace[867331128] 'agreement among raft nodes before linearized reading' (duration: 369.937796ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:22.597003Z","caller":"traceutil/trace.go:171","msg":"trace[261202447] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26271; }","duration":"332.944108ms","start":"2026-08-28T05:04:22.264029Z","end":"2026-08-28T05:04:22.596973Z","steps":["trace[261202447] 'agreement among raft nodes before linearized reading' (duration: 332.760764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.597045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.226866Z","time spent":"370.170181ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-28T05:04:22.597047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.885642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-28T05:04:22.597066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.263979Z","time spent":"333.068001ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-08-28T05:04:22.597125Z","caller":"traceutil/trace.go:171","msg":"trace[1623493283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26271; }","duration":"382.026856ms","start":"2026-08-28T05:04:22.215075Z","end":"2026-08-28T05:04:22.597102Z","steps":["trace[1623493283] 'agreement among raft nodes before linearized reading' (duration: 381.824202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:22.597161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:22.215052Z","time spent":"382.101337ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-28T05:04:36.010818Z","caller":"traceutil/trace.go:171","msg":"trace[653954648] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"131.206045ms","start":"2026-08-28T05:04:35.879583Z","end":"2026-08-28T05:04:36.010789Z","steps":["trace[653954648] 'process raft request' (duration: 131.032551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:36.614779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.467132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-28T05:04:36.614863Z","caller":"traceutil/trace.go:171","msg":"trace[606881806] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719; range_end:; response_count:1; response_revision:26427; }","duration":"100.596616ms","start":"2026-08-28T05:04:36.514245Z","end":"2026-08-28T05:04:36.614842Z","steps":["trace[606881806] 'range keys from in-memory index tree' (duration: 100.228017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:37.18703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3387164619701986870,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-28T05:04:37.232018Z","caller":"traceutil/trace.go:171","msg":"trace[807730749] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"609.201287ms","start":"2026-08-28T05:04:36.622788Z","end":"2026-08-28T05:04:37.23199Z","steps":["trace[807730749] 'process raft request' (duration: 609.007563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:37.2322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.622758Z","time spent":"609.326089ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:37.844076Z","caller":"traceutil/trace.go:171","msg":"trace[135110407] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"1.162777873s","start":"2026-08-28T05:04:36.68127Z","end":"2026-08-28T05:04:37.844048Z","steps":["trace[135110407] 'process raft request' (duration: 1.140477703s)","trace[135110407] 'compare' (duration: 22.049235ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:37.844214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.681245Z","time spent":"1.162902926s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:37.844252Z","caller":"traceutil/trace.go:171","msg":"trace[542383064] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"1.160939034s","start":"2026-08-28T05:04:36.683296Z","end":"2026-08-28T05:04:37.844235Z","steps":["trace[542383064] 'process raft request' (duration: 1.160692939s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:37.844344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.683273Z","time spent":"1.161024776s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:37.844486Z","caller":"traceutil/trace.go:171","msg":"trace[836743079] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"1.160035145s","start":"2026-08-28T05:04:36.684423Z","end":"2026-08-28T05:04:37.844458Z","steps":["trace[836743079] 'process raft request' (duration: 1.159753939s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:37.844637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.684402Z","time spent":"1.160153597s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:37.844791Z","caller":"traceutil/trace.go:171","msg":"trace[1059082929] linearizableReadLoop","detail":"{readStateIndex:27310; appliedIndex:27305; }","duration":"1.15846805s","start":"2026-08-28T05:04:36.68608Z","end":"2026-08-28T05:04:37.844548Z","steps":["trace[1059082929] 'read index received' (duration: 545.719719ms)","trace[1059082929] 'applied index is now lower than readState.Index' (duration: 612.747411ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:37.844804Z","caller":"traceutil/trace.go:171","msg":"trace[588342387] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"867.019636ms","start":"2026-08-28T05:04:36.977737Z","end":"2026-08-28T05:04:37.844757Z","steps":["trace[588342387] 'process raft request' (duration: 866.613947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:37.844899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158816269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:37.844937Z","caller":"traceutil/trace.go:171","msg":"trace[739169209] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:26432; }","duration":"1.15888511s","start":"2026-08-28T05:04:36.686039Z","end":"2026-08-28T05:04:37.844924Z","steps":["trace[739169209] 'agreement among raft nodes before linearized reading' (duration: 1.158812918s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:37.844966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.686026Z","time spent":"1.158930322s","remote":"127.0.0.1:35988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:37.844973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.977716Z","time spent":"867.16353ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:38.052789Z","caller":"traceutil/trace.go:171","msg":"trace[1392048728] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"625.406734ms","start":"2026-08-28T05:04:37.427351Z","end":"2026-08-28T05:04:38.052758Z","steps":["trace[1392048728] 'process raft request' (duration: 561.59363ms)","trace[1392048728] 'compare' (duration: 63.405726ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:38.052818Z","caller":"traceutil/trace.go:171","msg":"trace[1449267367] linearizableReadLoop","detail":"{readStateIndex:27312; appliedIndex:27310; }","duration":"207.969397ms","start":"2026-08-28T05:04:37.844825Z","end":"2026-08-28T05:04:38.052794Z","steps":["trace[1449267367] 'read index received' (duration: 144.131922ms)","trace[1449267367] 'applied index is now lower than readState.Index' (duration: 63.836035ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:38.052877Z","caller":"traceutil/trace.go:171","msg":"trace[863299202] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"604.683588ms","start":"2026-08-28T05:04:37.448122Z","end":"2026-08-28T05:04:38.052805Z","steps":["trace[863299202] 'process raft request' (duration: 604.394922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.052923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:37.427328Z","time spent":"625.527517ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:38.052997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293353485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T05:04:38.053049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:37.448105Z","time spent":"604.848453ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:38.053065Z","caller":"traceutil/trace.go:171","msg":"trace[1497130936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26435; }","duration":"1.293458907s","start":"2026-08-28T05:04:36.759583Z","end":"2026-08-28T05:04:38.053042Z","steps":["trace[1497130936] 'agreement among raft nodes before linearized reading' (duration: 1.293357985s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.053087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.114552ms","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":"warn","ts":"2026-08-28T05:04:38.053118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159526074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T05:04:38.053133Z","caller":"traceutil/trace.go:171","msg":"trace[1075487413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26435; }","duration":"326.197763ms","start":"2026-08-28T05:04:37.726921Z","end":"2026-08-28T05:04:38.053119Z","steps":["trace[1075487413] 'agreement among raft nodes before linearized reading' (duration: 325.985769ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.053164Z","caller":"traceutil/trace.go:171","msg":"trace[343691908] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:26435; }","duration":"1.159606506s","start":"2026-08-28T05:04:36.893545Z","end":"2026-08-28T05:04:38.053151Z","steps":["trace[343691908] 'agreement among raft nodes before linearized reading' (duration: 1.159543944s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.053045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174750162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T05:04:38.053199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.893524Z","time spent":"1.159667417s","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-08-28T05:04:38.053212Z","caller":"traceutil/trace.go:171","msg":"trace[702794484] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26435; }","duration":"1.174982937s","start":"2026-08-28T05:04:36.878213Z","end":"2026-08-28T05:04:38.053196Z","steps":["trace[702794484] 'agreement among raft nodes before linearized reading' (duration: 1.174750392s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.053207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244290258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-08-28T05:04:38.053229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268265315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-08-28T05:04:38.053244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34098273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-28T05:04:38.053263Z","caller":"traceutil/trace.go:171","msg":"trace[382417624] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26435; }","duration":"1.268330837s","start":"2026-08-28T05:04:36.784922Z","end":"2026-08-28T05:04:38.053253Z","steps":["trace[382417624] 'agreement among raft nodes before linearized reading' (duration: 1.268222685s)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.053269Z","caller":"traceutil/trace.go:171","msg":"trace[1885402222] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719; range_end:; response_count:1; response_revision:26435; }","duration":"1.244416132s","start":"2026-08-28T05:04:36.808833Z","end":"2026-08-28T05:04:38.053249Z","steps":["trace[1885402222] 'agreement among raft nodes before linearized reading' (duration: 1.244228438s)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.053291Z","caller":"traceutil/trace.go:171","msg":"trace[1547557030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26435; }","duration":"1.341070142s","start":"2026-08-28T05:04:36.712208Z","end":"2026-08-28T05:04:38.053278Z","steps":["trace[1547557030] 'agreement among raft nodes before linearized reading' (duration: 1.34094072s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.053304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.784909Z","time spent":"1.268386858s","remote":"127.0.0.1:35778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-08-28T05:04:38.053316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.808815Z","time spent":"1.244489553s","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719\" "} {"level":"warn","ts":"2026-08-28T05:04:38.053338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.71219Z","time spent":"1.341137865s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-28T05:04:38.053124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.759542Z","time spent":"1.29356948s","remote":"127.0.0.1:35512","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-28T05:04:38.053458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300658852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-28T05:04:38.053251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.878188Z","time spent":"1.175048088s","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-08-28T05:04:38.053536Z","caller":"traceutil/trace.go:171","msg":"trace[1484789131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26435; }","duration":"1.300734764s","start":"2026-08-28T05:04:36.752788Z","end":"2026-08-28T05:04:38.053523Z","steps":["trace[1484789131] 'agreement among raft nodes before linearized reading' (duration: 1.300506839s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.0536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.752745Z","time spent":"1.300846286s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-28T05:04:38.053169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:37.726902Z","time spent":"326.257305ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-28T05:04:38.053743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313204063s","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-28T05:04:38.053839Z","caller":"traceutil/trace.go:171","msg":"trace[702378199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26435; }","duration":"1.313308555s","start":"2026-08-28T05:04:36.740513Z","end":"2026-08-28T05:04:38.053822Z","steps":["trace[702378199] 'agreement among raft nodes before linearized reading' (duration: 1.31309524s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.053875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:36.740478Z","time spent":"1.313388217s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-28T05:04:38.054069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.426872ms","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-28T05:04:38.054213Z","caller":"traceutil/trace.go:171","msg":"trace[675594784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26435; }","duration":"708.603176ms","start":"2026-08-28T05:04:37.345583Z","end":"2026-08-28T05:04:38.054186Z","steps":["trace[675594784] 'agreement among raft nodes before linearized reading' (duration: 708.387541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.054244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:37.345559Z","time spent":"708.676968ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-28T05:04:38.370164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.512179ms","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-28T05:04:38.370318Z","caller":"traceutil/trace.go:171","msg":"trace[1308873938] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26435; }","duration":"123.685402ms","start":"2026-08-28T05:04:38.246607Z","end":"2026-08-28T05:04:38.370293Z","steps":["trace[1308873938] 'range keys from in-memory index tree' (duration: 123.487288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.370871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.5842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T05:04:38.37095Z","caller":"traceutil/trace.go:171","msg":"trace[895061141] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"312.810204ms","start":"2026-08-28T05:04:38.058125Z","end":"2026-08-28T05:04:38.370935Z","steps":["trace[895061141] 'process raft request' (duration: 144.042101ms)","trace[895061141] 'compare' (duration: 168.102849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:38.371013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.058106Z","time spent":"312.877226ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:38.423214Z","caller":"traceutil/trace.go:171","msg":"trace[1687459987] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"364.983188ms","start":"2026-08-28T05:04:38.058184Z","end":"2026-08-28T05:04:38.423167Z","steps":["trace[1687459987] 'process raft request' (duration: 364.782603ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.423304Z","caller":"traceutil/trace.go:171","msg":"trace[1894278991] linearizableReadLoop","detail":"{readStateIndex:27318; appliedIndex:27313; }","duration":"361.966992ms","start":"2026-08-28T05:04:38.061318Z","end":"2026-08-28T05:04:38.423285Z","steps":["trace[1894278991] 'read index received' (duration: 140.865932ms)","trace[1894278991] 'applied index is now lower than readState.Index' (duration: 221.10004ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:38.423365Z","caller":"traceutil/trace.go:171","msg":"trace[1521343570] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"365.173012ms","start":"2026-08-28T05:04:38.058182Z","end":"2026-08-28T05:04:38.423355Z","steps":["trace[1521343570] 'process raft request' (duration: 364.896046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.423419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.05817Z","time spent":"365.133941ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:38.423471Z","caller":"traceutil/trace.go:171","msg":"trace[1133748323] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"352.439988ms","start":"2026-08-28T05:04:38.07102Z","end":"2026-08-28T05:04:38.42346Z","steps":["trace[1133748323] 'process raft request' (duration: 352.186863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.423494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.058156Z","time spent":"365.260824ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:38.423555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.070998Z","time spent":"352.51191ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:38.423618Z","caller":"traceutil/trace.go:171","msg":"trace[425244562] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"223.505782ms","start":"2026-08-28T05:04:38.200102Z","end":"2026-08-28T05:04:38.423607Z","steps":["trace[425244562] 'process raft request' (duration: 223.142654ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.423301Z","caller":"traceutil/trace.go:171","msg":"trace[1049699530] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"362.506894ms","start":"2026-08-28T05:04:38.060762Z","end":"2026-08-28T05:04:38.423269Z","steps":["trace[1049699530] 'process raft request' (duration: 362.372691ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.423436Z","caller":"traceutil/trace.go:171","msg":"trace[1269554480] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"362.527954ms","start":"2026-08-28T05:04:38.060895Z","end":"2026-08-28T05:04:38.423423Z","steps":["trace[1269554480] 'process raft request' (duration: 362.275949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.423684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.060725Z","time spent":"362.931143ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:38.423693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.380912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"warn","ts":"2026-08-28T05:04:38.423725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.060885Z","time spent":"362.807591ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:38.423751Z","caller":"traceutil/trace.go:171","msg":"trace[1799309971] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26442; }","duration":"362.435803ms","start":"2026-08-28T05:04:38.061297Z","end":"2026-08-28T05:04:38.423733Z","steps":["trace[1799309971] 'agreement among raft nodes before linearized reading' (duration: 362.272879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.423789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.061262Z","time spent":"362.518675ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1334,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-08-28T05:04:38.680077Z","caller":"traceutil/trace.go:171","msg":"trace[1087542090] linearizableReadLoop","detail":"{readStateIndex:27321; appliedIndex:27320; }","duration":"256.683506ms","start":"2026-08-28T05:04:38.423356Z","end":"2026-08-28T05:04:38.680039Z","steps":["trace[1087542090] 'read index received' (duration: 236.126613ms)","trace[1087542090] 'applied index is now lower than readState.Index' (duration: 20.554913ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:38.680107Z","caller":"traceutil/trace.go:171","msg":"trace[666220287] transaction","detail":"{read_only:false; response_revision:26443; number_of_response:1; }","duration":"370.095058ms","start":"2026-08-28T05:04:38.309961Z","end":"2026-08-28T05:04:38.680056Z","steps":["trace[666220287] 'process raft request' (duration: 349.662507ms)","trace[666220287] 'compare' (duration: 20.141484ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:38.680266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.580475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:38.680309Z","caller":"traceutil/trace.go:171","msg":"trace[1009636848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26443; }","duration":"616.647436ms","start":"2026-08-28T05:04:38.06365Z","end":"2026-08-28T05:04:38.680297Z","steps":["trace[1009636848] 'agreement among raft nodes before linearized reading' (duration: 616.559864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.680298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.28262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T05:04:38.680358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.372035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-28T05:04:38.680435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.096506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-08-28T05:04:38.680495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.309937Z","time spent":"370.2352ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:38.680502Z","caller":"traceutil/trace.go:171","msg":"trace[305040637] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26443; }","duration":"251.201288ms","start":"2026-08-28T05:04:38.429275Z","end":"2026-08-28T05:04:38.680477Z","steps":["trace[305040637] 'agreement among raft nodes before linearized reading' (duration: 250.951203ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.680503Z","caller":"traceutil/trace.go:171","msg":"trace[1343601257] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:26443; }","duration":"305.541678ms","start":"2026-08-28T05:04:38.374931Z","end":"2026-08-28T05:04:38.680473Z","steps":["trace[1343601257] 'agreement among raft nodes before linearized reading' (duration: 305.175071ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:38.680425Z","caller":"traceutil/trace.go:171","msg":"trace[1178783317] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:26443; }","duration":"552.429523ms","start":"2026-08-28T05:04:38.12797Z","end":"2026-08-28T05:04:38.680399Z","steps":["trace[1178783317] 'agreement among raft nodes before linearized reading' (duration: 552.225308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.680563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.374915Z","time spent":"305.63503ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":40,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:38.680571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.127957Z","time spent":"552.600487ms","remote":"127.0.0.1:46138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:38.680336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:38.06364Z","time spent":"616.689746ms","remote":"127.0.0.1:35512","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-28T05:04:38.957339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.487556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T05:04:38.957489Z","caller":"traceutil/trace.go:171","msg":"trace[797048081] linearizableReadLoop","detail":"{readStateIndex:27322; appliedIndex:27321; }","duration":"266.407275ms","start":"2026-08-28T05:04:38.691066Z","end":"2026-08-28T05:04:38.957474Z","steps":["trace[797048081] 'read index received' (duration: 117.706514ms)","trace[797048081] 'applied index is now lower than readState.Index' (duration: 148.699731ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:38.957575Z","caller":"traceutil/trace.go:171","msg":"trace[2108727775] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"269.996723ms","start":"2026-08-28T05:04:38.687547Z","end":"2026-08-28T05:04:38.957544Z","steps":["trace[2108727775] 'process raft request' (duration: 121.225271ms)","trace[2108727775] 'compare' (duration: 148.358984ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:38.957663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.60421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-08-28T05:04:38.957689Z","caller":"traceutil/trace.go:171","msg":"trace[693311635] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26444; }","duration":"266.65781ms","start":"2026-08-28T05:04:38.691023Z","end":"2026-08-28T05:04:38.957681Z","steps":["trace[693311635] 'agreement among raft nodes before linearized reading' (duration: 266.534318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:38.957747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.951325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:38.957861Z","caller":"traceutil/trace.go:171","msg":"trace[352465954] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26444; }","duration":"253.099249ms","start":"2026-08-28T05:04:38.704742Z","end":"2026-08-28T05:04:38.957841Z","steps":["trace[352465954] 'agreement among raft nodes before linearized reading' (duration: 252.932235ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:39.163735Z","caller":"traceutil/trace.go:171","msg":"trace[1638566695] linearizableReadLoop","detail":"{readStateIndex:27324; appliedIndex:27323; }","duration":"104.923608ms","start":"2026-08-28T05:04:39.05869Z","end":"2026-08-28T05:04:39.163614Z","steps":["trace[1638566695] 'read index received' (duration: 74.305099ms)","trace[1638566695] 'applied index is now lower than readState.Index' (duration: 30.617109ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:39.163735Z","caller":"traceutil/trace.go:171","msg":"trace[1387061242] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"149.09119ms","start":"2026-08-28T05:04:39.014591Z","end":"2026-08-28T05:04:39.163682Z","steps":["trace[1387061242] 'process raft request' (duration: 118.42165ms)","trace[1387061242] 'compare' (duration: 30.426655ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:39.163953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.229365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-28T05:04:39.16405Z","caller":"traceutil/trace.go:171","msg":"trace[1457838550] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719; range_end:; response_count:1; response_revision:26446; }","duration":"105.366397ms","start":"2026-08-28T05:04:39.058658Z","end":"2026-08-28T05:04:39.164025Z","steps":["trace[1457838550] 'agreement among raft nodes before linearized reading' (duration: 105.072401ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:42.094531Z","caller":"traceutil/trace.go:171","msg":"trace[1936163680] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"231.495894ms","start":"2026-08-28T05:04:41.863006Z","end":"2026-08-28T05:04:42.094502Z","steps":["trace[1936163680] 'process raft request' (duration: 230.86606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:42.094675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.879289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T05:04:42.094762Z","caller":"traceutil/trace.go:171","msg":"trace[2044406473] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26465; }","duration":"105.018881ms","start":"2026-08-28T05:04:41.989727Z","end":"2026-08-28T05:04:42.094746Z","steps":["trace[2044406473] 'agreement among raft nodes before linearized reading' (duration: 104.866638ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:42.094549Z","caller":"traceutil/trace.go:171","msg":"trace[262237263] linearizableReadLoop","detail":"{readStateIndex:27344; appliedIndex:27341; }","duration":"104.716675ms","start":"2026-08-28T05:04:41.989783Z","end":"2026-08-28T05:04:42.094499Z","steps":["trace[262237263] 'read index received' (duration: 99.145725ms)","trace[262237263] 'applied index is now lower than readState.Index' (duration: 5.57001ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:42.094566Z","caller":"traceutil/trace.go:171","msg":"trace[513646772] transaction","detail":"{read_only:false; response_revision:26465; number_of_response:1; }","duration":"231.344181ms","start":"2026-08-28T05:04:41.863185Z","end":"2026-08-28T05:04:42.094529Z","steps":["trace[513646772] 'process raft request' (duration: 231.020993ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:42.09399Z","caller":"traceutil/trace.go:171","msg":"trace[1934143627] transaction","detail":"{read_only:false; response_revision:26463; number_of_response:1; }","duration":"234.326305ms","start":"2026-08-28T05:04:41.859636Z","end":"2026-08-28T05:04:42.093963Z","steps":["trace[1934143627] 'process raft request' (duration: 229.277277ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:42.293608Z","caller":"traceutil/trace.go:171","msg":"trace[503287014] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"227.058948ms","start":"2026-08-28T05:04:42.066516Z","end":"2026-08-28T05:04:42.293575Z","steps":["trace[503287014] 'process raft request' (duration: 153.34172ms)","trace[503287014] 'compare' (duration: 73.235027ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:42.293852Z","caller":"traceutil/trace.go:171","msg":"trace[573485425] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"227.282293ms","start":"2026-08-28T05:04:42.066544Z","end":"2026-08-28T05:04:42.293827Z","steps":["trace[573485425] 'process raft request' (duration: 226.957587ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:42.294118Z","caller":"traceutil/trace.go:171","msg":"trace[1032328562] linearizableReadLoop","detail":"{readStateIndex:27347; appliedIndex:27344; }","duration":"116.902097ms","start":"2026-08-28T05:04:42.177205Z","end":"2026-08-28T05:04:42.294107Z","steps":["trace[1032328562] 'read index received' (duration: 42.609927ms)","trace[1032328562] 'applied index is now lower than readState.Index' (duration: 74.28934ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:42.294299Z","caller":"traceutil/trace.go:171","msg":"trace[1613882281] transaction","detail":"{read_only:false; response_revision:26468; number_of_response:1; }","duration":"218.997695ms","start":"2026-08-28T05:04:42.075131Z","end":"2026-08-28T05:04:42.294129Z","steps":["trace[1613882281] 'process raft request' (duration: 218.588976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:42.294476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.249594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-08-28T05:04:42.294661Z","caller":"traceutil/trace.go:171","msg":"trace[605338349] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-008acb6e-9794-4c7a-b2e2-85374a5d8719; range_end:; response_count:1; response_revision:26469; }","duration":"117.479569ms","start":"2026-08-28T05:04:42.177149Z","end":"2026-08-28T05:04:42.294628Z","steps":["trace[605338349] 'agreement among raft nodes before linearized reading' (duration: 117.001099ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:44.683327Z","caller":"traceutil/trace.go:171","msg":"trace[1264585827] linearizableReadLoop","detail":"{readStateIndex:27376; appliedIndex:27375; }","duration":"231.781941ms","start":"2026-08-28T05:04:44.451518Z","end":"2026-08-28T05:04:44.6833Z","steps":["trace[1264585827] 'read index received' (duration: 231.603477ms)","trace[1264585827] 'applied index is now lower than readState.Index' (duration: 177.554µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:44.683378Z","caller":"traceutil/trace.go:171","msg":"trace[1465281119] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"236.445511ms","start":"2026-08-28T05:04:44.446901Z","end":"2026-08-28T05:04:44.683346Z","steps":["trace[1465281119] 'process raft request' (duration: 236.196916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:44.683639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.415069ms","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":"warn","ts":"2026-08-28T05:04:44.683639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.113758ms","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-28T05:04:44.683691Z","caller":"traceutil/trace.go:171","msg":"trace[174739381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26495; }","duration":"229.510491ms","start":"2026-08-28T05:04:44.454163Z","end":"2026-08-28T05:04:44.683674Z","steps":["trace[174739381] 'agreement among raft nodes before linearized reading' (duration: 229.313087ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:44.683701Z","caller":"traceutil/trace.go:171","msg":"trace[1496771048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26495; }","duration":"232.196129ms","start":"2026-08-28T05:04:44.451489Z","end":"2026-08-28T05:04:44.683685Z","steps":["trace[1496771048] 'agreement among raft nodes before linearized reading' (duration: 231.984435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:44.683789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.98955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T05:04:44.683898Z","caller":"traceutil/trace.go:171","msg":"trace[470838576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26495; }","duration":"229.104812ms","start":"2026-08-28T05:04:44.454775Z","end":"2026-08-28T05:04:44.683879Z","steps":["trace[470838576] 'agreement among raft nodes before linearized reading' (duration: 228.886698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:44.683789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.411578ms","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-28T05:04:44.684035Z","caller":"traceutil/trace.go:171","msg":"trace[224528661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26495; }","duration":"228.692174ms","start":"2026-08-28T05:04:44.455323Z","end":"2026-08-28T05:04:44.684015Z","steps":["trace[224528661] 'agreement among raft nodes before linearized reading' (duration: 228.275505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:44.684268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.902802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-08-28T05:04:44.68431Z","caller":"traceutil/trace.go:171","msg":"trace[1647838326] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26495; }","duration":"107.988865ms","start":"2026-08-28T05:04:44.576309Z","end":"2026-08-28T05:04:44.684297Z","steps":["trace[1647838326] 'agreement among raft nodes before linearized reading' (duration: 107.27158ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:44.8041Z","caller":"traceutil/trace.go:171","msg":"trace[1123836319] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"115.144199ms","start":"2026-08-28T05:04:44.688936Z","end":"2026-08-28T05:04:44.80408Z","steps":["trace[1123836319] 'process raft request' (duration: 115.073608ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:44.804134Z","caller":"traceutil/trace.go:171","msg":"trace[1490560879] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"115.274942ms","start":"2026-08-28T05:04:44.68883Z","end":"2026-08-28T05:04:44.804105Z","steps":["trace[1490560879] 'process raft request' (duration: 115.075307ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:44.80427Z","caller":"traceutil/trace.go:171","msg":"trace[1950693683] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"115.246461ms","start":"2026-08-28T05:04:44.689003Z","end":"2026-08-28T05:04:44.804249Z","steps":["trace[1950693683] 'process raft request' (duration: 115.046047ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:44.809811Z","caller":"traceutil/trace.go:171","msg":"trace[506492238] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"109.730462ms","start":"2026-08-28T05:04:44.700026Z","end":"2026-08-28T05:04:44.809756Z","steps":["trace[506492238] 'process raft request' (duration: 109.474407ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:56.320757Z","caller":"traceutil/trace.go:171","msg":"trace[1291532502] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"178.102255ms","start":"2026-08-28T05:04:56.142629Z","end":"2026-08-28T05:04:56.320731Z","steps":["trace[1291532502] 'process raft request' (duration: 177.951672ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:56.326536Z","caller":"traceutil/trace.go:171","msg":"trace[1260760182] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"179.475524ms","start":"2026-08-28T05:04:56.147041Z","end":"2026-08-28T05:04:56.326516Z","steps":["trace[1260760182] 'process raft request' (duration: 179.233959ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:56.326515Z","caller":"traceutil/trace.go:171","msg":"trace[725348510] linearizableReadLoop","detail":"{readStateIndex:27485; appliedIndex:27483; }","duration":"177.683415ms","start":"2026-08-28T05:04:56.148809Z","end":"2026-08-28T05:04:56.326493Z","steps":["trace[725348510] 'read index received' (duration: 172.217977ms)","trace[725348510] 'applied index is now lower than readState.Index' (duration: 5.464538ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:56.326755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.92701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-28T05:04:56.326789Z","caller":"traceutil/trace.go:171","msg":"trace[2079425768] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26602; }","duration":"178.013553ms","start":"2026-08-28T05:04:56.148762Z","end":"2026-08-28T05:04:56.326776Z","steps":["trace[2079425768] 'agreement among raft nodes before linearized reading' (duration: 177.832569ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:56.326951Z","caller":"traceutil/trace.go:171","msg":"trace[787480938] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"178.319619ms","start":"2026-08-28T05:04:56.14862Z","end":"2026-08-28T05:04:56.32694Z","steps":["trace[787480938] 'process raft request' (duration: 177.822069ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:56.731271Z","caller":"traceutil/trace.go:171","msg":"trace[116754408] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"391.465727ms","start":"2026-08-28T05:04:56.339784Z","end":"2026-08-28T05:04:56.73125Z","steps":["trace[116754408] 'process raft request' (duration: 387.755888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:56.731372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.339761Z","time spent":"391.555319ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:57.358662Z","caller":"traceutil/trace.go:171","msg":"trace[121671891] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27485; }","duration":"950.682317ms","start":"2026-08-28T05:04:56.407951Z","end":"2026-08-28T05:04:57.358633Z","steps":["trace[121671891] 'read index received' (duration: 319.618981ms)","trace[121671891] 'applied index is now lower than readState.Index' (duration: 631.062136ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:57.35871Z","caller":"traceutil/trace.go:171","msg":"trace[47969457] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"1.013296304s","start":"2026-08-28T05:04:56.345381Z","end":"2026-08-28T05:04:57.358677Z","steps":["trace[47969457] 'process raft request' (duration: 931.907212ms)","trace[47969457] 'compare' (duration: 80.635026ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:57.358707Z","caller":"traceutil/trace.go:171","msg":"trace[1910294365] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"1.009713898s","start":"2026-08-28T05:04:56.348966Z","end":"2026-08-28T05:04:57.35868Z","steps":["trace[1910294365] 'process raft request' (duration: 1.009578515s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:57.358836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.345357Z","time spent":"1.013416628s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:57.358873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.348955Z","time spent":"1.009830121s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:57.358887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.923412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:57.359042Z","caller":"traceutil/trace.go:171","msg":"trace[939052180] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26605; }","duration":"951.124797ms","start":"2026-08-28T05:04:56.4079Z","end":"2026-08-28T05:04:57.359024Z","steps":["trace[939052180] 'agreement among raft nodes before linearized reading' (duration: 950.901311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:57.35909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.407883Z","time spent":"951.194449ms","remote":"127.0.0.1:40774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:58.036547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.434955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T05:04:58.036763Z","caller":"traceutil/trace.go:171","msg":"trace[1286988212] linearizableReadLoop","detail":"{readStateIndex:27490; appliedIndex:27488; }","duration":"677.998617ms","start":"2026-08-28T05:04:57.358748Z","end":"2026-08-28T05:04:58.036747Z","steps":["trace[1286988212] 'read index received' (duration: 131.290627ms)","trace[1286988212] 'applied index is now lower than readState.Index' (duration: 546.70707ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:58.036864Z","caller":"traceutil/trace.go:171","msg":"trace[1246953973] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"1.180412973s","start":"2026-08-28T05:04:56.85638Z","end":"2026-08-28T05:04:58.036793Z","steps":["trace[1246953973] 'process raft request' (duration: 633.643181ms)","trace[1246953973] 'compare' (duration: 545.756ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:04:58.036836Z","caller":"traceutil/trace.go:171","msg":"trace[990460264] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"731.405937ms","start":"2026-08-28T05:04:57.305394Z","end":"2026-08-28T05:04:58.0368Z","steps":["trace[990460264] 'process raft request' (duration: 731.258274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.036887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.590552ms","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:6"} {"level":"info","ts":"2026-08-28T05:04:58.036944Z","caller":"traceutil/trace.go:171","msg":"trace[499390413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26607; }","duration":"909.692826ms","start":"2026-08-28T05:04:57.127236Z","end":"2026-08-28T05:04:58.036929Z","steps":["trace[499390413] 'agreement among raft nodes before linearized reading' (duration: 909.567712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.036936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012660652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-79cbcbc845-krsl2.18cfddfb3515c6c4\" ","response":"range_response_count:1 size:2097"} {"level":"warn","ts":"2026-08-28T05:04:58.036976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:57.127226Z","time spent":"909.740507ms","remote":"127.0.0.1:40838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-28T05:04:58.036981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.856359Z","time spent":"1.180562558s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:58.037004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17097486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-28T05:04:58.037004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174554268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-28T05:04:58.037032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289790398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-28T05:04:58.037045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:57.305368Z","time spent":"731.578191ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:58.037062Z","caller":"traceutil/trace.go:171","msg":"trace[846752779] range","detail":"{range_begin:/registry/events/openstack/glance-api-79cbcbc845-krsl2.18cfddfb3515c6c4; range_end:; response_count:1; response_revision:26607; }","duration":"1.012737284s","start":"2026-08-28T05:04:57.024249Z","end":"2026-08-28T05:04:58.036986Z","steps":["trace[846752779] 'agreement among raft nodes before linearized reading' (duration: 1.012516558s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.037063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238405043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:58.037077Z","caller":"traceutil/trace.go:171","msg":"trace[1381791574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26607; }","duration":"1.174702141s","start":"2026-08-28T05:04:56.862357Z","end":"2026-08-28T05:04:58.037059Z","steps":["trace[1381791574] 'agreement among raft nodes before linearized reading' (duration: 1.174519477s)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.037094Z","caller":"traceutil/trace.go:171","msg":"trace[2044146840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26607; }","duration":"1.289891041s","start":"2026-08-28T05:04:56.747186Z","end":"2026-08-28T05:04:58.037077Z","steps":["trace[2044146840] 'agreement among raft nodes before linearized reading' (duration: 1.289696136s)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.037117Z","caller":"traceutil/trace.go:171","msg":"trace[97377277] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26607; }","duration":"1.238493524s","start":"2026-08-28T05:04:56.798608Z","end":"2026-08-28T05:04:58.037102Z","steps":["trace[97377277] 'agreement among raft nodes before linearized reading' (duration: 1.238390922s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.0371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297615018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-28T05:04:58.037138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.747167Z","time spent":"1.289959723s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-28T05:04:58.037164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.798594Z","time spent":"1.238557576s","remote":"127.0.0.1:35792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-28T05:04:58.037178Z","caller":"traceutil/trace.go:171","msg":"trace[1225175170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26607; }","duration":"1.29772638s","start":"2026-08-28T05:04:56.739435Z","end":"2026-08-28T05:04:58.037161Z","steps":["trace[1225175170] 'agreement among raft nodes before linearized reading' (duration: 1.297483914s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.037181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179876623s","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":"warn","ts":"2026-08-28T05:04:58.037223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.73942Z","time spent":"1.297795411s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-28T05:04:58.037228Z","caller":"traceutil/trace.go:171","msg":"trace[1838639425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26607; }","duration":"1.179922034s","start":"2026-08-28T05:04:56.857291Z","end":"2026-08-28T05:04:58.037213Z","steps":["trace[1838639425] 'agreement among raft nodes before linearized reading' (duration: 1.179524554s)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.037065Z","caller":"traceutil/trace.go:171","msg":"trace[727709046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26607; }","duration":"1.171073192s","start":"2026-08-28T05:04:56.865975Z","end":"2026-08-28T05:04:58.037048Z","steps":["trace[727709046] 'agreement among raft nodes before linearized reading' (duration: 1.170844516s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.037267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.857248Z","time spent":"1.180008646s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-28T05:04:58.037283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.865962Z","time spent":"1.171311837s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-28T05:04:58.037126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:56.862337Z","time spent":"1.174776893s","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-28T05:04:58.037113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:57.024215Z","time spent":"1.012885327s","remote":"127.0.0.1:35612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":2119,"request content":"key:\"/registry/events/openstack/glance-api-79cbcbc845-krsl2.18cfddfb3515c6c4\" "} {"level":"warn","ts":"2026-08-28T05:04:58.03702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020535732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419069"} {"level":"info","ts":"2026-08-28T05:04:58.037635Z","caller":"traceutil/trace.go:171","msg":"trace[642116014] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26607; }","duration":"1.021170025s","start":"2026-08-28T05:04:57.016439Z","end":"2026-08-28T05:04:58.037609Z","steps":["trace[642116014] 'agreement among raft nodes before linearized reading' (duration: 1.020372697s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.037717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:57.016421Z","time spent":"1.021276568s","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":419091,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-08-28T05:04:58.045948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.460163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T05:04:58.04601Z","caller":"traceutil/trace.go:171","msg":"trace[506317334] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26608; }","duration":"643.569186ms","start":"2026-08-28T05:04:57.402427Z","end":"2026-08-28T05:04:58.045997Z","steps":["trace[506317334] 'agreement among raft nodes before linearized reading' (duration: 643.429943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.046006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.329867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T05:04:58.046043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:57.402391Z","time spent":"643.643617ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-08-28T05:04:58.045921Z","caller":"traceutil/trace.go:171","msg":"trace[1559741652] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"107.312541ms","start":"2026-08-28T05:04:57.938578Z","end":"2026-08-28T05:04:58.04589Z","steps":["trace[1559741652] 'process raft request' (duration: 107.106917ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.046074Z","caller":"traceutil/trace.go:171","msg":"trace[1969439568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26608; }","duration":"287.380638ms","start":"2026-08-28T05:04:57.758661Z","end":"2026-08-28T05:04:58.046041Z","steps":["trace[1969439568] 'agreement among raft nodes before linearized reading' (duration: 287.247975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.348266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.054938ms","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-28T05:04:58.348461Z","caller":"traceutil/trace.go:171","msg":"trace[41024520] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26608; }","duration":"102.271813ms","start":"2026-08-28T05:04:58.246164Z","end":"2026-08-28T05:04:58.348435Z","steps":["trace[41024520] 'range keys from in-memory index tree' (duration: 102.031207ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.370795Z","caller":"traceutil/trace.go:171","msg":"trace[279120962] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"329.132307ms","start":"2026-08-28T05:04:58.041631Z","end":"2026-08-28T05:04:58.370763Z","steps":["trace[279120962] 'process raft request' (duration: 328.891011ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.370833Z","caller":"traceutil/trace.go:171","msg":"trace[264502552] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"328.080494ms","start":"2026-08-28T05:04:58.042727Z","end":"2026-08-28T05:04:58.370807Z","steps":["trace[264502552] 'process raft request' (duration: 328.005892ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.370871Z","caller":"traceutil/trace.go:171","msg":"trace[1466368623] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"328.482903ms","start":"2026-08-28T05:04:58.042358Z","end":"2026-08-28T05:04:58.370841Z","steps":["trace[1466368623] 'process raft request' (duration: 328.32007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.370983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:58.041611Z","time spent":"329.266279ms","remote":"127.0.0.1:35612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:58.370987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:58.042695Z","time spent":"328.201905ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:58.371088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:58.042346Z","time spent":"328.661587ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:58.371111Z","caller":"traceutil/trace.go:171","msg":"trace[171882202] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"327.780437ms","start":"2026-08-28T05:04:58.043311Z","end":"2026-08-28T05:04:58.371091Z","steps":["trace[171882202] 'process raft request' (duration: 327.4556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.371216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:58.043295Z","time spent":"327.873649ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T05:04:58.371288Z","caller":"traceutil/trace.go:171","msg":"trace[1463655748] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"327.883939ms","start":"2026-08-28T05:04:58.04338Z","end":"2026-08-28T05:04:58.371264Z","steps":["trace[1463655748] 'process raft request' (duration: 327.607143ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.371383Z","caller":"traceutil/trace.go:171","msg":"trace[310509582] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"327.582192ms","start":"2026-08-28T05:04:58.043787Z","end":"2026-08-28T05:04:58.371369Z","steps":["trace[310509582] 'process raft request' (duration: 327.419929ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.371369Z","caller":"traceutil/trace.go:171","msg":"trace[2058335585] linearizableReadLoop","detail":"{readStateIndex:27497; appliedIndex:27491; }","duration":"323.831842ms","start":"2026-08-28T05:04:58.047518Z","end":"2026-08-28T05:04:58.37135Z","steps":["trace[2058335585] 'read index received' (duration: 323.057306ms)","trace[2058335585] 'applied index is now lower than readState.Index' (duration: 773.436µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:04:58.371456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:58.043354Z","time spent":"328.012161ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:58.371477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:58.04377Z","time spent":"327.676385ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T05:04:58.371563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.045486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:58.371617Z","caller":"traceutil/trace.go:171","msg":"trace[1694564900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26614; }","duration":"324.129548ms","start":"2026-08-28T05:04:58.047471Z","end":"2026-08-28T05:04:58.371601Z","steps":["trace[1694564900] 'agreement among raft nodes before linearized reading' (duration: 324.025336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:04:58.371656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T05:04:58.047449Z","time spent":"324.1957ms","remote":"127.0.0.1:35508","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-28T05:04:58.40846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.66889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-28T05:04:58.408464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.269973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:04:58.408516Z","caller":"traceutil/trace.go:171","msg":"trace[1892685202] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26617; }","duration":"148.730632ms","start":"2026-08-28T05:04:58.259772Z","end":"2026-08-28T05:04:58.408502Z","steps":["trace[1892685202] 'agreement among raft nodes before linearized reading' (duration: 148.615159ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:04:58.408539Z","caller":"traceutil/trace.go:171","msg":"trace[1740465804] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26617; }","duration":"188.411416ms","start":"2026-08-28T05:04:58.220109Z","end":"2026-08-28T05:04:58.40852Z","steps":["trace[1740465804] 'agreement among raft nodes before linearized reading' (duration: 188.234272ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:00.895683Z","caller":"traceutil/trace.go:171","msg":"trace[621420763] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"109.308274ms","start":"2026-08-28T05:05:00.786355Z","end":"2026-08-28T05:05:00.895663Z","steps":["trace[621420763] 'process raft request' (duration: 109.214942ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:01.023148Z","caller":"traceutil/trace.go:171","msg":"trace[2155372] transaction","detail":"{read_only:false; response_revision:26658; number_of_response:1; }","duration":"119.065993ms","start":"2026-08-28T05:05:00.904055Z","end":"2026-08-28T05:05:01.023121Z","steps":["trace[2155372] 'process raft request' (duration: 98.00602ms)","trace[2155372] 'compare' (duration: 20.88638ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:05:01.300744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.265296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:05:01.300871Z","caller":"traceutil/trace.go:171","msg":"trace[174584376] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:26658; }","duration":"104.4761ms","start":"2026-08-28T05:05:01.196367Z","end":"2026-08-28T05:05:01.300844Z","steps":["trace[174584376] 'count revisions from in-memory index tree' (duration: 104.110992ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:01.605093Z","caller":"traceutil/trace.go:171","msg":"trace[486996243] transaction","detail":"{read_only:false; response_revision:26659; number_of_response:1; }","duration":"210.922271ms","start":"2026-08-28T05:05:01.394146Z","end":"2026-08-28T05:05:01.605068Z","steps":["trace[486996243] 'process raft request' (duration: 210.777808ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:01.606055Z","caller":"traceutil/trace.go:171","msg":"trace[1955713276] linearizableReadLoop","detail":"{readStateIndex:27545; appliedIndex:27544; }","duration":"198.444673ms","start":"2026-08-28T05:05:01.407586Z","end":"2026-08-28T05:05:01.60603Z","steps":["trace[1955713276] 'read index received' (duration: 197.586934ms)","trace[1955713276] 'applied index is now lower than readState.Index' (duration: 856.679µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:01.606255Z","caller":"traceutil/trace.go:171","msg":"trace[1458599152] transaction","detail":"{read_only:false; response_revision:26660; number_of_response:1; }","duration":"200.238481ms","start":"2026-08-28T05:05:01.406005Z","end":"2026-08-28T05:05:01.606244Z","steps":["trace[1458599152] 'process raft request' (duration: 199.898594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:01.606521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.885487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-28T05:05:01.606555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.926573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:05:01.606617Z","caller":"traceutil/trace.go:171","msg":"trace[2047571005] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26660; }","duration":"199.053376ms","start":"2026-08-28T05:05:01.407548Z","end":"2026-08-28T05:05:01.606601Z","steps":["trace[2047571005] 'agreement among raft nodes before linearized reading' (duration: 198.914213ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:01.606616Z","caller":"traceutil/trace.go:171","msg":"trace[1683252671] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26660; }","duration":"163.02844ms","start":"2026-08-28T05:05:01.443562Z","end":"2026-08-28T05:05:01.606591Z","steps":["trace[1683252671] 'agreement among raft nodes before linearized reading' (duration: 162.806785ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:03.240391Z","caller":"traceutil/trace.go:171","msg":"trace[421455932] transaction","detail":"{read_only:false; response_revision:26679; number_of_response:1; }","duration":"150.177973ms","start":"2026-08-28T05:05:03.090184Z","end":"2026-08-28T05:05:03.240362Z","steps":["trace[421455932] 'process raft request' (duration: 149.902887ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:03.423812Z","caller":"traceutil/trace.go:171","msg":"trace[604009510] linearizableReadLoop","detail":"{readStateIndex:27565; appliedIndex:27564; }","duration":"101.642358ms","start":"2026-08-28T05:05:03.322148Z","end":"2026-08-28T05:05:03.423791Z","steps":["trace[604009510] 'read index received' (duration: 101.432383ms)","trace[604009510] 'applied index is now lower than readState.Index' (duration: 209.075µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:03.423882Z","caller":"traceutil/trace.go:171","msg":"trace[913064783] transaction","detail":"{read_only:false; response_revision:26680; number_of_response:1; }","duration":"172.511803ms","start":"2026-08-28T05:05:03.251339Z","end":"2026-08-28T05:05:03.423851Z","steps":["trace[913064783] 'process raft request' (duration: 172.312059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:03.423941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.77347ms","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:6"} {"level":"info","ts":"2026-08-28T05:05:03.423988Z","caller":"traceutil/trace.go:171","msg":"trace[1166527340] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:26680; }","duration":"101.866954ms","start":"2026-08-28T05:05:03.322108Z","end":"2026-08-28T05:05:03.423975Z","steps":["trace[1166527340] 'agreement among raft nodes before linearized reading' (duration: 101.773101ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:07.914168Z","caller":"traceutil/trace.go:171","msg":"trace[1939725133] linearizableReadLoop","detail":"{readStateIndex:27621; appliedIndex:27620; }","duration":"145.487232ms","start":"2026-08-28T05:05:07.768632Z","end":"2026-08-28T05:05:07.914119Z","steps":["trace[1939725133] 'read index received' (duration: 98.121082ms)","trace[1939725133] 'applied index is now lower than readState.Index' (duration: 47.36512ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:07.91423Z","caller":"traceutil/trace.go:171","msg":"trace[561678807] transaction","detail":"{read_only:false; response_revision:26735; number_of_response:1; }","duration":"206.70488ms","start":"2026-08-28T05:05:07.707482Z","end":"2026-08-28T05:05:07.914187Z","steps":["trace[561678807] 'process raft request' (duration: 159.277109ms)","trace[561678807] 'compare' (duration: 47.216786ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:05:07.914281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.633035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:05:07.914317Z","caller":"traceutil/trace.go:171","msg":"trace[798729158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26735; }","duration":"145.756718ms","start":"2026-08-28T05:05:07.768545Z","end":"2026-08-28T05:05:07.914302Z","steps":["trace[798729158] 'agreement among raft nodes before linearized reading' (duration: 145.684456ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:17.095107Z","caller":"traceutil/trace.go:171","msg":"trace[857784704] linearizableReadLoop","detail":"{readStateIndex:27715; appliedIndex:27714; }","duration":"152.532634ms","start":"2026-08-28T05:05:16.942549Z","end":"2026-08-28T05:05:17.095082Z","steps":["trace[857784704] 'read index received' (duration: 152.283549ms)","trace[857784704] 'applied index is now lower than readState.Index' (duration: 248.195µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T05:05:17.095268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.701588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:05:17.095319Z","caller":"traceutil/trace.go:171","msg":"trace[465175158] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26827; }","duration":"152.80292ms","start":"2026-08-28T05:05:16.9425Z","end":"2026-08-28T05:05:17.095303Z","steps":["trace[465175158] 'agreement among raft nodes before linearized reading' (duration: 152.686357ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:17.095587Z","caller":"traceutil/trace.go:171","msg":"trace[1086524434] transaction","detail":"{read_only:false; response_revision:26827; number_of_response:1; }","duration":"169.53445ms","start":"2026-08-28T05:05:16.925991Z","end":"2026-08-28T05:05:17.095526Z","steps":["trace[1086524434] 'process raft request' (duration: 168.946238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:18.752636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.018584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T05:05:18.752855Z","caller":"traceutil/trace.go:171","msg":"trace[2052540739] linearizableReadLoop","detail":"{readStateIndex:27722; appliedIndex:27720; }","duration":"265.923004ms","start":"2026-08-28T05:05:18.486916Z","end":"2026-08-28T05:05:18.752839Z","steps":["trace[2052540739] 'read index received' (duration: 34.317428ms)","trace[2052540739] 'applied index is now lower than readState.Index' (duration: 231.604596ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:18.7531Z","caller":"traceutil/trace.go:171","msg":"trace[1491994510] transaction","detail":"{read_only:false; response_revision:26837; number_of_response:1; }","duration":"256.205177ms","start":"2026-08-28T05:05:18.496862Z","end":"2026-08-28T05:05:18.753067Z","steps":["trace[1491994510] 'process raft request' (duration: 255.93941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:18.753155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.234262ms","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-28T05:05:18.753192Z","caller":"traceutil/trace.go:171","msg":"trace[619904744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26837; }","duration":"266.307174ms","start":"2026-08-28T05:05:18.486872Z","end":"2026-08-28T05:05:18.753179Z","steps":["trace[619904744] 'agreement among raft nodes before linearized reading' (duration: 266.189772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:18.753385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.645339ms","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-28T05:05:18.753449Z","caller":"traceutil/trace.go:171","msg":"trace[1554611050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26837; }","duration":"258.723661ms","start":"2026-08-28T05:05:18.494692Z","end":"2026-08-28T05:05:18.753416Z","steps":["trace[1554611050] 'agreement among raft nodes before linearized reading' (duration: 258.629539ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:18.75346Z","caller":"traceutil/trace.go:171","msg":"trace[1612194878] transaction","detail":"{read_only:false; response_revision:26834; number_of_response:1; }","duration":"267.773115ms","start":"2026-08-28T05:05:18.485639Z","end":"2026-08-28T05:05:18.753412Z","steps":["trace[1612194878] 'process raft request' (duration: 113.895422ms)","trace[1612194878] 'compare' (duration: 152.496903ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:18.753561Z","caller":"traceutil/trace.go:171","msg":"trace[240263574] transaction","detail":"{read_only:false; response_revision:26835; number_of_response:1; }","duration":"258.216239ms","start":"2026-08-28T05:05:18.495335Z","end":"2026-08-28T05:05:18.753551Z","steps":["trace[240263574] 'process raft request' (duration: 257.38413ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:18.753635Z","caller":"traceutil/trace.go:171","msg":"trace[1496194451] transaction","detail":"{read_only:false; response_revision:26836; number_of_response:1; }","duration":"257.577996ms","start":"2026-08-28T05:05:18.49605Z","end":"2026-08-28T05:05:18.753628Z","steps":["trace[1496194451] 'process raft request' (duration: 256.718686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:18.753755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.158314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T05:05:18.753826Z","caller":"traceutil/trace.go:171","msg":"trace[1974003688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26837; }","duration":"249.234716ms","start":"2026-08-28T05:05:18.504548Z","end":"2026-08-28T05:05:18.753783Z","steps":["trace[1974003688] 'agreement among raft nodes before linearized reading' (duration: 249.145914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:18.754092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.786622ms","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-28T05:05:18.754139Z","caller":"traceutil/trace.go:171","msg":"trace[642065312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26837; }","duration":"258.863464ms","start":"2026-08-28T05:05:18.495263Z","end":"2026-08-28T05:05:18.754127Z","steps":["trace[642065312] 'agreement among raft nodes before linearized reading' (duration: 258.694731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:19.032348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.495545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T05:05:19.032666Z","caller":"traceutil/trace.go:171","msg":"trace[1967255167] transaction","detail":"{read_only:false; response_revision:26839; number_of_response:1; }","duration":"273.161901ms","start":"2026-08-28T05:05:18.759479Z","end":"2026-08-28T05:05:19.032641Z","steps":["trace[1967255167] 'process raft request' (duration: 273.025858ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:19.032645Z","caller":"traceutil/trace.go:171","msg":"trace[1823626548] transaction","detail":"{read_only:false; response_revision:26838; number_of_response:1; }","duration":"273.372955ms","start":"2026-08-28T05:05:18.759235Z","end":"2026-08-28T05:05:19.032608Z","steps":["trace[1823626548] 'process raft request' (duration: 171.521882ms)","trace[1823626548] 'compare' (duration: 101.367923ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:19.032901Z","caller":"traceutil/trace.go:171","msg":"trace[58857651] linearizableReadLoop","detail":"{readStateIndex:27729; appliedIndex:27725; }","duration":"270.160506ms","start":"2026-08-28T05:05:18.762726Z","end":"2026-08-28T05:05:19.032886Z","steps":["trace[58857651] 'read index received' (duration: 168.115569ms)","trace[58857651] 'applied index is now lower than readState.Index' (duration: 102.044097ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:19.032931Z","caller":"traceutil/trace.go:171","msg":"trace[2005948732] transaction","detail":"{read_only:false; response_revision:26840; number_of_response:1; }","duration":"273.377695ms","start":"2026-08-28T05:05:18.759535Z","end":"2026-08-28T05:05:19.032913Z","steps":["trace[2005948732] 'process raft request' (duration: 273.054319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:19.032973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.234477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:05:19.032996Z","caller":"traceutil/trace.go:171","msg":"trace[1334095448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26841; }","duration":"270.294319ms","start":"2026-08-28T05:05:18.762695Z","end":"2026-08-28T05:05:19.032989Z","steps":["trace[1334095448] 'agreement among raft nodes before linearized reading' (duration: 270.241188ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:19.033021Z","caller":"traceutil/trace.go:171","msg":"trace[594417480] transaction","detail":"{read_only:false; response_revision:26841; number_of_response:1; }","duration":"271.30081ms","start":"2026-08-28T05:05:18.761646Z","end":"2026-08-28T05:05:19.032946Z","steps":["trace[594417480] 'process raft request' (duration: 271.148497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:21.282762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.851599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:05:21.282881Z","caller":"traceutil/trace.go:171","msg":"trace[48819601] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:26857; }","duration":"165.024503ms","start":"2026-08-28T05:05:21.117836Z","end":"2026-08-28T05:05:21.282861Z","steps":["trace[48819601] 'count revisions from in-memory index tree' (duration: 164.768918ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:21.285864Z","caller":"traceutil/trace.go:171","msg":"trace[2141461514] transaction","detail":"{read_only:false; response_revision:26858; number_of_response:1; }","duration":"124.92315ms","start":"2026-08-28T05:05:21.160905Z","end":"2026-08-28T05:05:21.285828Z","steps":["trace[2141461514] 'process raft request' (duration: 124.601263ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:22.034462Z","caller":"traceutil/trace.go:171","msg":"trace[2041645325] transaction","detail":"{read_only:false; response_revision:26859; number_of_response:1; }","duration":"181.294213ms","start":"2026-08-28T05:05:21.853117Z","end":"2026-08-28T05:05:22.034411Z","steps":["trace[2041645325] 'process raft request' (duration: 181.14025ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:24.275882Z","caller":"traceutil/trace.go:171","msg":"trace[145385955] transaction","detail":"{read_only:false; response_revision:26875; number_of_response:1; }","duration":"108.865595ms","start":"2026-08-28T05:05:24.166989Z","end":"2026-08-28T05:05:24.275855Z","steps":["trace[145385955] 'process raft request' (duration: 85.211676ms)","trace[145385955] 'compare' (duration: 23.527646ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T05:05:24.341185Z","caller":"traceutil/trace.go:171","msg":"trace[687106] transaction","detail":"{read_only:false; response_revision:26876; number_of_response:1; }","duration":"172.758589ms","start":"2026-08-28T05:05:24.168404Z","end":"2026-08-28T05:05:24.341163Z","steps":["trace[687106] 'process raft request' (duration: 172.297439ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:24.341272Z","caller":"traceutil/trace.go:171","msg":"trace[696792430] transaction","detail":"{read_only:false; response_revision:26877; number_of_response:1; }","duration":"167.803192ms","start":"2026-08-28T05:05:24.173412Z","end":"2026-08-28T05:05:24.341215Z","steps":["trace[696792430] 'process raft request' (duration: 167.425854ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:34.498673Z","caller":"traceutil/trace.go:171","msg":"trace[1420258625] transaction","detail":"{read_only:false; response_revision:26951; number_of_response:1; }","duration":"179.913053ms","start":"2026-08-28T05:05:34.318727Z","end":"2026-08-28T05:05:34.49864Z","steps":["trace[1420258625] 'process raft request' (duration: 179.75964ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:34.523753Z","caller":"traceutil/trace.go:171","msg":"trace[2095383317] transaction","detail":"{read_only:false; response_revision:26952; number_of_response:1; }","duration":"126.982173ms","start":"2026-08-28T05:05:34.396741Z","end":"2026-08-28T05:05:34.523724Z","steps":["trace[2095383317] 'process raft request' (duration: 126.799729ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:34.523926Z","caller":"traceutil/trace.go:171","msg":"trace[208814254] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"104.094231ms","start":"2026-08-28T05:05:34.419801Z","end":"2026-08-28T05:05:34.523895Z","steps":["trace[208814254] 'process raft request' (duration: 103.880676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:05:34.680839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.25438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T05:05:34.680935Z","caller":"traceutil/trace.go:171","msg":"trace[945884090] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:26953; }","duration":"101.394683ms","start":"2026-08-28T05:05:34.579509Z","end":"2026-08-28T05:05:34.680903Z","steps":["trace[945884090] 'count revisions from in-memory index tree' (duration: 101.118356ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:42.638702Z","caller":"traceutil/trace.go:171","msg":"trace[1509560994] transaction","detail":"{read_only:false; response_revision:27007; number_of_response:1; }","duration":"109.074278ms","start":"2026-08-28T05:05:42.529596Z","end":"2026-08-28T05:05:42.63867Z","steps":["trace[1509560994] 'process raft request' (duration: 108.879934ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:42.664179Z","caller":"traceutil/trace.go:171","msg":"trace[1232146245] transaction","detail":"{read_only:false; response_revision:27008; number_of_response:1; }","duration":"104.149501ms","start":"2026-08-28T05:05:42.559996Z","end":"2026-08-28T05:05:42.664145Z","steps":["trace[1232146245] 'process raft request' (duration: 103.974618ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:05:42.664255Z","caller":"traceutil/trace.go:171","msg":"trace[2065490276] transaction","detail":"{read_only:false; response_revision:27009; number_of_response:1; }","duration":"102.481816ms","start":"2026-08-28T05:05:42.56174Z","end":"2026-08-28T05:05:42.664221Z","steps":["trace[2065490276] 'process raft request' (duration: 102.335503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:06:01.420615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.29565ms","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-28T05:06:01.420727Z","caller":"traceutil/trace.go:171","msg":"trace[937778639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27301; }","duration":"114.468784ms","start":"2026-08-28T05:06:01.306232Z","end":"2026-08-28T05:06:01.4207Z","steps":["trace[937778639] 'range keys from in-memory index tree' (duration: 114.072835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:06:01.421068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.41519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T05:06:01.421124Z","caller":"traceutil/trace.go:171","msg":"trace[1818976213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27301; }","duration":"125.471491ms","start":"2026-08-28T05:06:01.295637Z","end":"2026-08-28T05:06:01.421108Z","steps":["trace[1818976213] 'range keys from in-memory index tree' (duration: 125.314058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:06:01.421234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.764102ms","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-28T05:06:01.421371Z","caller":"traceutil/trace.go:171","msg":"trace[936676734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27301; }","duration":"128.950786ms","start":"2026-08-28T05:06:01.292399Z","end":"2026-08-28T05:06:01.42135Z","steps":["trace[936676734] 'range keys from in-memory index tree' (duration: 128.475506ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:06:01.547138Z","caller":"traceutil/trace.go:171","msg":"trace[1034565613] transaction","detail":"{read_only:false; response_revision:27302; number_of_response:1; }","duration":"121.772152ms","start":"2026-08-28T05:06:01.425341Z","end":"2026-08-28T05:06:01.547114Z","steps":["trace[1034565613] 'process raft request' (duration: 121.643709ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:06:01.569133Z","caller":"traceutil/trace.go:171","msg":"trace[1624691200] transaction","detail":"{read_only:false; response_revision:27305; number_of_response:1; }","duration":"103.961928ms","start":"2026-08-28T05:06:01.465151Z","end":"2026-08-28T05:06:01.569113Z","steps":["trace[1624691200] 'process raft request' (duration: 103.923347ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:06:01.569473Z","caller":"traceutil/trace.go:171","msg":"trace[820263352] transaction","detail":"{read_only:false; response_revision:27303; number_of_response:1; }","duration":"144.050532ms","start":"2026-08-28T05:06:01.425405Z","end":"2026-08-28T05:06:01.569455Z","steps":["trace[820263352] 'process raft request' (duration: 143.54282ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:06:01.569699Z","caller":"traceutil/trace.go:171","msg":"trace[1872346520] transaction","detail":"{read_only:false; response_revision:27304; number_of_response:1; }","duration":"144.274087ms","start":"2026-08-28T05:06:01.425413Z","end":"2026-08-28T05:06:01.569687Z","steps":["trace[1872346520] 'process raft request' (duration: 143.618412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T05:06:01.799998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.327894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T05:06:01.800073Z","caller":"traceutil/trace.go:171","msg":"trace[1551218984] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:27305; }","duration":"141.478656ms","start":"2026-08-28T05:06:01.658575Z","end":"2026-08-28T05:06:01.800054Z","steps":["trace[1551218984] 'count revisions from in-memory index tree' (duration: 141.220641ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T05:06:50.407747Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24863} {"level":"info","ts":"2026-08-28T05:06:50.560951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24863,"took":"149.228002ms","hash":653978918,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":36253696,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-28T05:06:50.561003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":653978918,"revision":24863,"compact-revision":21566} {"level":"info","ts":"2026-08-28T05:09:10.246192Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T05:09:10.251908Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-28T05:09:10.252039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-28T05:11:50.415112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27721} {"level":"info","ts":"2026-08-28T05:11:50.557551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27721,"took":"138.544686ms","hash":785315246,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":34607104,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-28T05:11:50.557621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":785315246,"revision":27721,"compact-revision":24863} {"level":"info","ts":"2026-08-28T05:16:50.42264Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30403} {"level":"info","ts":"2026-08-28T05:16:50.574483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30403,"took":"147.743782ms","hash":874857771,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":30646272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T05:16:50.574559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874857771,"revision":30403,"compact-revision":27721} {"level":"info","ts":"2026-08-28T05:21:50.435086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32653} {"level":"info","ts":"2026-08-28T05:21:50.596471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32653,"took":"157.011732ms","hash":1706414029,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":30253056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-28T05:21:50.596562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1706414029,"revision":32653,"compact-revision":30403} {"level":"info","ts":"2026-08-28T05:26:50.442094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34884} {"level":"info","ts":"2026-08-28T05:26:50.578247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34884,"took":"130.39158ms","hash":4287083217,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":31133696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T05:26:50.578303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4287083217,"revision":34884,"compact-revision":32653} {"level":"info","ts":"2026-08-28T05:30:18.607627Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T05:30:18.614575Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-28T05:30:18.614719Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-28T05:31:50.452381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37183} {"level":"info","ts":"2026-08-28T05:31:50.598213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37183,"took":"141.176843ms","hash":3643486626,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":32817152,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-28T05:31:50.598276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3643486626,"revision":37183,"compact-revision":34884} {"level":"info","ts":"2026-08-28T05:36:50.459456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39456} {"level":"info","ts":"2026-08-28T05:36:50.595746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39456,"took":"131.611447ms","hash":3326816917,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T05:36:50.595849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3326816917,"revision":39456,"compact-revision":37183}