{"level":"warn","ts":"2026-07-24T21:25:29.379822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:25:29.379943Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-24T21:25:29.380019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:25:29.380051Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-24T21:25:29.38008Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T21:25:29.380744Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-07-24T21:25:29.380861Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-24T21:25:29.388244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.699153ms"} {"level":"info","ts":"2026-07-24T21:25:29.399598Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-07-24T21:25:29.399747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T21:25:29.399784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-07-24T21:25:29.399806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T21:25:29.399824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-07-24T21:25:29.399891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-07-24T21:25:29.410146Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T21:25:29.413727Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T21:25:29.416876Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-24T21:25:29.419561Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T21:25:29.419717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T21:25:29.419769Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T21:25:29.419896Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T21:25:29.419927Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T21:25:29.421762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-07-24T21:25:29.421981Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-24T21:25:29.424856Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T21:25:29.425025Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-24T21:25:29.425085Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-24T21:25:29.425135Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T21:25:29.425168Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T21:25:30.000584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T21:25:30.00067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T21:25:30.000699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-07-24T21:25:30.00073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-07-24T21:25:30.000748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-24T21:25:30.000771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-07-24T21:25:30.000785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-24T21:25:30.002246Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:30.004187Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T21:25:30.00424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:25:30.004281Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:25:30.004575Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:30.004634Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T21:25:30.004692Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T21:25:30.004722Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:30.004778Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:30.007129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T21:25:30.014387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-07-24T21:25:45.039305Z","caller":"traceutil/trace.go:171","msg":"trace[285206883] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"155.455803ms","start":"2026-07-24T21:25:44.88382Z","end":"2026-07-24T21:25:45.039276Z","steps":["trace[285206883] 'process raft request' (duration: 155.29012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:15.470204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.3636ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:26:15.470463Z","caller":"traceutil/trace.go:171","msg":"trace[710489802] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"151.647216ms","start":"2026-07-24T21:26:15.318777Z","end":"2026-07-24T21:26:15.470424Z","steps":["trace[710489802] 'range keys from in-memory index tree' (duration: 151.330239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:15.47115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.976237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:26:15.471337Z","caller":"traceutil/trace.go:171","msg":"trace[679654823] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"672.504478ms","start":"2026-07-24T21:26:14.798782Z","end":"2026-07-24T21:26:15.471286Z","steps":["trace[679654823] 'process raft request' (duration: 264.604731ms)","trace[679654823] 'compare' (duration: 406.788623ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:26:15.47157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:14.79876Z","time spent":"672.678162ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:26:15.471913Z","caller":"traceutil/trace.go:171","msg":"trace[1265274536] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"277.020588ms","start":"2026-07-24T21:26:15.194878Z","end":"2026-07-24T21:26:15.471899Z","steps":["trace[1265274536] 'process raft request' (duration: 276.551067ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:15.471931Z","caller":"traceutil/trace.go:171","msg":"trace[312535253] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"576.721372ms","start":"2026-07-24T21:26:14.895118Z","end":"2026-07-24T21:26:15.47184Z","steps":["trace[312535253] 'read index received' (duration: 168.306313ms)","trace[312535253] 'applied index is now lower than readState.Index' (duration: 408.411269ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:26:15.472208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.073469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T21:26:15.47225Z","caller":"traceutil/trace.go:171","msg":"trace[78709392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"577.13584ms","start":"2026-07-24T21:26:14.895102Z","end":"2026-07-24T21:26:15.472238Z","steps":["trace[78709392] 'agreement among raft nodes before linearized reading' (duration: 577.003087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:15.472293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:26:14.895039Z","time spent":"577.245443ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T21:26:47.017669Z","caller":"traceutil/trace.go:171","msg":"trace[200099848] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"188.786453ms","start":"2026-07-24T21:26:46.82886Z","end":"2026-07-24T21:26:47.017647Z","steps":["trace[200099848] 'read index received' (duration: 188.543308ms)","trace[200099848] 'applied index is now lower than readState.Index' (duration: 242.225µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:26:47.017785Z","caller":"traceutil/trace.go:171","msg":"trace[1166402624] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"215.041569ms","start":"2026-07-24T21:26:46.80271Z","end":"2026-07-24T21:26:47.017752Z","steps":["trace[1166402624] 'process raft request' (duration: 214.800643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:47.018484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.602391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T21:26:47.018595Z","caller":"traceutil/trace.go:171","msg":"trace[888922621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"189.742194ms","start":"2026-07-24T21:26:46.828807Z","end":"2026-07-24T21:26:47.018549Z","steps":["trace[888922621] 'agreement among raft nodes before linearized reading' (duration: 188.954598ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:47.196224Z","caller":"traceutil/trace.go:171","msg":"trace[1399348488] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"170.971241ms","start":"2026-07-24T21:26:47.025216Z","end":"2026-07-24T21:26:47.196188Z","steps":["trace[1399348488] 'process raft request' (duration: 82.611989ms)","trace[1399348488] 'compare' (duration: 88.113977ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:26:47.197489Z","caller":"traceutil/trace.go:171","msg":"trace[372154678] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"172.037864ms","start":"2026-07-24T21:26:47.025421Z","end":"2026-07-24T21:26:47.197459Z","steps":["trace[372154678] 'process raft request' (duration: 171.952642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:05.284392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.29832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:27:05.284475Z","caller":"traceutil/trace.go:171","msg":"trace[211340213] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1390; }","duration":"151.392312ms","start":"2026-07-24T21:27:05.133068Z","end":"2026-07-24T21:27:05.28446Z","steps":["trace[211340213] 'count revisions from in-memory index tree' (duration: 151.197908ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:06.24467Z","caller":"traceutil/trace.go:171","msg":"trace[666590897] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"228.717159ms","start":"2026-07-24T21:27:06.015928Z","end":"2026-07-24T21:27:06.244645Z","steps":["trace[666590897] 'process raft request' (duration: 228.579376ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:06.275412Z","caller":"traceutil/trace.go:171","msg":"trace[1866622979] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"120.753491ms","start":"2026-07-24T21:27:06.154636Z","end":"2026-07-24T21:27:06.275389Z","steps":["trace[1866622979] 'process raft request' (duration: 116.742854ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:08.367877Z","caller":"traceutil/trace.go:171","msg":"trace[1716178234] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"115.510149ms","start":"2026-07-24T21:27:08.25234Z","end":"2026-07-24T21:27:08.36785Z","steps":["trace[1716178234] 'process raft request' (duration: 115.329175ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:08.513977Z","caller":"traceutil/trace.go:171","msg":"trace[1933297470] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"231.101731ms","start":"2026-07-24T21:27:08.282852Z","end":"2026-07-24T21:27:08.513953Z","steps":["trace[1933297470] 'process raft request' (duration: 224.847096ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:17.32789Z","caller":"traceutil/trace.go:171","msg":"trace[1904083566] linearizableReadLoop","detail":"{readStateIndex:1591; appliedIndex:1590; }","duration":"106.91095ms","start":"2026-07-24T21:27:17.220947Z","end":"2026-07-24T21:27:17.327858Z","steps":["trace[1904083566] 'read index received' (duration: 94.579024ms)","trace[1904083566] 'applied index is now lower than readState.Index' (duration: 12.331346ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:17.327941Z","caller":"traceutil/trace.go:171","msg":"trace[2128401378] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"123.745294ms","start":"2026-07-24T21:27:17.204158Z","end":"2026-07-24T21:27:17.327904Z","steps":["trace[2128401378] 'process raft request' (duration: 111.429148ms)","trace[2128401378] 'compare' (duration: 12.175933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:17.328121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.287785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:1 size:1054"} {"level":"info","ts":"2026-07-24T21:27:17.32815Z","caller":"traceutil/trace.go:171","msg":"trace[1289070657] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:1; response_revision:1551; }","duration":"105.386167ms","start":"2026-07-24T21:27:17.222755Z","end":"2026-07-24T21:27:17.328141Z","steps":["trace[1289070657] 'agreement among raft nodes before linearized reading' (duration: 105.231124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:17.328163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.189456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-24T21:27:17.328234Z","caller":"traceutil/trace.go:171","msg":"trace[487152804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1551; }","duration":"107.300629ms","start":"2026-07-24T21:27:17.220918Z","end":"2026-07-24T21:27:17.328218Z","steps":["trace[487152804] 'agreement among raft nodes before linearized reading' (duration: 107.052704ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:23.987216Z","caller":"traceutil/trace.go:171","msg":"trace[1455811033] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"184.345034ms","start":"2026-07-24T21:27:23.802843Z","end":"2026-07-24T21:27:23.987188Z","steps":["trace[1455811033] 'process raft request' (duration: 151.918123ms)","trace[1455811033] 'compare' (duration: 32.311168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:24.261551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.894733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:27:24.261737Z","caller":"traceutil/trace.go:171","msg":"trace[1862912977] linearizableReadLoop","detail":"{readStateIndex:1691; appliedIndex:1689; }","duration":"125.717637ms","start":"2026-07-24T21:27:24.136001Z","end":"2026-07-24T21:27:24.261719Z","steps":["trace[1862912977] 'read index received' (duration: 35.01µs)","trace[1862912977] 'applied index is now lower than readState.Index' (duration: 125.681747ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:24.261904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.890391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:27:24.26195Z","caller":"traceutil/trace.go:171","msg":"trace[274530966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com; range_end:; response_count:0; response_revision:1647; }","duration":"125.973093ms","start":"2026-07-24T21:27:24.135965Z","end":"2026-07-24T21:27:24.261938Z","steps":["trace[274530966] 'agreement among raft nodes before linearized reading' (duration: 125.885701ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:24.261884Z","caller":"traceutil/trace.go:171","msg":"trace[733762505] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"269.951135ms","start":"2026-07-24T21:27:23.991894Z","end":"2026-07-24T21:27:24.261845Z","steps":["trace[733762505] 'process raft request' (duration: 269.757931ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:24.261907Z","caller":"traceutil/trace.go:171","msg":"trace[991238538] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"272.99279ms","start":"2026-07-24T21:27:23.988734Z","end":"2026-07-24T21:27:24.261727Z","steps":["trace[991238538] 'process raft request' (duration: 133.841292ms)","trace[991238538] 'compare' (duration: 138.75286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:24.262094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.4585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-5tf8k\" ","response":"range_response_count:1 size:7665"} {"level":"info","ts":"2026-07-24T21:27:24.262131Z","caller":"traceutil/trace.go:171","msg":"trace[1835923581] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-5tf8k; range_end:; response_count:1; response_revision:1647; }","duration":"105.538732ms","start":"2026-07-24T21:27:24.156582Z","end":"2026-07-24T21:27:24.262121Z","steps":["trace[1835923581] 'agreement among raft nodes before linearized reading' (duration: 105.413839ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:39.680543Z","caller":"traceutil/trace.go:171","msg":"trace[974486663] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"115.154539ms","start":"2026-07-24T21:27:39.565368Z","end":"2026-07-24T21:27:39.680523Z","steps":["trace[974486663] 'process raft request' (duration: 115.050467ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:44.526227Z","caller":"traceutil/trace.go:171","msg":"trace[1978342318] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"135.942789ms","start":"2026-07-24T21:27:44.390156Z","end":"2026-07-24T21:27:44.526098Z","steps":["trace[1978342318] 'process raft request' (duration: 128.126599ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:44.810808Z","caller":"traceutil/trace.go:171","msg":"trace[1646256814] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"280.374629ms","start":"2026-07-24T21:27:44.5304Z","end":"2026-07-24T21:27:44.810774Z","steps":["trace[1646256814] 'process raft request' (duration: 197.339135ms)","trace[1646256814] 'compare' (duration: 82.697397ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:44.810985Z","caller":"traceutil/trace.go:171","msg":"trace[2101473701] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"279.247884ms","start":"2026-07-24T21:27:44.531718Z","end":"2026-07-24T21:27:44.810966Z","steps":["trace[2101473701] 'process raft request' (duration: 279.06103ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:44.811125Z","caller":"traceutil/trace.go:171","msg":"trace[1007011806] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"280.394399ms","start":"2026-07-24T21:27:44.530713Z","end":"2026-07-24T21:27:44.811108Z","steps":["trace[1007011806] 'process raft request' (duration: 279.96468ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:44.811166Z","caller":"traceutil/trace.go:171","msg":"trace[1167088806] linearizableReadLoop","detail":"{readStateIndex:2148; appliedIndex:2145; }","duration":"265.278082ms","start":"2026-07-24T21:27:44.54587Z","end":"2026-07-24T21:27:44.811148Z","steps":["trace[1167088806] 'read index received' (duration: 10.888944ms)","trace[1167088806] 'applied index is now lower than readState.Index' (duration: 254.387508ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:44.811206Z","caller":"traceutil/trace.go:171","msg":"trace[1711509740] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"168.329657ms","start":"2026-07-24T21:27:44.642869Z","end":"2026-07-24T21:27:44.811199Z","steps":["trace[1711509740] 'process raft request' (duration: 167.961729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:44.811298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.425846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T21:27:44.811319Z","caller":"traceutil/trace.go:171","msg":"trace[807759061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2075; }","duration":"265.505687ms","start":"2026-07-24T21:27:44.545807Z","end":"2026-07-24T21:27:44.811313Z","steps":["trace[807759061] 'agreement among raft nodes before linearized reading' (duration: 265.382404ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:45.915611Z","caller":"traceutil/trace.go:171","msg":"trace[970524682] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"207.997536ms","start":"2026-07-24T21:27:45.707593Z","end":"2026-07-24T21:27:45.915591Z","steps":["trace[970524682] 'process raft request' (duration: 207.859392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:46.113184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.642361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:27:46.113677Z","caller":"traceutil/trace.go:171","msg":"trace[1849348899] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"346.641591ms","start":"2026-07-24T21:27:45.766998Z","end":"2026-07-24T21:27:46.113639Z","steps":["trace[1849348899] 'process raft request' (duration: 204.450638ms)","trace[1849348899] 'compare' (duration: 141.532749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:46.113757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:45.766974Z","time spent":"346.747554ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:27:46.1139Z","caller":"traceutil/trace.go:171","msg":"trace[22029506] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"125.723857ms","start":"2026-07-24T21:27:45.988167Z","end":"2026-07-24T21:27:46.113891Z","steps":["trace[22029506] 'process raft request' (duration: 125.122474ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:47.151785Z","caller":"traceutil/trace.go:171","msg":"trace[1128490900] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"335.485151ms","start":"2026-07-24T21:27:46.816279Z","end":"2026-07-24T21:27:47.151764Z","steps":["trace[1128490900] 'process raft request' (duration: 335.347278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:47.151949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:46.816256Z","time spent":"335.616783ms","remote":"127.0.0.1:40384","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-07-24T21:27:47.165546Z","caller":"traceutil/trace.go:171","msg":"trace[1337263317] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2158; }","duration":"313.145378ms","start":"2026-07-24T21:27:46.852378Z","end":"2026-07-24T21:27:47.165524Z","steps":["trace[1337263317] 'read index received' (duration: 299.234867ms)","trace[1337263317] 'applied index is now lower than readState.Index' (duration: 13.909391ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:47.16563Z","caller":"traceutil/trace.go:171","msg":"trace[1145782861] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"348.996143ms","start":"2026-07-24T21:27:46.81662Z","end":"2026-07-24T21:27:47.165616Z","steps":["trace[1145782861] 'process raft request' (duration: 348.771828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:47.165724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.349232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T21:27:47.165777Z","caller":"traceutil/trace.go:171","msg":"trace[948865290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2085; }","duration":"313.402073ms","start":"2026-07-24T21:27:46.852362Z","end":"2026-07-24T21:27:47.165764Z","steps":["trace[948865290] 'agreement among raft nodes before linearized reading' (duration: 313.27412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:47.165769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.407803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"warn","ts":"2026-07-24T21:27:47.16573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:46.816594Z","time spent":"349.070964ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:27:47.165813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:46.852291Z","time spent":"313.514585ms","remote":"127.0.0.1:40384","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-07-24T21:27:47.16582Z","caller":"traceutil/trace.go:171","msg":"trace[65201809] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:2085; }","duration":"108.506315ms","start":"2026-07-24T21:27:47.057302Z","end":"2026-07-24T21:27:47.165808Z","steps":["trace[65201809] 'agreement among raft nodes before linearized reading' (duration: 108.355902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:47.672179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.204684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-24T21:27:47.672268Z","caller":"traceutil/trace.go:171","msg":"trace[75150159] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"124.314367ms","start":"2026-07-24T21:27:47.547918Z","end":"2026-07-24T21:27:47.672233Z","steps":["trace[75150159] 'range keys from in-memory index tree' (duration: 124.068642ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:47.733088Z","caller":"traceutil/trace.go:171","msg":"trace[1639288200] linearizableReadLoop","detail":"{readStateIndex:2163; appliedIndex:2162; }","duration":"149.45165ms","start":"2026-07-24T21:27:47.583605Z","end":"2026-07-24T21:27:47.733057Z","steps":["trace[1639288200] 'read index received' (duration: 149.218625ms)","trace[1639288200] 'applied index is now lower than readState.Index' (duration: 231.825µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:47.733315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.676704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:27:47.733376Z","caller":"traceutil/trace.go:171","msg":"trace[1354611900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2088; }","duration":"149.777796ms","start":"2026-07-24T21:27:47.583582Z","end":"2026-07-24T21:27:47.733359Z","steps":["trace[1354611900] 'agreement among raft nodes before linearized reading' (duration: 149.651703ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:47.733279Z","caller":"traceutil/trace.go:171","msg":"trace[2037797305] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"162.602694ms","start":"2026-07-24T21:27:47.570612Z","end":"2026-07-24T21:27:47.733214Z","steps":["trace[2037797305] 'process raft request' (duration: 162.243096ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:47.993515Z","caller":"traceutil/trace.go:171","msg":"trace[1962989183] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"254.325716ms","start":"2026-07-24T21:27:47.739162Z","end":"2026-07-24T21:27:47.993487Z","steps":["trace[1962989183] 'process raft request' (duration: 254.088321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:48.302086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.076122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:27:48.302279Z","caller":"traceutil/trace.go:171","msg":"trace[232467355] linearizableReadLoop","detail":"{readStateIndex:2166; appliedIndex:2165; }","duration":"234.59746ms","start":"2026-07-24T21:27:48.067651Z","end":"2026-07-24T21:27:48.302249Z","steps":["trace[232467355] 'read index received' (duration: 97.24963ms)","trace[232467355] 'applied index is now lower than readState.Index' (duration: 137.345659ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:48.302303Z","caller":"traceutil/trace.go:171","msg":"trace[1642722428] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"304.861569ms","start":"2026-07-24T21:27:47.997395Z","end":"2026-07-24T21:27:48.302257Z","steps":["trace[1642722428] 'process raft request' (duration: 167.483009ms)","trace[1642722428] 'compare' (duration: 136.733385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:48.302472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:47.997369Z","time spent":"305.005161ms","remote":"127.0.0.1:40384","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-07-24T21:27:48.524582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.369827ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-07-24T21:27:48.524645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.980626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-07-24T21:27:48.524709Z","caller":"traceutil/trace.go:171","msg":"trace[362717007] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2091; }","duration":"457.054448ms","start":"2026-07-24T21:27:48.067635Z","end":"2026-07-24T21:27:48.524689Z","steps":["trace[362717007] 'agreement among raft nodes before linearized reading' (duration: 234.762774ms)","trace[362717007] 'range keys from in-memory index tree' (duration: 222.10759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:48.524757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:48.067616Z","time spent":"457.126049ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10352,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-24T21:27:48.524836Z","caller":"traceutil/trace.go:171","msg":"trace[380781647] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2166; }","duration":"222.477708ms","start":"2026-07-24T21:27:48.302339Z","end":"2026-07-24T21:27:48.524817Z","steps":["trace[380781647] 'read index received' (duration: 48.681µs)","trace[380781647] 'applied index is now lower than readState.Index' (duration: 222.427957ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:48.524869Z","caller":"traceutil/trace.go:171","msg":"trace[76284927] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"404.183145ms","start":"2026-07-24T21:27:48.120641Z","end":"2026-07-24T21:27:48.524825Z","steps":["trace[76284927] 'process raft request' (duration: 404.018052ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:48.524931Z","caller":"traceutil/trace.go:171","msg":"trace[535481135] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"404.104433ms","start":"2026-07-24T21:27:48.120815Z","end":"2026-07-24T21:27:48.524919Z","steps":["trace[535481135] 'process raft request' (duration: 403.95987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:48.52504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:48.120467Z","time spent":"404.485571ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:27:48.525182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:48.12079Z","time spent":"404.172114ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:27:48.576016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.904567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T21:27:48.576069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.107317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d3e1dd7-c0a8-499e-a53b-703aa0b8b297\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-07-24T21:27:48.576123Z","caller":"traceutil/trace.go:171","msg":"trace[1116098022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2093; }","duration":"154.06168ms","start":"2026-07-24T21:27:48.422032Z","end":"2026-07-24T21:27:48.576094Z","steps":["trace[1116098022] 'agreement among raft nodes before linearized reading' (duration: 102.901515ms)","trace[1116098022] 'range keys from in-memory index tree' (duration: 50.848399ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:48.576149Z","caller":"traceutil/trace.go:171","msg":"trace[1265283564] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d3e1dd7-c0a8-499e-a53b-703aa0b8b297; range_end:; response_count:1; response_revision:2093; }","duration":"406.23752ms","start":"2026-07-24T21:27:48.169889Z","end":"2026-07-24T21:27:48.576127Z","steps":["trace[1265283564] 'agreement among raft nodes before linearized reading' (duration: 355.060264ms)","trace[1265283564] 'range keys from in-memory index tree' (duration: 50.937561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:27:48.576197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:27:48.169866Z","time spent":"406.321571ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d3e1dd7-c0a8-499e-a53b-703aa0b8b297\" "} {"level":"info","ts":"2026-07-24T21:28:00.211803Z","caller":"traceutil/trace.go:171","msg":"trace[995620303] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"104.595921ms","start":"2026-07-24T21:28:00.107182Z","end":"2026-07-24T21:28:00.211778Z","steps":["trace[995620303] 'process raft request' (duration: 104.416337ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:00.223758Z","caller":"traceutil/trace.go:171","msg":"trace[1238455173] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"114.921954ms","start":"2026-07-24T21:28:00.108808Z","end":"2026-07-24T21:28:00.22373Z","steps":["trace[1238455173] 'process raft request' (duration: 114.540026ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:00.443981Z","caller":"traceutil/trace.go:171","msg":"trace[1623608681] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"218.089174ms","start":"2026-07-24T21:28:00.225828Z","end":"2026-07-24T21:28:00.443917Z","steps":["trace[1623608681] 'process raft request' (duration: 126.842171ms)","trace[1623608681] 'compare' (duration: 91.022508ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:00.760931Z","caller":"traceutil/trace.go:171","msg":"trace[1683750116] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"164.066956ms","start":"2026-07-24T21:28:00.596811Z","end":"2026-07-24T21:28:00.760878Z","steps":["trace[1683750116] 'process raft request' (duration: 140.504366ms)","trace[1683750116] 'compare' (duration: 23.134321ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:00.761354Z","caller":"traceutil/trace.go:171","msg":"trace[434462101] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"161.257425ms","start":"2026-07-24T21:28:00.600068Z","end":"2026-07-24T21:28:00.761325Z","steps":["trace[434462101] 'process raft request' (duration: 160.703703ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:14.701823Z","caller":"traceutil/trace.go:171","msg":"trace[1211886633] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"107.869381ms","start":"2026-07-24T21:28:14.593924Z","end":"2026-07-24T21:28:14.701793Z","steps":["trace[1211886633] 'process raft request' (duration: 107.676107ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:21.067761Z","caller":"traceutil/trace.go:171","msg":"trace[1181748436] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"181.267857ms","start":"2026-07-24T21:28:20.886188Z","end":"2026-07-24T21:28:21.067456Z","steps":["trace[1181748436] 'process raft request' (duration: 120.728929ms)","trace[1181748436] 'compare' (duration: 60.343054ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:28.163613Z","caller":"traceutil/trace.go:171","msg":"trace[1185575621] transaction","detail":"{read_only:false; response_revision:2641; number_of_response:1; }","duration":"106.258259ms","start":"2026-07-24T21:28:28.057321Z","end":"2026-07-24T21:28:28.16358Z","steps":["trace[1185575621] 'process raft request' (duration: 79.803618ms)","trace[1185575621] 'compare' (duration: 26.31199ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:28.26642Z","caller":"traceutil/trace.go:171","msg":"trace[1861752825] linearizableReadLoop","detail":"{readStateIndex:2737; appliedIndex:2735; }","duration":"147.919674ms","start":"2026-07-24T21:28:28.118476Z","end":"2026-07-24T21:28:28.266396Z","steps":["trace[1861752825] 'read index received' (duration: 18.665276ms)","trace[1861752825] 'applied index is now lower than readState.Index' (duration: 129.252968ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:28:28.266746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.253759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-24T21:28:28.266837Z","caller":"traceutil/trace.go:171","msg":"trace[628165748] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2641; }","duration":"148.37173ms","start":"2026-07-24T21:28:28.118429Z","end":"2026-07-24T21:28:28.266801Z","steps":["trace[628165748] 'agreement among raft nodes before linearized reading' (duration: 148.047085ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:28.64601Z","caller":"traceutil/trace.go:171","msg":"trace[1467043717] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"142.129214ms","start":"2026-07-24T21:28:28.503837Z","end":"2026-07-24T21:28:28.645966Z","steps":["trace[1467043717] 'process raft request' (duration: 141.911482ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:29.143093Z","caller":"traceutil/trace.go:171","msg":"trace[1939813632] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"267.86971ms","start":"2026-07-24T21:28:28.875188Z","end":"2026-07-24T21:28:29.143057Z","steps":["trace[1939813632] 'process raft request' (duration: 267.643647ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:29.155862Z","caller":"traceutil/trace.go:171","msg":"trace[774621332] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"276.160991ms","start":"2026-07-24T21:28:28.879691Z","end":"2026-07-24T21:28:29.155852Z","steps":["trace[774621332] 'process raft request' (duration: 274.972497ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:29.156101Z","caller":"traceutil/trace.go:171","msg":"trace[1326021784] linearizableReadLoop","detail":"{readStateIndex:2741; appliedIndex:2739; }","duration":"270.305649ms","start":"2026-07-24T21:28:28.885768Z","end":"2026-07-24T21:28:29.156074Z","steps":["trace[1326021784] 'read index received' (duration: 257.144499ms)","trace[1326021784] 'applied index is now lower than readState.Index' (duration: 13.15936ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:29.15621Z","caller":"traceutil/trace.go:171","msg":"trace[991273642] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"253.206722ms","start":"2026-07-24T21:28:28.902992Z","end":"2026-07-24T21:28:29.156198Z","steps":["trace[991273642] 'process raft request' (duration: 252.832217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:29.156328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.519581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:28:29.156414Z","caller":"traceutil/trace.go:171","msg":"trace[1018006420] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:2646; }","duration":"270.662253ms","start":"2026-07-24T21:28:28.885727Z","end":"2026-07-24T21:28:29.156389Z","steps":["trace[1018006420] 'agreement among raft nodes before linearized reading' (duration: 270.498571ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:55.068631Z","caller":"traceutil/trace.go:171","msg":"trace[731801519] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2906; }","duration":"125.468083ms","start":"2026-07-24T21:28:54.943137Z","end":"2026-07-24T21:28:55.068606Z","steps":["trace[731801519] 'read index received' (duration: 125.303891ms)","trace[731801519] 'applied index is now lower than readState.Index' (duration: 162.822µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:55.068699Z","caller":"traceutil/trace.go:171","msg":"trace[680586070] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"134.055086ms","start":"2026-07-24T21:28:54.934598Z","end":"2026-07-24T21:28:55.068653Z","steps":["trace[680586070] 'process raft request' (duration: 133.836724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:55.068822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.651365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:28:55.068868Z","caller":"traceutil/trace.go:171","msg":"trace[1124859122] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2805; }","duration":"125.749106ms","start":"2026-07-24T21:28:54.943106Z","end":"2026-07-24T21:28:55.068856Z","steps":["trace[1124859122] 'agreement among raft nodes before linearized reading' (duration: 125.607984ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:13.959954Z","caller":"traceutil/trace.go:171","msg":"trace[1740336618] linearizableReadLoop","detail":"{readStateIndex:3034; appliedIndex:3033; }","duration":"206.893391ms","start":"2026-07-24T21:29:13.753017Z","end":"2026-07-24T21:29:13.959911Z","steps":["trace[1740336618] 'read index received' (duration: 202.634148ms)","trace[1740336618] 'applied index is now lower than readState.Index' (duration: 4.257052ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:13.96Z","caller":"traceutil/trace.go:171","msg":"trace[495936071] transaction","detail":"{read_only:false; response_revision:2928; number_of_response:1; }","duration":"234.537336ms","start":"2026-07-24T21:29:13.725428Z","end":"2026-07-24T21:29:13.959965Z","steps":["trace[495936071] 'process raft request' (duration: 230.237583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:13.96027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.876555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"warn","ts":"2026-07-24T21:29:13.960272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.241654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T21:29:13.960324Z","caller":"traceutil/trace.go:171","msg":"trace[1833848630] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:2928; }","duration":"199.963316ms","start":"2026-07-24T21:29:13.760344Z","end":"2026-07-24T21:29:13.960307Z","steps":["trace[1833848630] 'agreement among raft nodes before linearized reading' (duration: 199.770584ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:13.960341Z","caller":"traceutil/trace.go:171","msg":"trace[1619844792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2928; }","duration":"207.351465ms","start":"2026-07-24T21:29:13.752968Z","end":"2026-07-24T21:29:13.96032Z","steps":["trace[1619844792] 'agreement among raft nodes before linearized reading' (duration: 207.105412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:25.866405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.172996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:29:25.866582Z","caller":"traceutil/trace.go:171","msg":"trace[162994174] linearizableReadLoop","detail":"{readStateIndex:3151; appliedIndex:3150; }","duration":"258.702498ms","start":"2026-07-24T21:29:25.60785Z","end":"2026-07-24T21:29:25.866552Z","steps":["trace[162994174] 'read index received' (duration: 26.297289ms)","trace[162994174] 'applied index is now lower than readState.Index' (duration: 232.403889ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:25.866622Z","caller":"traceutil/trace.go:171","msg":"trace[664078591] transaction","detail":"{read_only:false; response_revision:3044; number_of_response:1; }","duration":"252.677635ms","start":"2026-07-24T21:29:25.613926Z","end":"2026-07-24T21:29:25.866604Z","steps":["trace[664078591] 'process raft request' (duration: 252.581604ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:25.866523Z","caller":"traceutil/trace.go:171","msg":"trace[955569417] transaction","detail":"{read_only:false; response_revision:3043; number_of_response:1; }","duration":"310.66682ms","start":"2026-07-24T21:29:25.555832Z","end":"2026-07-24T21:29:25.866499Z","steps":["trace[955569417] 'process raft request' (duration: 78.303221ms)","trace[955569417] 'compare' (duration: 232.026935ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:25.866722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.238014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:25.866779Z","caller":"traceutil/trace.go:171","msg":"trace[1751875268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3044; }","duration":"282.342976ms","start":"2026-07-24T21:29:25.584422Z","end":"2026-07-24T21:29:25.866765Z","steps":["trace[1751875268] 'agreement among raft nodes before linearized reading' (duration: 282.206274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:25.866805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:25.555805Z","time spent":"310.939123ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:25.878805Z","caller":"traceutil/trace.go:171","msg":"trace[299598798] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"186.432781ms","start":"2026-07-24T21:29:25.692338Z","end":"2026-07-24T21:29:25.878771Z","steps":["trace[299598798] 'process raft request' (duration: 186.277719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:25.87885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.994617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:29:25.878927Z","caller":"traceutil/trace.go:171","msg":"trace[41583873] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:3045; }","duration":"177.088498ms","start":"2026-07-24T21:29:25.701822Z","end":"2026-07-24T21:29:25.878911Z","steps":["trace[41583873] 'agreement among raft nodes before linearized reading' (duration: 176.957716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:26.19968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.341164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:29:26.19979Z","caller":"traceutil/trace.go:171","msg":"trace[1090771844] linearizableReadLoop","detail":"{readStateIndex:3154; appliedIndex:3153; }","duration":"201.355133ms","start":"2026-07-24T21:29:25.998413Z","end":"2026-07-24T21:29:26.199768Z","steps":["trace[1090771844] 'read index received' (duration: 50.801206ms)","trace[1090771844] 'applied index is now lower than readState.Index' (duration: 150.552757ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:26.199874Z","caller":"traceutil/trace.go:171","msg":"trace[1440602993] transaction","detail":"{read_only:false; response_revision:3046; number_of_response:1; }","duration":"203.697811ms","start":"2026-07-24T21:29:25.996114Z","end":"2026-07-24T21:29:26.199812Z","steps":["trace[1440602993] 'process raft request' (duration: 53.137055ms)","trace[1440602993] 'compare' (duration: 150.200912ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:26.199953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.495294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T21:29:26.199995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.437299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T21:29:26.200019Z","caller":"traceutil/trace.go:171","msg":"trace[1622135280] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:3046; }","duration":"201.627405ms","start":"2026-07-24T21:29:25.998373Z","end":"2026-07-24T21:29:26.2Z","steps":["trace[1622135280] 'agreement among raft nodes before linearized reading' (duration: 201.448193ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:26.200031Z","caller":"traceutil/trace.go:171","msg":"trace[465090863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3046; }","duration":"157.493061ms","start":"2026-07-24T21:29:26.042527Z","end":"2026-07-24T21:29:26.20002Z","steps":["trace[465090863] 'agreement among raft nodes before linearized reading' (duration: 157.350029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:26.200085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.525823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-07-24T21:29:26.200117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.23991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:29:26.200206Z","caller":"traceutil/trace.go:171","msg":"trace[552589937] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:3046; }","duration":"153.342271ms","start":"2026-07-24T21:29:26.046843Z","end":"2026-07-24T21:29:26.200186Z","steps":["trace[552589937] 'agreement among raft nodes before linearized reading' (duration: 153.098808ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:26.200148Z","caller":"traceutil/trace.go:171","msg":"trace[1995575235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3046; }","duration":"149.633185ms","start":"2026-07-24T21:29:26.050499Z","end":"2026-07-24T21:29:26.200133Z","steps":["trace[1995575235] 'agreement among raft nodes before linearized reading' (duration: 149.410722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:30.44514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.322266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:29:30.445226Z","caller":"traceutil/trace.go:171","msg":"trace[1800631228] linearizableReadLoop","detail":"{readStateIndex:3177; appliedIndex:3176; }","duration":"156.950645ms","start":"2026-07-24T21:29:30.288262Z","end":"2026-07-24T21:29:30.445213Z","steps":["trace[1800631228] 'read index received' (duration: 47.515587ms)","trace[1800631228] 'applied index is now lower than readState.Index' (duration: 109.434038ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:30.445294Z","caller":"traceutil/trace.go:171","msg":"trace[1318721523] transaction","detail":"{read_only:false; response_revision:3068; number_of_response:1; }","duration":"191.094619ms","start":"2026-07-24T21:29:30.254192Z","end":"2026-07-24T21:29:30.445286Z","steps":["trace[1318721523] 'process raft request' (duration: 81.520879ms)","trace[1318721523] 'compare' (duration: 109.218695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:30.44544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.972023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:29:30.445514Z","caller":"traceutil/trace.go:171","msg":"trace[80664914] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:3068; }","duration":"156.111445ms","start":"2026-07-24T21:29:30.289385Z","end":"2026-07-24T21:29:30.445496Z","steps":["trace[80664914] 'agreement among raft nodes before linearized reading' (duration: 155.916592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:30.445715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.440591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T21:29:30.445802Z","caller":"traceutil/trace.go:171","msg":"trace[853942624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3068; }","duration":"157.581982ms","start":"2026-07-24T21:29:30.288203Z","end":"2026-07-24T21:29:30.445785Z","steps":["trace[853942624] 'agreement among raft nodes before linearized reading' (duration: 157.321789ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:30.865371Z","caller":"traceutil/trace.go:171","msg":"trace[1624393014] linearizableReadLoop","detail":"{readStateIndex:3179; appliedIndex:3178; }","duration":"123.007751ms","start":"2026-07-24T21:29:30.742338Z","end":"2026-07-24T21:29:30.865346Z","steps":["trace[1624393014] 'read index received' (duration: 122.789999ms)","trace[1624393014] 'applied index is now lower than readState.Index' (duration: 216.732µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:30.865551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.173374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:30.865595Z","caller":"traceutil/trace.go:171","msg":"trace[1338564618] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:3070; }","duration":"123.286285ms","start":"2026-07-24T21:29:30.742295Z","end":"2026-07-24T21:29:30.865582Z","steps":["trace[1338564618] 'agreement among raft nodes before linearized reading' (duration: 123.177444ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:30.865601Z","caller":"traceutil/trace.go:171","msg":"trace[1323735946] transaction","detail":"{read_only:false; response_revision:3070; number_of_response:1; }","duration":"142.950144ms","start":"2026-07-24T21:29:30.72252Z","end":"2026-07-24T21:29:30.86547Z","steps":["trace[1323735946] 'process raft request' (duration: 142.660699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:39.630124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.658776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:39.630197Z","caller":"traceutil/trace.go:171","msg":"trace[779107319] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:3139; }","duration":"115.76382ms","start":"2026-07-24T21:29:39.514417Z","end":"2026-07-24T21:29:39.630181Z","steps":["trace[779107319] 'count revisions from in-memory index tree' (duration: 115.604775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:39.630421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.519077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T21:29:39.630546Z","caller":"traceutil/trace.go:171","msg":"trace[457912102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3139; }","duration":"145.721443ms","start":"2026-07-24T21:29:39.484796Z","end":"2026-07-24T21:29:39.630518Z","steps":["trace[457912102] 'range keys from in-memory index tree' (duration: 145.240878ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:40.750861Z","caller":"traceutil/trace.go:171","msg":"trace[1242733937] linearizableReadLoop","detail":"{readStateIndex:3259; appliedIndex:3258; }","duration":"212.975074ms","start":"2026-07-24T21:29:40.537863Z","end":"2026-07-24T21:29:40.750838Z","steps":["trace[1242733937] 'read index received' (duration: 198.376459ms)","trace[1242733937] 'applied index is now lower than readState.Index' (duration: 14.597734ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:40.751024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.155878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T21:29:40.751047Z","caller":"traceutil/trace.go:171","msg":"trace[767218677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3146; }","duration":"213.229531ms","start":"2026-07-24T21:29:40.53781Z","end":"2026-07-24T21:29:40.751039Z","steps":["trace[767218677] 'agreement among raft nodes before linearized reading' (duration: 213.107907ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:40.751119Z","caller":"traceutil/trace.go:171","msg":"trace[158816876] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"221.862524ms","start":"2026-07-24T21:29:40.529229Z","end":"2026-07-24T21:29:40.751091Z","steps":["trace[158816876] 'process raft request' (duration: 207.086574ms)","trace[158816876] 'compare' (duration: 14.411969ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:50.692373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.899461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:50.692459Z","caller":"traceutil/trace.go:171","msg":"trace[378325961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3201; }","duration":"110.020215ms","start":"2026-07-24T21:29:50.582418Z","end":"2026-07-24T21:29:50.692438Z","steps":["trace[378325961] 'range keys from in-memory index tree' (duration: 109.802477ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:51.95833Z","caller":"traceutil/trace.go:171","msg":"trace[1868198635] linearizableReadLoop","detail":"{readStateIndex:3323; appliedIndex:3322; }","duration":"160.966549ms","start":"2026-07-24T21:29:51.797315Z","end":"2026-07-24T21:29:51.958282Z","steps":["trace[1868198635] 'read index received' (duration: 67.760137ms)","trace[1868198635] 'applied index is now lower than readState.Index' (duration: 93.204712ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:51.958541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.215236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:29:51.95857Z","caller":"traceutil/trace.go:171","msg":"trace[821549972] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3208; }","duration":"161.279668ms","start":"2026-07-24T21:29:51.797282Z","end":"2026-07-24T21:29:51.958562Z","steps":["trace[821549972] 'agreement among raft nodes before linearized reading' (duration: 161.109273ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:51.958576Z","caller":"traceutil/trace.go:171","msg":"trace[1491800714] transaction","detail":"{read_only:false; response_revision:3208; number_of_response:1; }","duration":"205.049752ms","start":"2026-07-24T21:29:51.7535Z","end":"2026-07-24T21:29:51.95855Z","steps":["trace[1491800714] 'process raft request' (duration: 111.529631ms)","trace[1491800714] 'compare' (duration: 93.007355ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:52.246268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.431399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:29:52.246472Z","caller":"traceutil/trace.go:171","msg":"trace[195128209] transaction","detail":"{read_only:false; response_revision:3210; number_of_response:1; }","duration":"188.562699ms","start":"2026-07-24T21:29:52.057896Z","end":"2026-07-24T21:29:52.246459Z","steps":["trace[195128209] 'process raft request' (duration: 188.486447ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:52.246536Z","caller":"traceutil/trace.go:171","msg":"trace[828395132] linearizableReadLoop","detail":"{readStateIndex:3324; appliedIndex:3323; }","duration":"214.741797ms","start":"2026-07-24T21:29:52.031781Z","end":"2026-07-24T21:29:52.246523Z","steps":["trace[828395132] 'read index received' (duration: 39.872775ms)","trace[828395132] 'applied index is now lower than readState.Index' (duration: 174.867582ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:52.246463Z","caller":"traceutil/trace.go:171","msg":"trace[379476737] transaction","detail":"{read_only:false; response_revision:3209; number_of_response:1; }","duration":"223.434843ms","start":"2026-07-24T21:29:52.022998Z","end":"2026-07-24T21:29:52.246433Z","steps":["trace[379476737] 'process raft request' (duration: 48.701914ms)","trace[379476737] 'compare' (duration: 174.249274ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:52.246864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.066868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-07-24T21:29:52.246912Z","caller":"traceutil/trace.go:171","msg":"trace[426024089] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3210; }","duration":"215.124429ms","start":"2026-07-24T21:29:52.03177Z","end":"2026-07-24T21:29:52.246894Z","steps":["trace[426024089] 'agreement among raft nodes before linearized reading' (duration: 214.948384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:52.246945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.908332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:52.247059Z","caller":"traceutil/trace.go:171","msg":"trace[1530234581] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:3210; }","duration":"133.077046ms","start":"2026-07-24T21:29:52.113947Z","end":"2026-07-24T21:29:52.247024Z","steps":["trace[1530234581] 'agreement among raft nodes before linearized reading' (duration: 132.889161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:52.24713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.605218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T21:29:52.247242Z","caller":"traceutil/trace.go:171","msg":"trace[527885302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3210; }","duration":"161.790023ms","start":"2026-07-24T21:29:52.085426Z","end":"2026-07-24T21:29:52.247216Z","steps":["trace[527885302] 'agreement among raft nodes before linearized reading' (duration: 161.387581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:52.542374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.874446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:52.542466Z","caller":"traceutil/trace.go:171","msg":"trace[402532658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3211; }","duration":"125.018811ms","start":"2026-07-24T21:29:52.417428Z","end":"2026-07-24T21:29:52.542447Z","steps":["trace[402532658] 'range keys from in-memory index tree' (duration: 124.791764ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:53.598901Z","caller":"traceutil/trace.go:171","msg":"trace[127038132] linearizableReadLoop","detail":"{readStateIndex:3334; appliedIndex:3333; }","duration":"160.609668ms","start":"2026-07-24T21:29:53.438269Z","end":"2026-07-24T21:29:53.598879Z","steps":["trace[127038132] 'read index received' (duration: 123.082583ms)","trace[127038132] 'applied index is now lower than readState.Index' (duration: 37.526165ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:53.598959Z","caller":"traceutil/trace.go:171","msg":"trace[1079817591] transaction","detail":"{read_only:false; response_revision:3218; number_of_response:1; }","duration":"161.556406ms","start":"2026-07-24T21:29:53.437371Z","end":"2026-07-24T21:29:53.598928Z","steps":["trace[1079817591] 'process raft request' (duration: 124.047272ms)","trace[1079817591] 'compare' (duration: 37.36094ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:53.599108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.828874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-24T21:29:53.599163Z","caller":"traceutil/trace.go:171","msg":"trace[46003079] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:3218; }","duration":"160.911727ms","start":"2026-07-24T21:29:53.438237Z","end":"2026-07-24T21:29:53.599149Z","steps":["trace[46003079] 'agreement among raft nodes before linearized reading' (duration: 160.755392ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:17.303635Z","caller":"traceutil/trace.go:171","msg":"trace[1768799900] transaction","detail":"{read_only:false; response_revision:3352; number_of_response:1; }","duration":"100.611438ms","start":"2026-07-24T21:30:17.202988Z","end":"2026-07-24T21:30:17.303599Z","steps":["trace[1768799900] 'process raft request' (duration: 100.252407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:17.786126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.812934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:30:17.786236Z","caller":"traceutil/trace.go:171","msg":"trace[540820881] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3355; }","duration":"100.968278ms","start":"2026-07-24T21:30:17.68525Z","end":"2026-07-24T21:30:17.786218Z","steps":["trace[540820881] 'range keys from in-memory index tree' (duration: 100.623858ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:25.536849Z","caller":"traceutil/trace.go:171","msg":"trace[1593080778] transaction","detail":"{read_only:false; response_revision:3394; number_of_response:1; }","duration":"146.963248ms","start":"2026-07-24T21:30:25.389868Z","end":"2026-07-24T21:30:25.536832Z","steps":["trace[1593080778] 'process raft request' (duration: 146.871856ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:32.648429Z","caller":"traceutil/trace.go:171","msg":"trace[1538020929] transaction","detail":"{read_only:false; response_revision:3430; number_of_response:1; }","duration":"173.494376ms","start":"2026-07-24T21:30:32.474917Z","end":"2026-07-24T21:30:32.648411Z","steps":["trace[1538020929] 'process raft request' (duration: 126.860671ms)","trace[1538020929] 'compare' (duration: 46.427551ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:30:39.281609Z","caller":"traceutil/trace.go:171","msg":"trace[2045034641] transaction","detail":"{read_only:false; response_revision:3464; number_of_response:1; }","duration":"103.665339ms","start":"2026-07-24T21:30:39.17792Z","end":"2026-07-24T21:30:39.281585Z","steps":["trace[2045034641] 'process raft request' (duration: 103.517146ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:47.449719Z","caller":"traceutil/trace.go:171","msg":"trace[93269575] transaction","detail":"{read_only:false; response_revision:3505; number_of_response:1; }","duration":"125.244162ms","start":"2026-07-24T21:30:47.324449Z","end":"2026-07-24T21:30:47.449693Z","steps":["trace[93269575] 'process raft request' (duration: 125.052967ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:47.555255Z","caller":"traceutil/trace.go:171","msg":"trace[1381686280] transaction","detail":"{read_only:false; response_revision:3506; number_of_response:1; }","duration":"101.319533ms","start":"2026-07-24T21:30:47.453908Z","end":"2026-07-24T21:30:47.555227Z","steps":["trace[1381686280] 'process raft request' (duration: 66.828878ms)","trace[1381686280] 'compare' (duration: 34.367592ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:31:29.224595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.242558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:31:29.224736Z","caller":"traceutil/trace.go:171","msg":"trace[1949470128] transaction","detail":"{read_only:false; response_revision:3716; number_of_response:1; }","duration":"359.909895ms","start":"2026-07-24T21:31:28.864798Z","end":"2026-07-24T21:31:29.224708Z","steps":["trace[1949470128] 'process raft request' (duration: 227.507004ms)","trace[1949470128] 'compare' (duration: 132.023053ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:31:29.22488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:31:28.864773Z","time spent":"360.031817ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:31:29.224688Z","caller":"traceutil/trace.go:171","msg":"trace[1671457010] linearizableReadLoop","detail":"{readStateIndex:3851; appliedIndex:3850; }","duration":"325.4604ms","start":"2026-07-24T21:31:28.899214Z","end":"2026-07-24T21:31:29.224674Z","steps":["trace[1671457010] 'read index received' (duration: 193.055679ms)","trace[1671457010] 'applied index is now lower than readState.Index' (duration: 132.403521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:31:29.225155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.925298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:31:29.225256Z","caller":"traceutil/trace.go:171","msg":"trace[613248961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3716; }","duration":"326.106292ms","start":"2026-07-24T21:31:28.899124Z","end":"2026-07-24T21:31:29.22523Z","steps":["trace[613248961] 'agreement among raft nodes before linearized reading' (duration: 325.837236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:31:29.225323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:31:28.899103Z","time spent":"326.205614ms","remote":"127.0.0.1:40384","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":"info","ts":"2026-07-24T21:31:29.296056Z","caller":"traceutil/trace.go:171","msg":"trace[1924308909] transaction","detail":"{read_only:false; response_revision:3717; number_of_response:1; }","duration":"185.99736ms","start":"2026-07-24T21:31:29.11003Z","end":"2026-07-24T21:31:29.296028Z","steps":["trace[1924308909] 'process raft request' (duration: 185.833807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:31:29.296147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.142268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:31:29.296229Z","caller":"traceutil/trace.go:171","msg":"trace[1129628516] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3717; }","duration":"243.263741ms","start":"2026-07-24T21:31:29.052946Z","end":"2026-07-24T21:31:29.29621Z","steps":["trace[1129628516] 'agreement among raft nodes before linearized reading' (duration: 243.060737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:31:29.296347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.643074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:31:29.296667Z","caller":"traceutil/trace.go:171","msg":"trace[2070424716] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:3717; }","duration":"159.020143ms","start":"2026-07-24T21:31:29.137602Z","end":"2026-07-24T21:31:29.296622Z","steps":["trace[2070424716] 'agreement among raft nodes before linearized reading' (duration: 158.464031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:31:29.510922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.302851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:31:29.51101Z","caller":"traceutil/trace.go:171","msg":"trace[1553612691] linearizableReadLoop","detail":"{readStateIndex:3853; appliedIndex:3852; }","duration":"211.735354ms","start":"2026-07-24T21:31:29.299262Z","end":"2026-07-24T21:31:29.510997Z","steps":["trace[1553612691] 'read index received' (duration: 84.27648ms)","trace[1553612691] 'applied index is now lower than readState.Index' (duration: 127.457764ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:31:29.511053Z","caller":"traceutil/trace.go:171","msg":"trace[1765046452] transaction","detail":"{read_only:false; response_revision:3718; number_of_response:1; }","duration":"280.897417ms","start":"2026-07-24T21:31:29.230114Z","end":"2026-07-24T21:31:29.511011Z","steps":["trace[1765046452] 'process raft request' (duration: 153.425483ms)","trace[1765046452] 'compare' (duration: 127.062316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:31:29.511282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.960788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-07-24T21:31:29.511278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.280682ms","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-07-24T21:31:29.511344Z","caller":"traceutil/trace.go:171","msg":"trace[317038913] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3718; }","duration":"212.09811ms","start":"2026-07-24T21:31:29.299231Z","end":"2026-07-24T21:31:29.511329Z","steps":["trace[317038913] 'agreement among raft nodes before linearized reading' (duration: 211.829205ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:31:29.511357Z","caller":"traceutil/trace.go:171","msg":"trace[1794707581] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:3718; }","duration":"147.464055ms","start":"2026-07-24T21:31:29.363876Z","end":"2026-07-24T21:31:29.51134Z","steps":["trace[1794707581] 'agreement among raft nodes before linearized reading' (duration: 147.280152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:31:29.511299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.590062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:10 size:97759"} {"level":"info","ts":"2026-07-24T21:31:29.511448Z","caller":"traceutil/trace.go:171","msg":"trace[1409063533] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:10; response_revision:3718; }","duration":"209.729315ms","start":"2026-07-24T21:31:29.30169Z","end":"2026-07-24T21:31:29.51142Z","steps":["trace[1409063533] 'agreement among raft nodes before linearized reading' (duration: 209.389138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:31:30.035137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.063478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:31:30.035221Z","caller":"traceutil/trace.go:171","msg":"trace[513725203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3720; }","duration":"148.215761ms","start":"2026-07-24T21:31:29.886987Z","end":"2026-07-24T21:31:30.035203Z","steps":["trace[513725203] 'range keys from in-memory index tree' (duration: 147.796593ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:31:36.71734Z","caller":"traceutil/trace.go:171","msg":"trace[769161922] transaction","detail":"{read_only:false; response_revision:3755; number_of_response:1; }","duration":"133.720427ms","start":"2026-07-24T21:31:36.583592Z","end":"2026-07-24T21:31:36.717313Z","steps":["trace[769161922] 'process raft request' (duration: 133.526593ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:31:51.444545Z","caller":"traceutil/trace.go:171","msg":"trace[479426949] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"141.710142ms","start":"2026-07-24T21:31:51.302802Z","end":"2026-07-24T21:31:51.444512Z","steps":["trace[479426949] 'process raft request' (duration: 141.490558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:31:53.100819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.322668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:31:53.100928Z","caller":"traceutil/trace.go:171","msg":"trace[321769505] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:3837; }","duration":"140.50306ms","start":"2026-07-24T21:31:52.960403Z","end":"2026-07-24T21:31:53.100906Z","steps":["trace[321769505] 'count revisions from in-memory index tree' (duration: 140.186005ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:18.52752Z","caller":"traceutil/trace.go:171","msg":"trace[447715537] linearizableReadLoop","detail":"{readStateIndex:4114; appliedIndex:4113; }","duration":"152.119166ms","start":"2026-07-24T21:32:18.375351Z","end":"2026-07-24T21:32:18.52747Z","steps":["trace[447715537] 'read index received' (duration: 151.952152ms)","trace[447715537] 'applied index is now lower than readState.Index' (duration: 165.964µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:18.527588Z","caller":"traceutil/trace.go:171","msg":"trace[1658594428] transaction","detail":"{read_only:false; response_revision:3969; number_of_response:1; }","duration":"207.255714ms","start":"2026-07-24T21:32:18.320302Z","end":"2026-07-24T21:32:18.527558Z","steps":["trace[1658594428] 'process raft request' (duration: 206.970428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:18.52772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.335829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:18.527821Z","caller":"traceutil/trace.go:171","msg":"trace[455553273] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:3969; }","duration":"152.499192ms","start":"2026-07-24T21:32:18.375304Z","end":"2026-07-24T21:32:18.527803Z","steps":["trace[455553273] 'agreement among raft nodes before linearized reading' (duration: 152.303509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:18.790808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.837806ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T21:32:18.790996Z","caller":"traceutil/trace.go:171","msg":"trace[1213461913] linearizableReadLoop","detail":"{readStateIndex:4115; appliedIndex:4114; }","duration":"207.654592ms","start":"2026-07-24T21:32:18.583322Z","end":"2026-07-24T21:32:18.790977Z","steps":["trace[1213461913] 'read index received' (duration: 4.614521ms)","trace[1213461913] 'applied index is now lower than readState.Index' (duration: 203.038251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:18.791237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.0354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:18.791316Z","caller":"traceutil/trace.go:171","msg":"trace[1777279509] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:3969; }","duration":"137.152971ms","start":"2026-07-24T21:32:18.654149Z","end":"2026-07-24T21:32:18.791302Z","steps":["trace[1777279509] 'agreement among raft nodes before linearized reading' (duration: 137.027259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:18.791244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.910126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:18.791414Z","caller":"traceutil/trace.go:171","msg":"trace[86270143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3969; }","duration":"208.128ms","start":"2026-07-24T21:32:18.583269Z","end":"2026-07-24T21:32:18.791397Z","steps":["trace[86270143] 'agreement among raft nodes before linearized reading' (duration: 207.877516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:18.791455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.399041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-24T21:32:18.791529Z","caller":"traceutil/trace.go:171","msg":"trace[495102548] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:3969; }","duration":"102.506403ms","start":"2026-07-24T21:32:18.689007Z","end":"2026-07-24T21:32:18.791513Z","steps":["trace[495102548] 'agreement among raft nodes before linearized reading' (duration: 102.32819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:19.082315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.532653ms","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-07-24T21:32:19.08238Z","caller":"traceutil/trace.go:171","msg":"trace[552492353] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:3970; }","duration":"139.615945ms","start":"2026-07-24T21:32:18.942749Z","end":"2026-07-24T21:32:19.082365Z","steps":["trace[552492353] 'count revisions from in-memory index tree' (duration: 139.415452ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:19.082602Z","caller":"traceutil/trace.go:171","msg":"trace[214631994] transaction","detail":"{read_only:false; response_revision:3971; number_of_response:1; }","duration":"139.32812ms","start":"2026-07-24T21:32:18.943246Z","end":"2026-07-24T21:32:19.082574Z","steps":["trace[214631994] 'process raft request' (duration: 130.897312ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:19.130823Z","caller":"traceutil/trace.go:171","msg":"trace[142332773] transaction","detail":"{read_only:false; response_revision:3972; number_of_response:1; }","duration":"177.609394ms","start":"2026-07-24T21:32:18.953189Z","end":"2026-07-24T21:32:19.130798Z","steps":["trace[142332773] 'process raft request' (duration: 177.462521ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:19.958868Z","caller":"traceutil/trace.go:171","msg":"trace[857772606] transaction","detail":"{read_only:false; response_revision:3973; number_of_response:1; }","duration":"255.059004ms","start":"2026-07-24T21:32:19.703785Z","end":"2026-07-24T21:32:19.958844Z","steps":["trace[857772606] 'process raft request' (duration: 254.839821ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:19.967875Z","caller":"traceutil/trace.go:171","msg":"trace[1469200215] linearizableReadLoop","detail":"{readStateIndex:4120; appliedIndex:4118; }","duration":"260.962678ms","start":"2026-07-24T21:32:19.706891Z","end":"2026-07-24T21:32:19.967854Z","steps":["trace[1469200215] 'read index received' (duration: 251.798507ms)","trace[1469200215] 'applied index is now lower than readState.Index' (duration: 9.162991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:19.968183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.909089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:32:19.968217Z","caller":"traceutil/trace.go:171","msg":"trace[804438876] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:3975; }","duration":"181.991051ms","start":"2026-07-24T21:32:19.786217Z","end":"2026-07-24T21:32:19.968208Z","steps":["trace[804438876] 'agreement among raft nodes before linearized reading' (duration: 181.857398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:19.968166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.234093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:19.968272Z","caller":"traceutil/trace.go:171","msg":"trace[262065007] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:3975; }","duration":"261.412287ms","start":"2026-07-24T21:32:19.706834Z","end":"2026-07-24T21:32:19.968246Z","steps":["trace[262065007] 'agreement among raft nodes before linearized reading' (duration: 261.202354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:19.96849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.219723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:32:19.968513Z","caller":"traceutil/trace.go:171","msg":"trace[1908108011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3975; }","duration":"145.265195ms","start":"2026-07-24T21:32:19.823241Z","end":"2026-07-24T21:32:19.968507Z","steps":["trace[1908108011] 'agreement among raft nodes before linearized reading' (duration: 145.140853ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:19.968519Z","caller":"traceutil/trace.go:171","msg":"trace[427442439] transaction","detail":"{read_only:false; response_revision:3974; number_of_response:1; }","duration":"264.025932ms","start":"2026-07-24T21:32:19.704456Z","end":"2026-07-24T21:32:19.968482Z","steps":["trace[427442439] 'process raft request' (duration: 263.254679ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:19.968665Z","caller":"traceutil/trace.go:171","msg":"trace[1158707422] transaction","detail":"{read_only:false; response_revision:3975; number_of_response:1; }","duration":"159.083817ms","start":"2026-07-24T21:32:19.809556Z","end":"2026-07-24T21:32:19.96864Z","steps":["trace[1158707422] 'process raft request' (duration: 158.262933ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:22.109842Z","caller":"traceutil/trace.go:171","msg":"trace[1282681598] linearizableReadLoop","detail":"{readStateIndex:4131; appliedIndex:4129; }","duration":"134.888051ms","start":"2026-07-24T21:32:21.974931Z","end":"2026-07-24T21:32:22.109819Z","steps":["trace[1282681598] 'read index received' (duration: 62.168703ms)","trace[1282681598] 'applied index is now lower than readState.Index' (duration: 72.718108ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:22.109975Z","caller":"traceutil/trace.go:171","msg":"trace[123303099] transaction","detail":"{read_only:false; response_revision:3985; number_of_response:1; }","duration":"137.03958ms","start":"2026-07-24T21:32:21.972925Z","end":"2026-07-24T21:32:22.109965Z","steps":["trace[123303099] 'process raft request' (duration: 136.751305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:22.11014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.031366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:32:22.110202Z","caller":"traceutil/trace.go:171","msg":"trace[1138263896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3985; }","duration":"134.127808ms","start":"2026-07-24T21:32:21.97606Z","end":"2026-07-24T21:32:22.110187Z","steps":["trace[1138263896] 'agreement among raft nodes before linearized reading' (duration: 133.999136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:22.110162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.206318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:32:22.11022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.056059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:32:22.110279Z","caller":"traceutil/trace.go:171","msg":"trace[1258333583] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3985; }","duration":"125.15981ms","start":"2026-07-24T21:32:21.985103Z","end":"2026-07-24T21:32:22.110263Z","steps":["trace[1258333583] 'agreement among raft nodes before linearized reading' (duration: 125.018748ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:22.110302Z","caller":"traceutil/trace.go:171","msg":"trace[1031773762] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:3985; }","duration":"135.35734ms","start":"2026-07-24T21:32:21.97489Z","end":"2026-07-24T21:32:22.110247Z","steps":["trace[1031773762] 'agreement among raft nodes before linearized reading' (duration: 135.195457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:22.110341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.180094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:32:22.110391Z","caller":"traceutil/trace.go:171","msg":"trace[1493504818] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:3985; }","duration":"132.257615ms","start":"2026-07-24T21:32:21.978122Z","end":"2026-07-24T21:32:22.110379Z","steps":["trace[1493504818] 'agreement among raft nodes before linearized reading' (duration: 132.078072ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:31.096193Z","caller":"traceutil/trace.go:171","msg":"trace[735583901] transaction","detail":"{read_only:false; response_revision:4030; number_of_response:1; }","duration":"195.927115ms","start":"2026-07-24T21:32:30.900239Z","end":"2026-07-24T21:32:31.096167Z","steps":["trace[735583901] 'process raft request' (duration: 195.773592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:31.331176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.273976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:32:31.331433Z","caller":"traceutil/trace.go:171","msg":"trace[489571157] transaction","detail":"{read_only:false; response_revision:4032; number_of_response:1; }","duration":"163.776519ms","start":"2026-07-24T21:32:31.167634Z","end":"2026-07-24T21:32:31.331411Z","steps":["trace[489571157] 'process raft request' (duration: 163.657347ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:31.331653Z","caller":"traceutil/trace.go:171","msg":"trace[673784420] transaction","detail":"{read_only:false; response_revision:4031; number_of_response:1; }","duration":"214.277227ms","start":"2026-07-24T21:32:31.117344Z","end":"2026-07-24T21:32:31.331621Z","steps":["trace[673784420] 'process raft request' (duration: 73.450341ms)","trace[673784420] 'compare' (duration: 140.065533ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:31.82022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.221491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:31.820311Z","caller":"traceutil/trace.go:171","msg":"trace[299306096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4032; }","duration":"237.370744ms","start":"2026-07-24T21:32:31.58292Z","end":"2026-07-24T21:32:31.820291Z","steps":["trace[299306096] 'range keys from in-memory index tree' (duration: 237.11934ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:31.820856Z","caller":"traceutil/trace.go:171","msg":"trace[361768539] transaction","detail":"{read_only:false; response_revision:4033; number_of_response:1; }","duration":"109.724299ms","start":"2026-07-24T21:32:31.711099Z","end":"2026-07-24T21:32:31.820823Z","steps":["trace[361768539] 'process raft request' (duration: 60.343241ms)","trace[361768539] 'compare' (duration: 48.706587ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:32.203121Z","caller":"traceutil/trace.go:171","msg":"trace[1319481526] transaction","detail":"{read_only:false; response_revision:4034; number_of_response:1; }","duration":"135.139376ms","start":"2026-07-24T21:32:32.067943Z","end":"2026-07-24T21:32:32.203083Z","steps":["trace[1319481526] 'process raft request' (duration: 135.006844ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:39.263294Z","caller":"traceutil/trace.go:171","msg":"trace[1045517718] transaction","detail":"{read_only:false; response_revision:4071; number_of_response:1; }","duration":"127.248678ms","start":"2026-07-24T21:32:39.136018Z","end":"2026-07-24T21:32:39.263267Z","steps":["trace[1045517718] 'process raft request' (duration: 127.089234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:39.508393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.20638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:32:39.5085Z","caller":"traceutil/trace.go:171","msg":"trace[1274013909] transaction","detail":"{read_only:false; response_revision:4072; number_of_response:1; }","duration":"150.407735ms","start":"2026-07-24T21:32:39.358072Z","end":"2026-07-24T21:32:39.50848Z","steps":["trace[1274013909] 'process raft request' (duration: 49.028332ms)","trace[1274013909] 'compare' (duration: 101.102018ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:39.513839Z","caller":"traceutil/trace.go:171","msg":"trace[263374697] transaction","detail":"{read_only:false; response_revision:4073; number_of_response:1; }","duration":"155.651728ms","start":"2026-07-24T21:32:39.358164Z","end":"2026-07-24T21:32:39.513816Z","steps":["trace[263374697] 'process raft request' (duration: 155.551276ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:46.432704Z","caller":"traceutil/trace.go:171","msg":"trace[1311652205] linearizableReadLoop","detail":"{readStateIndex:4258; appliedIndex:4257; }","duration":"147.486723ms","start":"2026-07-24T21:32:46.285192Z","end":"2026-07-24T21:32:46.432679Z","steps":["trace[1311652205] 'read index received' (duration: 131.243388ms)","trace[1311652205] 'applied index is now lower than readState.Index' (duration: 16.241985ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:46.432828Z","caller":"traceutil/trace.go:171","msg":"trace[678253351] transaction","detail":"{read_only:false; response_revision:4107; number_of_response:1; }","duration":"175.654799ms","start":"2026-07-24T21:32:46.257159Z","end":"2026-07-24T21:32:46.432814Z","steps":["trace[678253351] 'process raft request' (duration: 159.324901ms)","trace[678253351] 'compare' (duration: 15.947441ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:46.43299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.138339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:32:46.433066Z","caller":"traceutil/trace.go:171","msg":"trace[1735452350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4107; }","duration":"142.225911ms","start":"2026-07-24T21:32:46.290821Z","end":"2026-07-24T21:32:46.433047Z","steps":["trace[1735452350] 'agreement among raft nodes before linearized reading' (duration: 142.021337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:46.433057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.850369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-24T21:32:46.433059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.042997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-24T21:32:46.433062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.95241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:32:46.433116Z","caller":"traceutil/trace.go:171","msg":"trace[260481549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4107; }","duration":"147.946852ms","start":"2026-07-24T21:32:46.285156Z","end":"2026-07-24T21:32:46.433103Z","steps":["trace[260481549] 'agreement among raft nodes before linearized reading' (duration: 147.743398ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:46.433136Z","caller":"traceutil/trace.go:171","msg":"trace[1833226314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4107; }","duration":"142.199651ms","start":"2026-07-24T21:32:46.290918Z","end":"2026-07-24T21:32:46.433118Z","steps":["trace[1833226314] 'agreement among raft nodes before linearized reading' (duration: 141.967227ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:46.433148Z","caller":"traceutil/trace.go:171","msg":"trace[1480040541] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4107; }","duration":"136.068393ms","start":"2026-07-24T21:32:46.29706Z","end":"2026-07-24T21:32:46.433128Z","steps":["trace[1480040541] 'agreement among raft nodes before linearized reading' (duration: 135.835669ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:46.627265Z","caller":"traceutil/trace.go:171","msg":"trace[568425830] linearizableReadLoop","detail":"{readStateIndex:4261; appliedIndex:4258; }","duration":"188.969102ms","start":"2026-07-24T21:32:46.438264Z","end":"2026-07-24T21:32:46.627233Z","steps":["trace[568425830] 'read index received' (duration: 6.809149ms)","trace[568425830] 'applied index is now lower than readState.Index' (duration: 182.157983ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:46.627377Z","caller":"traceutil/trace.go:171","msg":"trace[733411726] transaction","detail":"{read_only:false; response_revision:4110; number_of_response:1; }","duration":"190.733254ms","start":"2026-07-24T21:32:46.436597Z","end":"2026-07-24T21:32:46.62733Z","steps":["trace[733411726] 'process raft request' (duration: 163.287042ms)","trace[733411726] 'compare' (duration: 27.189557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:46.627751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.461511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:10 size:97759"} {"level":"info","ts":"2026-07-24T21:32:46.627843Z","caller":"traceutil/trace.go:171","msg":"trace[980115411] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:10; response_revision:4110; }","duration":"189.596213ms","start":"2026-07-24T21:32:46.438227Z","end":"2026-07-24T21:32:46.627823Z","steps":["trace[980115411] 'agreement among raft nodes before linearized reading' (duration: 189.121665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:46.883074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.149316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:32:46.8833Z","caller":"traceutil/trace.go:171","msg":"trace[534079800] transaction","detail":"{read_only:false; response_revision:4111; number_of_response:1; }","duration":"183.330843ms","start":"2026-07-24T21:32:46.699912Z","end":"2026-07-24T21:32:46.883242Z","steps":["trace[534079800] 'process raft request' (duration: 34.912292ms)","trace[534079800] 'compare' (duration: 147.968432ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:51.052527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.136753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"warn","ts":"2026-07-24T21:32:51.052592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.827216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:51.052674Z","caller":"traceutil/trace.go:171","msg":"trace[1704593863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4129; }","duration":"469.976648ms","start":"2026-07-24T21:32:50.582679Z","end":"2026-07-24T21:32:51.052655Z","steps":["trace[1704593863] 'range keys from in-memory index tree' (duration: 469.744475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:51.052693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.226053ms","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-07-24T21:32:51.052741Z","caller":"traceutil/trace.go:171","msg":"trace[1654191690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4129; }","duration":"412.278683ms","start":"2026-07-24T21:32:50.640437Z","end":"2026-07-24T21:32:51.052715Z","steps":["trace[1654191690] 'range keys from in-memory index tree' (duration: 412.022199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:51.052741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:32:50.582661Z","time spent":"470.069991ms","remote":"127.0.0.1:40078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T21:32:51.052774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:32:50.640426Z","time spent":"412.339724ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T21:32:51.052602Z","caller":"traceutil/trace.go:171","msg":"trace[1393596002] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4129; }","duration":"409.255536ms","start":"2026-07-24T21:32:50.643328Z","end":"2026-07-24T21:32:51.052584Z","steps":["trace[1393596002] 'range keys from in-memory index tree' (duration: 408.94671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:51.052886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:32:50.64331Z","time spent":"409.540741ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6465,"request content":"key:\"/registry/pods/openstack/keepalived-9k2jv\" "} {"level":"warn","ts":"2026-07-24T21:32:51.052941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.557843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:32:51.05305Z","caller":"traceutil/trace.go:171","msg":"trace[568732963] linearizableReadLoop","detail":"{readStateIndex:4282; appliedIndex:4281; }","duration":"157.22577ms","start":"2026-07-24T21:32:50.895803Z","end":"2026-07-24T21:32:51.053028Z","steps":["trace[568732963] 'read index received' (duration: 39.011µs)","trace[568732963] 'applied index is now lower than readState.Index' (duration: 157.185009ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:51.053075Z","caller":"traceutil/trace.go:171","msg":"trace[1831709330] transaction","detail":"{read_only:false; response_revision:4130; number_of_response:1; }","duration":"394.29427ms","start":"2026-07-24T21:32:50.658758Z","end":"2026-07-24T21:32:51.053052Z","steps":["trace[1831709330] 'process raft request' (duration: 232.494632ms)","trace[1831709330] 'compare' (duration: 161.420431ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:51.053164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.364643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:32:51.0532Z","caller":"traceutil/trace.go:171","msg":"trace[1530211193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4130; }","duration":"157.444664ms","start":"2026-07-24T21:32:50.895746Z","end":"2026-07-24T21:32:51.05319Z","steps":["trace[1530211193] 'agreement among raft nodes before linearized reading' (duration: 157.339042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:51.053186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:32:50.65875Z","time spent":"394.370131ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:32:51.23694Z","caller":"traceutil/trace.go:171","msg":"trace[806463532] transaction","detail":"{read_only:false; response_revision:4131; number_of_response:1; }","duration":"302.381119ms","start":"2026-07-24T21:32:50.934529Z","end":"2026-07-24T21:32:51.23691Z","steps":["trace[806463532] 'process raft request' (duration: 300.156216ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:51.23703Z","caller":"traceutil/trace.go:171","msg":"trace[1056325014] linearizableReadLoop","detail":"{readStateIndex:4283; appliedIndex:4282; }","duration":"183.105226ms","start":"2026-07-24T21:32:51.053897Z","end":"2026-07-24T21:32:51.237002Z","steps":["trace[1056325014] 'read index received' (duration: 180.861393ms)","trace[1056325014] 'applied index is now lower than readState.Index' (duration: 2.241633ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:51.237088Z","caller":"traceutil/trace.go:171","msg":"trace[570398111] transaction","detail":"{read_only:false; response_revision:4132; number_of_response:1; }","duration":"180.756872ms","start":"2026-07-24T21:32:51.056288Z","end":"2026-07-24T21:32:51.237045Z","steps":["trace[570398111] 'process raft request' (duration: 180.627609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:51.2371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:32:50.934504Z","time spent":"302.515002ms","remote":"127.0.0.1:40384","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-07-24T21:32:51.237135Z","caller":"traceutil/trace.go:171","msg":"trace[1395120745] transaction","detail":"{read_only:false; response_revision:4133; number_of_response:1; }","duration":"180.087417ms","start":"2026-07-24T21:32:51.057034Z","end":"2026-07-24T21:32:51.237122Z","steps":["trace[1395120745] 'process raft request' (duration: 179.973865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:51.237211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.29552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:51.237251Z","caller":"traceutil/trace.go:171","msg":"trace[87912424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4133; }","duration":"183.376762ms","start":"2026-07-24T21:32:51.053864Z","end":"2026-07-24T21:32:51.237241Z","steps":["trace[87912424] 'agreement among raft nodes before linearized reading' (duration: 183.28187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:51.237392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.096148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:10 size:97759"} {"level":"info","ts":"2026-07-24T21:32:51.237515Z","caller":"traceutil/trace.go:171","msg":"trace[604599242] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:10; response_revision:4133; }","duration":"181.23124ms","start":"2026-07-24T21:32:51.056266Z","end":"2026-07-24T21:32:51.237498Z","steps":["trace[604599242] 'agreement among raft nodes before linearized reading' (duration: 180.890464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:53.616563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.926822ms","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:7"} {"level":"info","ts":"2026-07-24T21:32:53.616619Z","caller":"traceutil/trace.go:171","msg":"trace[1810352974] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:4144; }","duration":"169.992953ms","start":"2026-07-24T21:32:53.446616Z","end":"2026-07-24T21:32:53.616609Z","steps":["trace[1810352974] 'count revisions from in-memory index tree' (duration: 169.842941ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:32:53.687916Z","caller":"traceutil/trace.go:171","msg":"trace[869233281] transaction","detail":"{read_only:false; response_revision:4145; number_of_response:1; }","duration":"133.033308ms","start":"2026-07-24T21:32:53.554861Z","end":"2026-07-24T21:32:53.687894Z","steps":["trace[869233281] 'process raft request' (duration: 132.894335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:53.887421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.732064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:32:53.887591Z","caller":"traceutil/trace.go:171","msg":"trace[691770645] linearizableReadLoop","detail":"{readStateIndex:4299; appliedIndex:4297; }","duration":"307.04521ms","start":"2026-07-24T21:32:53.580527Z","end":"2026-07-24T21:32:53.887572Z","steps":["trace[691770645] 'read index received' (duration: 107.200014ms)","trace[691770645] 'applied index is now lower than readState.Index' (duration: 199.843736ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:32:53.887691Z","caller":"traceutil/trace.go:171","msg":"trace[599632374] transaction","detail":"{read_only:false; response_revision:4146; number_of_response:1; }","duration":"330.877295ms","start":"2026-07-24T21:32:53.556779Z","end":"2026-07-24T21:32:53.887656Z","steps":["trace[599632374] 'process raft request' (duration: 189.820665ms)","trace[599632374] 'compare' (duration: 140.559691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:32:53.887733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.675342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:53.887774Z","caller":"traceutil/trace.go:171","msg":"trace[1225885070] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4146; }","duration":"142.744983ms","start":"2026-07-24T21:32:53.74502Z","end":"2026-07-24T21:32:53.887765Z","steps":["trace[1225885070] 'agreement among raft nodes before linearized reading' (duration: 142.655652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:53.887777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.234153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:32:53.887859Z","caller":"traceutil/trace.go:171","msg":"trace[536962821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4146; }","duration":"307.322335ms","start":"2026-07-24T21:32:53.580517Z","end":"2026-07-24T21:32:53.88784Z","steps":["trace[536962821] 'agreement among raft nodes before linearized reading' (duration: 307.140131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:32:53.887904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:32:53.580472Z","time spent":"307.424377ms","remote":"127.0.0.1:40078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T21:32:53.887885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:32:53.556764Z","time spent":"331.007597ms","remote":"127.0.0.1:40384","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-07-24T21:32:59.449146Z","caller":"traceutil/trace.go:171","msg":"trace[162668286] transaction","detail":"{read_only:false; response_revision:4173; number_of_response:1; }","duration":"100.996764ms","start":"2026-07-24T21:32:59.348121Z","end":"2026-07-24T21:32:59.449118Z","steps":["trace[162668286] 'process raft request' (duration: 89.713798ms)","trace[162668286] 'compare' (duration: 11.178544ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:33:10.130518Z","caller":"traceutil/trace.go:171","msg":"trace[1603041925] transaction","detail":"{read_only:false; response_revision:4226; number_of_response:1; }","duration":"177.427676ms","start":"2026-07-24T21:33:09.953066Z","end":"2026-07-24T21:33:10.130494Z","steps":["trace[1603041925] 'process raft request' (duration: 177.255913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:10.670738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.137154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:33:10.670869Z","caller":"traceutil/trace.go:171","msg":"trace[1449744236] linearizableReadLoop","detail":"{readStateIndex:4384; appliedIndex:4383; }","duration":"128.389038ms","start":"2026-07-24T21:33:10.542456Z","end":"2026-07-24T21:33:10.670845Z","steps":["trace[1449744236] 'read index received' (duration: 23.032141ms)","trace[1449744236] 'applied index is now lower than readState.Index' (duration: 105.355137ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:33:10.671003Z","caller":"traceutil/trace.go:171","msg":"trace[1379011509] transaction","detail":"{read_only:false; response_revision:4228; number_of_response:1; }","duration":"164.153922ms","start":"2026-07-24T21:33:10.50683Z","end":"2026-07-24T21:33:10.670984Z","steps":["trace[1379011509] 'process raft request' (duration: 58.644791ms)","trace[1379011509] 'compare' (duration: 104.845709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:10.671157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.652245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:33:10.671227Z","caller":"traceutil/trace.go:171","msg":"trace[1814304240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4228; }","duration":"126.774217ms","start":"2026-07-24T21:33:10.544439Z","end":"2026-07-24T21:33:10.671214Z","steps":["trace[1814304240] 'agreement among raft nodes before linearized reading' (duration: 126.598184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:10.671329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.844297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:33:10.671481Z","caller":"traceutil/trace.go:171","msg":"trace[918345115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4228; }","duration":"129.01484ms","start":"2026-07-24T21:33:10.542418Z","end":"2026-07-24T21:33:10.671433Z","steps":["trace[918345115] 'agreement among raft nodes before linearized reading' (duration: 128.675944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:17.697361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.045983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T21:33:17.697447Z","caller":"traceutil/trace.go:171","msg":"trace[1235928410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4264; }","duration":"172.181716ms","start":"2026-07-24T21:33:17.525245Z","end":"2026-07-24T21:33:17.697427Z","steps":["trace[1235928410] 'range keys from in-memory index tree' (duration: 171.82337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:17.697536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.148153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:33:17.697598Z","caller":"traceutil/trace.go:171","msg":"trace[1084772986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4264; }","duration":"117.49804ms","start":"2026-07-24T21:33:17.580081Z","end":"2026-07-24T21:33:17.697579Z","steps":["trace[1084772986] 'range keys from in-memory index tree' (duration: 116.962789ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:17.887924Z","caller":"traceutil/trace.go:171","msg":"trace[1411157623] transaction","detail":"{read_only:false; response_revision:4265; number_of_response:1; }","duration":"185.197726ms","start":"2026-07-24T21:33:17.702701Z","end":"2026-07-24T21:33:17.887898Z","steps":["trace[1411157623] 'process raft request' (duration: 185.058863ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:17.994131Z","caller":"traceutil/trace.go:171","msg":"trace[2017687774] transaction","detail":"{read_only:false; response_revision:4266; number_of_response:1; }","duration":"183.596674ms","start":"2026-07-24T21:33:17.81051Z","end":"2026-07-24T21:33:17.994107Z","steps":["trace[2017687774] 'process raft request' (duration: 178.231422ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:17.994306Z","caller":"traceutil/trace.go:171","msg":"trace[1410023793] transaction","detail":"{read_only:false; response_revision:4267; number_of_response:1; }","duration":"169.419933ms","start":"2026-07-24T21:33:17.824853Z","end":"2026-07-24T21:33:17.994273Z","steps":["trace[1410023793] 'process raft request' (duration: 169.20772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:18.346633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.986944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:33:18.346757Z","caller":"traceutil/trace.go:171","msg":"trace[675784173] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4267; }","duration":"194.172178ms","start":"2026-07-24T21:33:18.152539Z","end":"2026-07-24T21:33:18.346711Z","steps":["trace[675784173] 'count revisions from in-memory index tree' (duration: 193.77821ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:18.346728Z","caller":"traceutil/trace.go:171","msg":"trace[1946344419] transaction","detail":"{read_only:false; response_revision:4268; number_of_response:1; }","duration":"192.400414ms","start":"2026-07-24T21:33:18.154288Z","end":"2026-07-24T21:33:18.346688Z","steps":["trace[1946344419] 'process raft request' (duration: 137.272708ms)","trace[1946344419] 'compare' (duration: 54.959203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:21.679419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.452855ms","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-07-24T21:33:21.679543Z","caller":"traceutil/trace.go:171","msg":"trace[2089836221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4283; }","duration":"262.646489ms","start":"2026-07-24T21:33:21.416873Z","end":"2026-07-24T21:33:21.67952Z","steps":["trace[2089836221] 'range keys from in-memory index tree' (duration: 262.228171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:21.679751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.49254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:33:21.679832Z","caller":"traceutil/trace.go:171","msg":"trace[748171357] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4283; }","duration":"257.622713ms","start":"2026-07-24T21:33:21.422191Z","end":"2026-07-24T21:33:21.679814Z","steps":["trace[748171357] 'range keys from in-memory index tree' (duration: 257.308046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:21.679845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.674996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:33:21.679999Z","caller":"traceutil/trace.go:171","msg":"trace[835285953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4283; }","duration":"267.881029ms","start":"2026-07-24T21:33:21.412098Z","end":"2026-07-24T21:33:21.679979Z","steps":["trace[835285953] 'range keys from in-memory index tree' (duration: 267.504312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:24.875324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.595951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:33:24.875747Z","caller":"traceutil/trace.go:171","msg":"trace[232602679] transaction","detail":"{read_only:false; response_revision:4300; number_of_response:1; }","duration":"152.004181ms","start":"2026-07-24T21:33:24.723541Z","end":"2026-07-24T21:33:24.875545Z","steps":["trace[232602679] 'process raft request' (duration: 33.090414ms)","trace[232602679] 'compare' (duration: 118.470299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:25.183764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.293276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:33:25.184013Z","caller":"traceutil/trace.go:171","msg":"trace[1191705193] transaction","detail":"{read_only:false; response_revision:4302; number_of_response:1; }","duration":"303.692415ms","start":"2026-07-24T21:33:24.880296Z","end":"2026-07-24T21:33:25.183988Z","steps":["trace[1191705193] 'process raft request' (duration: 303.614123ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:25.184063Z","caller":"traceutil/trace.go:171","msg":"trace[1388069998] transaction","detail":"{read_only:false; response_revision:4301; number_of_response:1; }","duration":"304.568822ms","start":"2026-07-24T21:33:24.879462Z","end":"2026-07-24T21:33:25.18403Z","steps":["trace[1388069998] 'process raft request' (duration: 152.898668ms)","trace[1388069998] 'compare' (duration: 151.141904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:25.1841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:24.880272Z","time spent":"303.789407ms","remote":"127.0.0.1:40384","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-07-24T21:33:25.184218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:24.879438Z","time spent":"304.697514ms","remote":"127.0.0.1:40384","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-07-24T21:33:31.744416Z","caller":"traceutil/trace.go:171","msg":"trace[104247611] transaction","detail":"{read_only:false; response_revision:4332; number_of_response:1; }","duration":"103.280218ms","start":"2026-07-24T21:33:31.64111Z","end":"2026-07-24T21:33:31.744391Z","steps":["trace[104247611] 'process raft request' (duration: 103.068374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:32.058555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.54881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-24T21:33:32.058603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.559371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:33:32.058687Z","caller":"traceutil/trace.go:171","msg":"trace[557817280] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4332; }","duration":"257.692924ms","start":"2026-07-24T21:33:31.800976Z","end":"2026-07-24T21:33:32.058668Z","steps":["trace[557817280] 'range keys from in-memory index tree' (duration: 257.396478ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:32.058681Z","caller":"traceutil/trace.go:171","msg":"trace[274255632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4332; }","duration":"259.720893ms","start":"2026-07-24T21:33:31.798936Z","end":"2026-07-24T21:33:32.058657Z","steps":["trace[274255632] 'range keys from in-memory index tree' (duration: 259.365487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:32.059093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.963932ms","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-07-24T21:33:32.059195Z","caller":"traceutil/trace.go:171","msg":"trace[348393544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4332; }","duration":"266.118076ms","start":"2026-07-24T21:33:31.793055Z","end":"2026-07-24T21:33:32.059173Z","steps":["trace[348393544] 'range keys from in-memory index tree' (duration: 265.767559ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:32.140073Z","caller":"traceutil/trace.go:171","msg":"trace[280142252] linearizableReadLoop","detail":"{readStateIndex:4493; appliedIndex:4492; }","duration":"195.102726ms","start":"2026-07-24T21:33:31.944933Z","end":"2026-07-24T21:33:32.140035Z","steps":["trace[280142252] 'read index received' (duration: 194.908932ms)","trace[280142252] 'applied index is now lower than readState.Index' (duration: 192.334µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:33:32.140174Z","caller":"traceutil/trace.go:171","msg":"trace[564187295] transaction","detail":"{read_only:false; response_revision:4333; number_of_response:1; }","duration":"209.102324ms","start":"2026-07-24T21:33:31.931033Z","end":"2026-07-24T21:33:32.140136Z","steps":["trace[564187295] 'process raft request' (duration: 208.789388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:32.14032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.383891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T21:33:32.140371Z","caller":"traceutil/trace.go:171","msg":"trace[1425379073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4333; }","duration":"195.484003ms","start":"2026-07-24T21:33:31.944873Z","end":"2026-07-24T21:33:32.140357Z","steps":["trace[1425379073] 'agreement among raft nodes before linearized reading' (duration: 195.33615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:32.433964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.283073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:33:32.434151Z","caller":"traceutil/trace.go:171","msg":"trace[72460538] transaction","detail":"{read_only:false; response_revision:4335; number_of_response:1; }","duration":"370.29492ms","start":"2026-07-24T21:33:32.063839Z","end":"2026-07-24T21:33:32.434134Z","steps":["trace[72460538] 'process raft request' (duration: 370.219948ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:32.434183Z","caller":"traceutil/trace.go:171","msg":"trace[1261660556] transaction","detail":"{read_only:false; response_revision:4334; number_of_response:1; }","duration":"394.028424ms","start":"2026-07-24T21:33:32.040123Z","end":"2026-07-24T21:33:32.434152Z","steps":["trace[1261660556] 'process raft request' (duration: 120.470006ms)","trace[1261660556] 'compare' (duration: 273.159401ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:32.43422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:32.06382Z","time spent":"370.368651ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:33:32.434308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:32.040071Z","time spent":"394.169867ms","remote":"127.0.0.1:40384","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-07-24T21:33:32.434344Z","caller":"traceutil/trace.go:171","msg":"trace[975729182] transaction","detail":"{read_only:false; response_revision:4337; number_of_response:1; }","duration":"290.039184ms","start":"2026-07-24T21:33:32.144294Z","end":"2026-07-24T21:33:32.434333Z","steps":["trace[975729182] 'process raft request' (duration: 289.978122ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:32.434403Z","caller":"traceutil/trace.go:171","msg":"trace[1224909926] transaction","detail":"{read_only:false; response_revision:4336; number_of_response:1; }","duration":"370.539595ms","start":"2026-07-24T21:33:32.063853Z","end":"2026-07-24T21:33:32.434393Z","steps":["trace[1224909926] 'process raft request' (duration: 370.250399ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:32.434428Z","caller":"traceutil/trace.go:171","msg":"trace[1669118354] linearizableReadLoop","detail":"{readStateIndex:4496; appliedIndex:4493; }","duration":"294.251464ms","start":"2026-07-24T21:33:32.140167Z","end":"2026-07-24T21:33:32.434418Z","steps":["trace[1669118354] 'read index received' (duration: 20.437631ms)","trace[1669118354] 'applied index is now lower than readState.Index' (duration: 273.813193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:32.434461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:32.063833Z","time spent":"370.600135ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:33:32.434524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.025009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:33:32.434548Z","caller":"traceutil/trace.go:171","msg":"trace[842457902] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4337; }","duration":"470.08307ms","start":"2026-07-24T21:33:31.964459Z","end":"2026-07-24T21:33:32.434542Z","steps":["trace[842457902] 'agreement among raft nodes before linearized reading' (duration: 469.999919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:32.434568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:31.964446Z","time spent":"470.117491ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-24T21:33:32.434592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.108475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:10 size:97759"} {"level":"info","ts":"2026-07-24T21:33:32.434615Z","caller":"traceutil/trace.go:171","msg":"trace[1361337085] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:10; response_revision:4337; }","duration":"371.152356ms","start":"2026-07-24T21:33:32.063456Z","end":"2026-07-24T21:33:32.434608Z","steps":["trace[1361337085] 'agreement among raft nodes before linearized reading' (duration: 370.975572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:32.434642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:32.063441Z","time spent":"371.194237ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":10,"response size":97783,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-24T21:33:32.727686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.393364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:33:32.72776Z","caller":"traceutil/trace.go:171","msg":"trace[1665608845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4338; }","duration":"145.494036ms","start":"2026-07-24T21:33:32.582248Z","end":"2026-07-24T21:33:32.727742Z","steps":["trace[1665608845] 'range keys from in-memory index tree' (duration: 145.223621ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:32.728165Z","caller":"traceutil/trace.go:171","msg":"trace[469867157] transaction","detail":"{read_only:false; response_revision:4339; number_of_response:1; }","duration":"102.149746ms","start":"2026-07-24T21:33:32.625987Z","end":"2026-07-24T21:33:32.728137Z","steps":["trace[469867157] 'process raft request' (duration: 22.262106ms)","trace[469867157] 'compare' (duration: 79.776958ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:33:38.57756Z","caller":"traceutil/trace.go:171","msg":"trace[446653853] transaction","detail":"{read_only:false; response_revision:4365; number_of_response:1; }","duration":"124.81618ms","start":"2026-07-24T21:33:38.452725Z","end":"2026-07-24T21:33:38.577541Z","steps":["trace[446653853] 'process raft request' (duration: 124.714729ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:38.577621Z","caller":"traceutil/trace.go:171","msg":"trace[2123742051] linearizableReadLoop","detail":"{readStateIndex:4526; appliedIndex:4526; }","duration":"123.601236ms","start":"2026-07-24T21:33:38.453962Z","end":"2026-07-24T21:33:38.577563Z","steps":["trace[2123742051] 'read index received' (duration: 123.579776ms)","trace[2123742051] 'applied index is now lower than readState.Index' (duration: 18µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:38.577913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.923533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T21:33:38.578004Z","caller":"traceutil/trace.go:171","msg":"trace[1737359165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4365; }","duration":"124.060415ms","start":"2026-07-24T21:33:38.453922Z","end":"2026-07-24T21:33:38.577982Z","steps":["trace[1737359165] 'agreement among raft nodes before linearized reading' (duration: 123.777559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:38.789733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.487452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:33:38.789926Z","caller":"traceutil/trace.go:171","msg":"trace[838988674] transaction","detail":"{read_only:false; response_revision:4366; number_of_response:1; }","duration":"324.721268ms","start":"2026-07-24T21:33:38.465178Z","end":"2026-07-24T21:33:38.789899Z","steps":["trace[838988674] 'process raft request' (duration: 220.968731ms)","trace[838988674] 'compare' (duration: 103.364579ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:38.790022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:38.465157Z","time spent":"324.816609ms","remote":"127.0.0.1:40384","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-07-24T21:33:38.792443Z","caller":"traceutil/trace.go:171","msg":"trace[1362248831] linearizableReadLoop","detail":"{readStateIndex:4528; appliedIndex:4526; }","duration":"214.680641ms","start":"2026-07-24T21:33:38.577715Z","end":"2026-07-24T21:33:38.792395Z","steps":["trace[1362248831] 'read index received' (duration: 108.444797ms)","trace[1362248831] 'applied index is now lower than readState.Index' (duration: 106.229444ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:33:38.792709Z","caller":"traceutil/trace.go:171","msg":"trace[1777070140] transaction","detail":"{read_only:false; response_revision:4367; number_of_response:1; }","duration":"207.390671ms","start":"2026-07-24T21:33:38.585301Z","end":"2026-07-24T21:33:38.792692Z","steps":["trace[1777070140] 'process raft request' (duration: 207.012544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:38.792763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.321935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:33:38.792808Z","caller":"traceutil/trace.go:171","msg":"trace[658401115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4368; }","duration":"331.403886ms","start":"2026-07-24T21:33:38.461391Z","end":"2026-07-24T21:33:38.792795Z","steps":["trace[658401115] 'agreement among raft nodes before linearized reading' (duration: 331.184952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:38.79285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:38.461378Z","time spent":"331.463817ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T21:33:38.793027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.437115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:33:38.793062Z","caller":"traceutil/trace.go:171","msg":"trace[1934642455] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4368; }","duration":"332.496507ms","start":"2026-07-24T21:33:38.460557Z","end":"2026-07-24T21:33:38.793054Z","steps":["trace[1934642455] 'agreement among raft nodes before linearized reading' (duration: 332.404125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:38.79309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:38.460544Z","time spent":"332.537748ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6465,"request content":"key:\"/registry/pods/openstack/keepalived-9k2jv\" "} {"level":"warn","ts":"2026-07-24T21:33:38.793129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.750857ms","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-07-24T21:33:38.793172Z","caller":"traceutil/trace.go:171","msg":"trace[1320414722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4368; }","duration":"336.840999ms","start":"2026-07-24T21:33:38.45632Z","end":"2026-07-24T21:33:38.793161Z","steps":["trace[1320414722] 'agreement among raft nodes before linearized reading' (duration: 336.706287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:38.793207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:38.456298Z","time spent":"336.90133ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T21:33:38.793312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.246747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:33:38.793348Z","caller":"traceutil/trace.go:171","msg":"trace[2062828989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4368; }","duration":"209.316628ms","start":"2026-07-24T21:33:38.584022Z","end":"2026-07-24T21:33:38.793338Z","steps":["trace[2062828989] 'agreement among raft nodes before linearized reading' (duration: 209.258607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:38.793351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.646909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:33:38.793378Z","caller":"traceutil/trace.go:171","msg":"trace[962063541] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:4368; }","duration":"333.71147ms","start":"2026-07-24T21:33:38.459658Z","end":"2026-07-24T21:33:38.79337Z","steps":["trace[962063541] 'agreement among raft nodes before linearized reading' (duration: 333.666389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:38.79341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:33:38.459638Z","time spent":"333.766111ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" "} {"level":"info","ts":"2026-07-24T21:33:48.953265Z","caller":"traceutil/trace.go:171","msg":"trace[1001637654] linearizableReadLoop","detail":"{readStateIndex:4582; appliedIndex:4581; }","duration":"110.399624ms","start":"2026-07-24T21:33:48.842836Z","end":"2026-07-24T21:33:48.953236Z","steps":["trace[1001637654] 'read index received' (duration: 52.673569ms)","trace[1001637654] 'applied index is now lower than readState.Index' (duration: 57.724975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:48.953495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.204628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-24T21:33:48.953552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.467801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T21:33:48.953573Z","caller":"traceutil/trace.go:171","msg":"trace[1268400165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4418; }","duration":"112.32241ms","start":"2026-07-24T21:33:48.841232Z","end":"2026-07-24T21:33:48.953555Z","steps":["trace[1268400165] 'agreement among raft nodes before linearized reading' (duration: 112.136677ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:48.953595Z","caller":"traceutil/trace.go:171","msg":"trace[1685983159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4418; }","duration":"127.561523ms","start":"2026-07-24T21:33:48.826019Z","end":"2026-07-24T21:33:48.953581Z","steps":["trace[1685983159] 'agreement among raft nodes before linearized reading' (duration: 127.38324ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:48.95376Z","caller":"traceutil/trace.go:171","msg":"trace[292942103] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"131.350425ms","start":"2026-07-24T21:33:48.822397Z","end":"2026-07-24T21:33:48.953748Z","steps":["trace[292942103] 'process raft request' (duration: 73.14815ms)","trace[292942103] 'compare' (duration: 57.578603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:33:48.953819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.441965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:33:48.953903Z","caller":"traceutil/trace.go:171","msg":"trace[1575285583] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4418; }","duration":"110.559038ms","start":"2026-07-24T21:33:48.843322Z","end":"2026-07-24T21:33:48.953881Z","steps":["trace[1575285583] 'agreement among raft nodes before linearized reading' (duration: 110.324993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:48.953921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.04111ms","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-07-24T21:33:48.95406Z","caller":"traceutil/trace.go:171","msg":"trace[1601833679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4418; }","duration":"108.218922ms","start":"2026-07-24T21:33:48.845814Z","end":"2026-07-24T21:33:48.954033Z","steps":["trace[1601833679] 'agreement among raft nodes before linearized reading' (duration: 107.874536ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:57.303813Z","caller":"traceutil/trace.go:171","msg":"trace[841529643] transaction","detail":"{read_only:false; response_revision:4462; number_of_response:1; }","duration":"195.365672ms","start":"2026-07-24T21:33:57.108421Z","end":"2026-07-24T21:33:57.303787Z","steps":["trace[841529643] 'process raft request' (duration: 195.26784ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:57.30385Z","caller":"traceutil/trace.go:171","msg":"trace[123157621] transaction","detail":"{read_only:false; response_revision:4461; number_of_response:1; }","duration":"195.551995ms","start":"2026-07-24T21:33:57.108262Z","end":"2026-07-24T21:33:57.303814Z","steps":["trace[123157621] 'process raft request' (duration: 132.240421ms)","trace[123157621] 'compare' (duration: 62.924716ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:34:11.358153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.130304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:34:11.358242Z","caller":"traceutil/trace.go:171","msg":"trace[783840381] linearizableReadLoop","detail":"{readStateIndex:4700; appliedIndex:4699; }","duration":"222.986645ms","start":"2026-07-24T21:34:11.135242Z","end":"2026-07-24T21:34:11.358228Z","steps":["trace[783840381] 'read index received' (duration: 87.723579ms)","trace[783840381] 'applied index is now lower than readState.Index' (duration: 135.261926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:34:11.35841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.153328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:34:11.358443Z","caller":"traceutil/trace.go:171","msg":"trace[1579932780] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4531; }","duration":"223.21435ms","start":"2026-07-24T21:34:11.13522Z","end":"2026-07-24T21:34:11.358435Z","steps":["trace[1579932780] 'agreement among raft nodes before linearized reading' (duration: 223.093338ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:11.358657Z","caller":"traceutil/trace.go:171","msg":"trace[374746332] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"230.066664ms","start":"2026-07-24T21:34:11.12822Z","end":"2026-07-24T21:34:11.358287Z","steps":["trace[374746332] 'process raft request' (duration: 94.736207ms)","trace[374746332] 'compare' (duration: 135.025422ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:11.645669Z","caller":"traceutil/trace.go:171","msg":"trace[109841116] linearizableReadLoop","detail":"{readStateIndex:4701; appliedIndex:4700; }","duration":"287.375889ms","start":"2026-07-24T21:34:11.358259Z","end":"2026-07-24T21:34:11.645635Z","steps":["trace[109841116] 'read index received' (duration: 207.754737ms)","trace[109841116] 'applied index is now lower than readState.Index' (duration: 79.619072ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:11.645696Z","caller":"traceutil/trace.go:171","msg":"trace[59654362] transaction","detail":"{read_only:false; response_revision:4532; number_of_response:1; }","duration":"506.723648ms","start":"2026-07-24T21:34:11.13895Z","end":"2026-07-24T21:34:11.645674Z","steps":["trace[59654362] 'process raft request' (duration: 427.097037ms)","trace[59654362] 'compare' (duration: 79.411178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:34:11.645911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.914634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:34:11.645993Z","caller":"traceutil/trace.go:171","msg":"trace[734448630] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4532; }","duration":"483.038357ms","start":"2026-07-24T21:34:11.162935Z","end":"2026-07-24T21:34:11.645973Z","steps":["trace[734448630] 'agreement among raft nodes before linearized reading' (duration: 482.901654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:11.646037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.312548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-24T21:34:11.646048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:11.162923Z","time spent":"483.113568ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-24T21:34:11.646077Z","caller":"traceutil/trace.go:171","msg":"trace[1425779224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4532; }","duration":"287.349618ms","start":"2026-07-24T21:34:11.358716Z","end":"2026-07-24T21:34:11.646066Z","steps":["trace[1425779224] 'agreement among raft nodes before linearized reading' (duration: 287.261747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:11.646096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.839868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-24T21:34:11.645938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.34286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-07-24T21:34:11.646173Z","caller":"traceutil/trace.go:171","msg":"trace[1032083162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4532; }","duration":"406.950079ms","start":"2026-07-24T21:34:11.239206Z","end":"2026-07-24T21:34:11.646156Z","steps":["trace[1032083162] 'agreement among raft nodes before linearized reading' (duration: 406.755856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:11.646216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:11.239191Z","time spent":"407.016611ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T21:34:11.646212Z","caller":"traceutil/trace.go:171","msg":"trace[386963431] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4532; }","duration":"400.639725ms","start":"2026-07-24T21:34:11.24555Z","end":"2026-07-24T21:34:11.64619Z","steps":["trace[386963431] 'agreement among raft nodes before linearized reading' (duration: 400.240087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:11.646278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:11.245541Z","time spent":"400.713066ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6465,"request content":"key:\"/registry/pods/openstack/keepalived-9k2jv\" "} {"level":"warn","ts":"2026-07-24T21:34:11.646312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.425332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T21:34:11.646344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.167757ms","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-07-24T21:34:11.646398Z","caller":"traceutil/trace.go:171","msg":"trace[490305301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4532; }","duration":"286.244578ms","start":"2026-07-24T21:34:11.36014Z","end":"2026-07-24T21:34:11.646385Z","steps":["trace[490305301] 'agreement among raft nodes before linearized reading' (duration: 286.107106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:11.645826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:11.138927Z","time spent":"506.83186ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:34:11.6464Z","caller":"traceutil/trace.go:171","msg":"trace[885105467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4532; }","duration":"167.553604ms","start":"2026-07-24T21:34:11.478824Z","end":"2026-07-24T21:34:11.646378Z","steps":["trace[885105467] 'agreement among raft nodes before linearized reading' (duration: 167.30708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:11.646535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.725119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:34:11.646651Z","caller":"traceutil/trace.go:171","msg":"trace[1051625300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4532; }","duration":"161.895602ms","start":"2026-07-24T21:34:11.48473Z","end":"2026-07-24T21:34:11.646625Z","steps":["trace[1051625300] 'agreement among raft nodes before linearized reading' (duration: 161.487374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:40.352438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.62391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:34:40.352532Z","caller":"traceutil/trace.go:171","msg":"trace[1856727301] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:4681; }","duration":"244.761043ms","start":"2026-07-24T21:34:40.107748Z","end":"2026-07-24T21:34:40.352509Z","steps":["trace[1856727301] 'count revisions from in-memory index tree' (duration: 244.508399ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:47.022315Z","caller":"traceutil/trace.go:171","msg":"trace[1182684700] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"176.915056ms","start":"2026-07-24T21:34:46.845377Z","end":"2026-07-24T21:34:47.022293Z","steps":["trace[1182684700] 'process raft request' (duration: 176.770493ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:47.050291Z","caller":"traceutil/trace.go:171","msg":"trace[883343312] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"130.461229ms","start":"2026-07-24T21:34:46.919801Z","end":"2026-07-24T21:34:47.050262Z","steps":["trace[883343312] 'process raft request' (duration: 130.310896ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:48.204206Z","caller":"traceutil/trace.go:171","msg":"trace[1353058464] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"166.735919ms","start":"2026-07-24T21:34:48.037445Z","end":"2026-07-24T21:34:48.204181Z","steps":["trace[1353058464] 'process raft request' (duration: 166.552816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:48.550617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.747223ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T21:34:49.367855Z","caller":"traceutil/trace.go:171","msg":"trace[113989959] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"102.971568ms","start":"2026-07-24T21:34:49.264846Z","end":"2026-07-24T21:34:49.367818Z","steps":["trace[113989959] 'process raft request' (duration: 102.614141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:50.250496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.829543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:34:50.250589Z","caller":"traceutil/trace.go:171","msg":"trace[1546673130] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:4732; }","duration":"131.990677ms","start":"2026-07-24T21:34:50.118576Z","end":"2026-07-24T21:34:50.250567Z","steps":["trace[1546673130] 'count revisions from in-memory index tree' (duration: 131.738002ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:54.103465Z","caller":"traceutil/trace.go:171","msg":"trace[127925692] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"109.645059ms","start":"2026-07-24T21:34:53.993792Z","end":"2026-07-24T21:34:54.103437Z","steps":["trace[127925692] 'process raft request' (duration: 91.633231ms)","trace[127925692] 'compare' (duration: 17.711673ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:29.900858Z","caller":"traceutil/trace.go:171","msg":"trace[1055760329] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"209.310795ms","start":"2026-07-24T21:35:29.691529Z","end":"2026-07-24T21:35:29.90084Z","steps":["trace[1055760329] 'process raft request' (duration: 194.287621ms)","trace[1055760329] 'compare' (duration: 14.513615ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:29.9008Z","caller":"traceutil/trace.go:171","msg":"trace[714921061] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5163; }","duration":"106.531196ms","start":"2026-07-24T21:35:29.794245Z","end":"2026-07-24T21:35:29.900776Z","steps":["trace[714921061] 'read index received' (duration: 91.523962ms)","trace[714921061] 'applied index is now lower than readState.Index' (duration: 15.006204ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:29.901341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.085727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:35:29.901398Z","caller":"traceutil/trace.go:171","msg":"trace[1523234280] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4978; }","duration":"107.193568ms","start":"2026-07-24T21:35:29.794192Z","end":"2026-07-24T21:35:29.901386Z","steps":["trace[1523234280] 'agreement among raft nodes before linearized reading' (duration: 106.921383ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:30.067785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3416} {"level":"warn","ts":"2026-07-24T21:35:30.385792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.87876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:35:30.385882Z","caller":"traceutil/trace.go:171","msg":"trace[1243501940] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4979; }","duration":"294.027082ms","start":"2026-07-24T21:35:30.091834Z","end":"2026-07-24T21:35:30.385861Z","steps":["trace[1243501940] 'count revisions from in-memory index tree' (duration: 293.802308ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:30.464052Z","caller":"traceutil/trace.go:171","msg":"trace[2044336259] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"315.965913ms","start":"2026-07-24T21:35:30.148039Z","end":"2026-07-24T21:35:30.464005Z","steps":["trace[2044336259] 'process raft request' (duration: 299.623174ms)","trace[2044336259] 'compare' (duration: 16.169456ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:30.463974Z","caller":"traceutil/trace.go:171","msg":"trace[81613124] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5166; }","duration":"268.558017ms","start":"2026-07-24T21:35:30.195391Z","end":"2026-07-24T21:35:30.463949Z","steps":["trace[81613124] 'read index received' (duration: 252.262919ms)","trace[81613124] 'applied index is now lower than readState.Index' (duration: 16.294058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:30.464209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:35:30.148014Z","time spent":"316.108566ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:35:30.464362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.987015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9k2jv\" ","response":"range_response_count:1 size:6441"} {"level":"warn","ts":"2026-07-24T21:35:30.464411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.95875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:35:30.464455Z","caller":"traceutil/trace.go:171","msg":"trace[1340748386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4980; }","duration":"138.022022ms","start":"2026-07-24T21:35:30.326421Z","end":"2026-07-24T21:35:30.464443Z","steps":["trace[1340748386] 'agreement among raft nodes before linearized reading' (duration: 137.885519ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:30.464457Z","caller":"traceutil/trace.go:171","msg":"trace[611659659] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9k2jv; range_end:; response_count:1; response_revision:4980; }","duration":"269.156038ms","start":"2026-07-24T21:35:30.195282Z","end":"2026-07-24T21:35:30.464438Z","steps":["trace[611659659] 'agreement among raft nodes before linearized reading' (duration: 268.832652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:30.464414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.992568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-24T21:35:30.464488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.075097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:35:30.464554Z","caller":"traceutil/trace.go:171","msg":"trace[445554760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4980; }","duration":"182.170799ms","start":"2026-07-24T21:35:30.282367Z","end":"2026-07-24T21:35:30.464538Z","steps":["trace[445554760] 'agreement among raft nodes before linearized reading' (duration: 182.017606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:30.464565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.144829ms","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-07-24T21:35:30.464668Z","caller":"traceutil/trace.go:171","msg":"trace[1025640358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4980; }","duration":"129.280112ms","start":"2026-07-24T21:35:30.335367Z","end":"2026-07-24T21:35:30.464647Z","steps":["trace[1025640358] 'agreement among raft nodes before linearized reading' (duration: 128.996076ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:30.46457Z","caller":"traceutil/trace.go:171","msg":"trace[1662036503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4980; }","duration":"157.171472ms","start":"2026-07-24T21:35:30.307368Z","end":"2026-07-24T21:35:30.464539Z","steps":["trace[1662036503] 'agreement among raft nodes before linearized reading' (duration: 156.984228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:30.464713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.131061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T21:35:30.464772Z","caller":"traceutil/trace.go:171","msg":"trace[277601195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4980; }","duration":"186.207062ms","start":"2026-07-24T21:35:30.278552Z","end":"2026-07-24T21:35:30.46476Z","steps":["trace[277601195] 'agreement among raft nodes before linearized reading' (duration: 185.738723ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:30.531778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3416,"took":"462.704394ms","hash":1553523908,"current-db-size-bytes":39133184,"current-db-size":"39 MB","current-db-size-in-use-bytes":10715136,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-24T21:35:30.531861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1553523908,"revision":3416,"compact-revision":-1} {"level":"info","ts":"2026-07-24T21:36:00.113167Z","caller":"traceutil/trace.go:171","msg":"trace[852006201] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"105.431435ms","start":"2026-07-24T21:36:00.007706Z","end":"2026-07-24T21:36:00.113138Z","steps":["trace[852006201] 'process raft request' (duration: 105.296552ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:30.073216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4979} {"level":"info","ts":"2026-07-24T21:40:30.111004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4979,"took":"36.401486ms","hash":2013493150,"current-db-size-bytes":39133184,"current-db-size":"39 MB","current-db-size-in-use-bytes":18599936,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-24T21:40:30.111082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2013493150,"revision":4979,"compact-revision":3416} {"level":"info","ts":"2026-07-24T21:42:29.499636Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002594.wal"} {"level":"info","ts":"2026-07-24T21:42:50.728906Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T21:42:50.739251Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-24T21:42:50.739356Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-24T21:45:30.08339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7359} {"level":"info","ts":"2026-07-24T21:45:30.158235Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7359,"took":"72.623283ms","hash":3966540345,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":47284224,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-07-24T21:45:30.158288Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3966540345,"revision":7359,"compact-revision":4979} {"level":"warn","ts":"2026-07-24T21:48:36.737319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.773782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:48:36.7374Z","caller":"traceutil/trace.go:171","msg":"trace[261022211] linearizableReadLoop","detail":"{readStateIndex:14539; appliedIndex:14538; }","duration":"175.898808ms","start":"2026-07-24T21:48:36.56149Z","end":"2026-07-24T21:48:36.737388Z","steps":["trace[261022211] 'read index received' (duration: 57.866851ms)","trace[261022211] 'applied index is now lower than readState.Index' (duration: 118.031117ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:48:36.737559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.301448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:48:36.737585Z","caller":"traceutil/trace.go:171","msg":"trace[1337811389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14097; }","duration":"153.835397ms","start":"2026-07-24T21:48:36.583743Z","end":"2026-07-24T21:48:36.737579Z","steps":["trace[1337811389] 'agreement among raft nodes before linearized reading' (duration: 153.792767ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:48:36.737555Z","caller":"traceutil/trace.go:171","msg":"trace[696708460] transaction","detail":"{read_only:false; response_revision:14097; number_of_response:1; }","duration":"196.995931ms","start":"2026-07-24T21:48:36.540535Z","end":"2026-07-24T21:48:36.737531Z","steps":["trace[696708460] 'process raft request' (duration: 78.969474ms)","trace[696708460] 'compare' (duration: 117.612799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:48:36.737676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.152023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-24T21:48:36.737764Z","caller":"traceutil/trace.go:171","msg":"trace[1123001801] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14097; }","duration":"176.291056ms","start":"2026-07-24T21:48:36.561454Z","end":"2026-07-24T21:48:36.737745Z","steps":["trace[1123001801] 'agreement among raft nodes before linearized reading' (duration: 176.01938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:48:36.738001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.892817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:10355"} {"level":"info","ts":"2026-07-24T21:48:36.738063Z","caller":"traceutil/trace.go:171","msg":"trace[842868304] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:14097; }","duration":"114.984379ms","start":"2026-07-24T21:48:36.623064Z","end":"2026-07-24T21:48:36.738049Z","steps":["trace[842868304] 'agreement among raft nodes before linearized reading' (duration: 114.475489ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:50:30.089513Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11854} {"level":"info","ts":"2026-07-24T21:50:30.240759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11854,"took":"147.980049ms","hash":2455814037,"current-db-size-bytes":77221888,"current-db-size":"77 MB","current-db-size-in-use-bytes":49795072,"current-db-size-in-use":"50 MB"} {"level":"info","ts":"2026-07-24T21:50:30.240894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2455814037,"revision":11854,"compact-revision":7359} {"level":"info","ts":"2026-07-24T21:53:15.827998Z","caller":"traceutil/trace.go:171","msg":"trace[731036962] linearizableReadLoop","detail":"{readStateIndex:19039; appliedIndex:19038; }","duration":"135.201292ms","start":"2026-07-24T21:53:15.692756Z","end":"2026-07-24T21:53:15.827957Z","steps":["trace[731036962] 'read index received' (duration: 99.170904ms)","trace[731036962] 'applied index is now lower than readState.Index' (duration: 36.028358ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:53:15.828396Z","caller":"traceutil/trace.go:171","msg":"trace[322000099] transaction","detail":"{read_only:false; response_revision:18497; number_of_response:1; }","duration":"158.69673ms","start":"2026-07-24T21:53:15.669621Z","end":"2026-07-24T21:53:15.828317Z","steps":["trace[322000099] 'process raft request' (duration: 122.356416ms)","trace[322000099] 'compare' (duration: 35.541049ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:53:15.828521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.742832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-24T21:53:15.829551Z","caller":"traceutil/trace.go:171","msg":"trace[87849680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18497; }","duration":"136.822163ms","start":"2026-07-24T21:53:15.69271Z","end":"2026-07-24T21:53:15.829532Z","steps":["trace[87849680] 'agreement among raft nodes before linearized reading' (duration: 135.396275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:53:16.092447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.941496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T21:53:16.092564Z","caller":"traceutil/trace.go:171","msg":"trace[685386898] linearizableReadLoop","detail":"{readStateIndex:19042; appliedIndex:19041; }","duration":"162.189317ms","start":"2026-07-24T21:53:15.930357Z","end":"2026-07-24T21:53:16.092546Z","steps":["trace[685386898] 'read index received' (duration: 37.042827ms)","trace[685386898] 'applied index is now lower than readState.Index' (duration: 125.14512ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:53:16.09265Z","caller":"traceutil/trace.go:171","msg":"trace[407458000] transaction","detail":"{read_only:false; response_revision:18500; number_of_response:1; }","duration":"163.39158ms","start":"2026-07-24T21:53:15.929246Z","end":"2026-07-24T21:53:16.092638Z","steps":["trace[407458000] 'process raft request' (duration: 38.140768ms)","trace[407458000] 'compare' (duration: 124.814583ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:53:16.093089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.714246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/secretgen-controller\" ","response":"range_response_count:1 size:385"} {"level":"info","ts":"2026-07-24T21:53:16.093169Z","caller":"traceutil/trace.go:171","msg":"trace[118458877] range","detail":"{range_begin:/registry/namespaces/secretgen-controller; range_end:; response_count:1; response_revision:18500; }","duration":"162.821999ms","start":"2026-07-24T21:53:15.930327Z","end":"2026-07-24T21:53:16.093149Z","steps":["trace[118458877] 'agreement among raft nodes before linearized reading' (duration: 162.671146ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:53:16.247904Z","caller":"traceutil/trace.go:171","msg":"trace[808245451] transaction","detail":"{read_only:false; response_revision:18502; number_of_response:1; }","duration":"115.959104ms","start":"2026-07-24T21:53:16.13191Z","end":"2026-07-24T21:53:16.24787Z","steps":["trace[808245451] 'process raft request' (duration: 92.67126ms)","trace[808245451] 'compare' (duration: 22.854656ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:53:16.397165Z","caller":"traceutil/trace.go:171","msg":"trace[1636404335] linearizableReadLoop","detail":"{readStateIndex:19045; appliedIndex:19044; }","duration":"128.80913ms","start":"2026-07-24T21:53:16.268325Z","end":"2026-07-24T21:53:16.397134Z","steps":["trace[1636404335] 'read index received' (duration: 128.688928ms)","trace[1636404335] 'applied index is now lower than readState.Index' (duration: 118.912µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:53:16.397267Z","caller":"traceutil/trace.go:171","msg":"trace[136629847] transaction","detail":"{read_only:false; response_revision:18503; number_of_response:1; }","duration":"130.313128ms","start":"2026-07-24T21:53:16.266926Z","end":"2026-07-24T21:53:16.397239Z","steps":["trace[136629847] 'process raft request' (duration: 130.030543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:53:16.397406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.060425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secretimports.secretgen.carvel.dev\" ","response":"range_response_count:1 size:3790"} {"level":"info","ts":"2026-07-24T21:53:16.39745Z","caller":"traceutil/trace.go:171","msg":"trace[2077764884] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secretimports.secretgen.carvel.dev; range_end:; response_count:1; response_revision:18503; }","duration":"129.159687ms","start":"2026-07-24T21:53:16.268274Z","end":"2026-07-24T21:53:16.397434Z","steps":["trace[2077764884] 'agreement among raft nodes before linearized reading' (duration: 128.987294ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:53:16.600789Z","caller":"traceutil/trace.go:171","msg":"trace[536757439] linearizableReadLoop","detail":"{readStateIndex:19046; appliedIndex:19045; }","duration":"197.40964ms","start":"2026-07-24T21:53:16.403353Z","end":"2026-07-24T21:53:16.600763Z","steps":["trace[536757439] 'read index received' (duration: 121.759875ms)","trace[536757439] 'applied index is now lower than readState.Index' (duration: 75.648535ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:53:16.600919Z","caller":"traceutil/trace.go:171","msg":"trace[2062309973] transaction","detail":"{read_only:false; response_revision:18504; number_of_response:1; }","duration":"306.827249ms","start":"2026-07-24T21:53:16.294066Z","end":"2026-07-24T21:53:16.600893Z","steps":["trace[2062309973] 'process raft request' (duration: 231.014042ms)","trace[2062309973] 'compare' (duration: 75.572993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:53:16.601062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:53:16.294046Z","time spent":"306.922711ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:53:16.601151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.799838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secrettemplates.secretgen.carvel.dev\" ","response":"range_response_count:1 size:7257"} {"level":"info","ts":"2026-07-24T21:53:16.601227Z","caller":"traceutil/trace.go:171","msg":"trace[571114255] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secrettemplates.secretgen.carvel.dev; range_end:; response_count:1; response_revision:18504; }","duration":"197.918379ms","start":"2026-07-24T21:53:16.403294Z","end":"2026-07-24T21:53:16.601212Z","steps":["trace[571114255] 'agreement among raft nodes before linearized reading' (duration: 197.710345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:53:16.601399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.432588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T21:53:16.601496Z","caller":"traceutil/trace.go:171","msg":"trace[457976573] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:18504; }","duration":"167.58465ms","start":"2026-07-24T21:53:16.433891Z","end":"2026-07-24T21:53:16.601475Z","steps":["trace[457976573] 'agreement among raft nodes before linearized reading' (duration: 167.405517ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:53:57.180854Z","caller":"traceutil/trace.go:171","msg":"trace[100960238] transaction","detail":"{read_only:false; response_revision:18981; number_of_response:1; }","duration":"102.909885ms","start":"2026-07-24T21:53:57.07791Z","end":"2026-07-24T21:53:57.18082Z","steps":["trace[100960238] 'process raft request' (duration: 102.209672ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:06.578734Z","caller":"traceutil/trace.go:171","msg":"trace[1811621818] linearizableReadLoop","detail":"{readStateIndex:19630; appliedIndex:19629; }","duration":"145.727784ms","start":"2026-07-24T21:54:06.432974Z","end":"2026-07-24T21:54:06.578702Z","steps":["trace[1811621818] 'read index received' (duration: 89.920028ms)","trace[1811621818] 'applied index is now lower than readState.Index' (duration: 55.806686ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:54:06.57878Z","caller":"traceutil/trace.go:171","msg":"trace[255056434] transaction","detail":"{read_only:false; response_revision:19075; number_of_response:1; }","duration":"164.789267ms","start":"2026-07-24T21:54:06.413945Z","end":"2026-07-24T21:54:06.578734Z","steps":["trace[255056434] 'process raft request' (duration: 108.94105ms)","trace[255056434] 'compare' (duration: 55.680713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:54:06.578927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.913917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T21:54:06.57897Z","caller":"traceutil/trace.go:171","msg":"trace[1256468071] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:19075; }","duration":"146.017719ms","start":"2026-07-24T21:54:06.432937Z","end":"2026-07-24T21:54:06.578955Z","steps":["trace[1256468071] 'agreement among raft nodes before linearized reading' (duration: 145.913577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:54:06.589716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.061459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T21:54:06.589758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.423436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T21:54:06.589788Z","caller":"traceutil/trace.go:171","msg":"trace[1926940072] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:19076; }","duration":"156.13268ms","start":"2026-07-24T21:54:06.43363Z","end":"2026-07-24T21:54:06.589763Z","steps":["trace[1926940072] 'agreement among raft nodes before linearized reading' (duration: 156.044618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:54:06.589785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.924776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-24T21:54:06.589779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.226835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-07-24T21:54:06.589799Z","caller":"traceutil/trace.go:171","msg":"trace[193344429] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:19076; }","duration":"156.494287ms","start":"2026-07-24T21:54:06.43329Z","end":"2026-07-24T21:54:06.589785Z","steps":["trace[193344429] 'agreement among raft nodes before linearized reading' (duration: 156.327714ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:06.589834Z","caller":"traceutil/trace.go:171","msg":"trace[1038283936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19076; }","duration":"155.986218ms","start":"2026-07-24T21:54:06.433835Z","end":"2026-07-24T21:54:06.589822Z","steps":["trace[1038283936] 'agreement among raft nodes before linearized reading' (duration: 155.814115ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:06.589796Z","caller":"traceutil/trace.go:171","msg":"trace[1814715237] transaction","detail":"{read_only:false; response_revision:19076; number_of_response:1; }","duration":"155.706793ms","start":"2026-07-24T21:54:06.434039Z","end":"2026-07-24T21:54:06.589746Z","steps":["trace[1814715237] 'process raft request' (duration: 155.272395ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:06.589849Z","caller":"traceutil/trace.go:171","msg":"trace[2004090731] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:19076; }","duration":"154.318306ms","start":"2026-07-24T21:54:06.435514Z","end":"2026-07-24T21:54:06.589833Z","steps":["trace[2004090731] 'agreement among raft nodes before linearized reading' (duration: 154.115783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:54:06.589697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.1186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T21:54:06.58993Z","caller":"traceutil/trace.go:171","msg":"trace[1901228310] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:19076; }","duration":"156.388256ms","start":"2026-07-24T21:54:06.433531Z","end":"2026-07-24T21:54:06.589919Z","steps":["trace[1901228310] 'agreement among raft nodes before linearized reading' (duration: 156.064309ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:11.836616Z","caller":"traceutil/trace.go:171","msg":"trace[252355879] linearizableReadLoop","detail":"{readStateIndex:19668; appliedIndex:19667; }","duration":"159.801442ms","start":"2026-07-24T21:54:11.676788Z","end":"2026-07-24T21:54:11.83659Z","steps":["trace[252355879] 'read index received' (duration: 159.648219ms)","trace[252355879] 'applied index is now lower than readState.Index' (duration: 152.113µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:54:11.836691Z","caller":"traceutil/trace.go:171","msg":"trace[1580444126] transaction","detail":"{read_only:false; response_revision:19112; number_of_response:1; }","duration":"177.323236ms","start":"2026-07-24T21:54:11.659342Z","end":"2026-07-24T21:54:11.836665Z","steps":["trace[1580444126] 'process raft request' (duration: 177.097892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:54:11.83687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.067377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-07-24T21:54:11.836967Z","caller":"traceutil/trace.go:171","msg":"trace[785513178] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:19112; }","duration":"160.234481ms","start":"2026-07-24T21:54:11.676711Z","end":"2026-07-24T21:54:11.836945Z","steps":["trace[785513178] 'agreement among raft nodes before linearized reading' (duration: 159.972365ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:12.032093Z","caller":"traceutil/trace.go:171","msg":"trace[1836066464] transaction","detail":"{read_only:false; response_revision:19113; number_of_response:1; }","duration":"131.537471ms","start":"2026-07-24T21:54:11.900514Z","end":"2026-07-24T21:54:12.032051Z","steps":["trace[1836066464] 'process raft request' (duration: 109.439139ms)","trace[1836066464] 'compare' (duration: 21.864098ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:54:12.31998Z","caller":"traceutil/trace.go:171","msg":"trace[1323588331] transaction","detail":"{read_only:false; response_revision:19116; number_of_response:1; }","duration":"132.791066ms","start":"2026-07-24T21:54:12.187145Z","end":"2026-07-24T21:54:12.319936Z","steps":["trace[1323588331] 'process raft request' (duration: 95.311931ms)","trace[1323588331] 'compare' (duration: 37.281211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:54:12.603037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.544855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T21:54:12.603173Z","caller":"traceutil/trace.go:171","msg":"trace[2063279433] transaction","detail":"{read_only:false; response_revision:19117; number_of_response:1; }","duration":"279.892094ms","start":"2026-07-24T21:54:12.32325Z","end":"2026-07-24T21:54:12.603142Z","steps":["trace[2063279433] 'process raft request' (duration: 151.154766ms)","trace[2063279433] 'compare' (duration: 128.29133ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:54:12.726749Z","caller":"traceutil/trace.go:171","msg":"trace[804273573] transaction","detail":"{read_only:false; response_revision:19118; number_of_response:1; }","duration":"118.813399ms","start":"2026-07-24T21:54:12.607883Z","end":"2026-07-24T21:54:12.726696Z","steps":["trace[804273573] 'process raft request' (duration: 117.861861ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:12.72728Z","caller":"traceutil/trace.go:171","msg":"trace[1093507919] transaction","detail":"{read_only:false; response_revision:19119; number_of_response:1; }","duration":"117.998433ms","start":"2026-07-24T21:54:12.609239Z","end":"2026-07-24T21:54:12.727238Z","steps":["trace[1093507919] 'process raft request' (duration: 117.583855ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:54:47.036167Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T21:54:47.042506Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-24T21:54:47.042614Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-24T21:55:30.096303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16316} {"level":"info","ts":"2026-07-24T21:55:30.246027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16316,"took":"145.616423ms","hash":4021872690,"current-db-size-bytes":77221888,"current-db-size":"77 MB","current-db-size-in-use-bytes":43204608,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-24T21:55:30.246104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4021872690,"revision":16316,"compact-revision":11854} {"level":"info","ts":"2026-07-24T22:00:30.101858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20063} {"level":"info","ts":"2026-07-24T22:00:30.253126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20063,"took":"147.122295ms","hash":2036793454,"current-db-size-bytes":77221888,"current-db-size":"77 MB","current-db-size-in-use-bytes":35078144,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-24T22:00:30.253183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2036793454,"revision":20063,"compact-revision":16316} {"level":"info","ts":"2026-07-24T22:05:30.108407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22685} {"level":"info","ts":"2026-07-24T22:05:30.250991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22685,"took":"139.176042ms","hash":3693212000,"current-db-size-bytes":77221888,"current-db-size":"77 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T22:05:30.251064Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3693212000,"revision":22685,"compact-revision":20063} {"level":"info","ts":"2026-07-24T22:10:30.113898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24944} {"level":"info","ts":"2026-07-24T22:10:30.232672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24944,"took":"115.173118ms","hash":3357515328,"current-db-size-bytes":77221888,"current-db-size":"77 MB","current-db-size-in-use-bytes":32751616,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T22:10:30.232735Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3357515328,"revision":24944,"compact-revision":22685} {"level":"info","ts":"2026-07-24T22:12:23.879744Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000070d3.wal"} {"level":"info","ts":"2026-07-24T22:14:53.130317Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T22:14:53.134654Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-24T22:14:53.134737Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-24T22:15:30.119783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27232} {"level":"info","ts":"2026-07-24T22:15:30.246398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27232,"took":"123.195228ms","hash":343085138,"current-db-size-bytes":77221888,"current-db-size":"77 MB","current-db-size-in-use-bytes":33120256,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T22:15:30.246467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":343085138,"revision":27232,"compact-revision":24944} {"level":"info","ts":"2026-07-24T22:20:30.126952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29476} {"level":"info","ts":"2026-07-24T22:20:30.254942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29476,"took":"123.270547ms","hash":2277559135,"current-db-size-bytes":77221888,"current-db-size":"77 MB","current-db-size-in-use-bytes":32415744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T22:20:30.254992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2277559135,"revision":29476,"compact-revision":27232}