{"level":"warn","ts":"2026-05-11T21:05:48.211302Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T21:05:48.211429Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-05-11T21:05:48.211498Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T21:05:48.211508Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-11T21:05:48.211527Z","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-05-11T21:05:48.212135Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-05-11T21:05:48.212214Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-05-11T21:05:48.219631Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.181482ms"} {"level":"info","ts":"2026-05-11T21:05:48.226254Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-05-11T21:05:48.226339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T21:05:48.226363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-05-11T21:05:48.226382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T21:05:48.226396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-05-11T21:05:48.226437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-05-11T21:05:48.234489Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T21:05:48.23629Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T21:05:48.237767Z","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-05-11T21:05:48.241011Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T21:05:48.241288Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T21:05:48.241435Z","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-05-11T21:05:48.241823Z","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-05-11T21:05:48.241848Z","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-05-11T21:05:48.242349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-05-11T21:05:48.243965Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-11T21:05:48.247496Z","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-05-11T21:05:48.247637Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-11T21:05:48.247706Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-11T21:05:48.247839Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T21:05:48.247877Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T21:05:48.927613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T21:05:48.927712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T21:05:48.927757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-05-11T21:05:48.92778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-05-11T21:05:48.927793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-11T21:05:48.927811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-05-11T21:05:48.927827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-11T21:05:48.92894Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T21:05:48.929155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T21:05:48.929216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T21:05:48.929466Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:48.9296Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T21:05:48.929674Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T21:05:48.930546Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:48.930675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:48.930717Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:48.932836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-05-11T21:05:48.933075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T21:06:24.6342Z","caller":"traceutil/trace.go:171","msg":"trace[1656404756] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"180.410843ms","start":"2026-05-11T21:06:24.453743Z","end":"2026-05-11T21:06:24.634154Z","steps":["trace[1656404756] 'process raft request' (duration: 179.485534ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:24.908303Z","caller":"traceutil/trace.go:171","msg":"trace[1657447033] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"168.304417ms","start":"2026-05-11T21:06:24.739966Z","end":"2026-05-11T21:06:24.908271Z","steps":["trace[1657447033] 'read index received' (duration: 168.201785ms)","trace[1657447033] 'applied index is now lower than readState.Index' (duration: 101.192µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:24.908368Z","caller":"traceutil/trace.go:171","msg":"trace[1959324706] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"216.389053ms","start":"2026-05-11T21:06:24.691943Z","end":"2026-05-11T21:06:24.908332Z","steps":["trace[1959324706] 'process raft request' (duration: 216.151208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:24.908695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.443291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4jxnr\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-11T21:06:24.908695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.640839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T21:06:24.90869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.687346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-11T21:06:24.908793Z","caller":"traceutil/trace.go:171","msg":"trace[596268048] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4jxnr; range_end:; response_count:1; response_revision:579; }","duration":"160.606314ms","start":"2026-05-11T21:06:24.748169Z","end":"2026-05-11T21:06:24.908775Z","steps":["trace[596268048] 'agreement among raft nodes before linearized reading' (duration: 160.328139ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:24.908822Z","caller":"traceutil/trace.go:171","msg":"trace[1078359417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"163.824073ms","start":"2026-05-11T21:06:24.744976Z","end":"2026-05-11T21:06:24.9088Z","steps":["trace[1078359417] 'agreement among raft nodes before linearized reading' (duration: 163.508866ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:24.908845Z","caller":"traceutil/trace.go:171","msg":"trace[1057428932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"168.9135ms","start":"2026-05-11T21:06:24.739903Z","end":"2026-05-11T21:06:24.908817Z","steps":["trace[1057428932] 'agreement among raft nodes before linearized reading' (duration: 168.496762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:25.162549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.847756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T21:06:25.162782Z","caller":"traceutil/trace.go:171","msg":"trace[322221539] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"248.912492ms","start":"2026-05-11T21:06:24.913834Z","end":"2026-05-11T21:06:25.162747Z","steps":["trace[322221539] 'process raft request' (duration: 117.29014ms)","trace[322221539] 'compare' (duration: 130.702513ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:25.162792Z","caller":"traceutil/trace.go:171","msg":"trace[1845843624] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"243.186111ms","start":"2026-05-11T21:06:24.919589Z","end":"2026-05-11T21:06:25.162775Z","steps":["trace[1845843624] 'read index received' (duration: 111.547738ms)","trace[1845843624] 'applied index is now lower than readState.Index' (duration: 131.637293ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:25.162941Z","caller":"traceutil/trace.go:171","msg":"trace[711909080] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"239.229557ms","start":"2026-05-11T21:06:24.923687Z","end":"2026-05-11T21:06:25.162916Z","steps":["trace[711909080] 'process raft request' (duration: 239.030593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:25.163055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.428656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T21:06:25.163126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.142822ms","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-05-11T21:06:25.163202Z","caller":"traceutil/trace.go:171","msg":"trace[135747698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"228.245535ms","start":"2026-05-11T21:06:24.934945Z","end":"2026-05-11T21:06:25.16319Z","steps":["trace[135747698] 'agreement among raft nodes before linearized reading' (duration: 228.03363ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:25.163266Z","caller":"traceutil/trace.go:171","msg":"trace[2013250315] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:582; }","duration":"243.66492ms","start":"2026-05-11T21:06:24.919559Z","end":"2026-05-11T21:06:25.163224Z","steps":["trace[2013250315] 'agreement among raft nodes before linearized reading' (duration: 243.410135ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:25.163Z","caller":"traceutil/trace.go:171","msg":"trace[1427572985] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"248.894592ms","start":"2026-05-11T21:06:24.914014Z","end":"2026-05-11T21:06:25.162908Z","steps":["trace[1427572985] 'process raft request' (duration: 248.642967ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:41.346991Z","caller":"traceutil/trace.go:171","msg":"trace[598891813] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"104.133553ms","start":"2026-05-11T21:06:41.24283Z","end":"2026-05-11T21:06:41.346964Z","steps":["trace[598891813] 'process raft request' (duration: 104.010161ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:41.391518Z","caller":"traceutil/trace.go:171","msg":"trace[719854277] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:772; }","duration":"136.132446ms","start":"2026-05-11T21:06:41.255354Z","end":"2026-05-11T21:06:41.391487Z","steps":["trace[719854277] 'read index received' (duration: 91.482523ms)","trace[719854277] 'applied index is now lower than readState.Index' (duration: 44.648643ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:41.391621Z","caller":"traceutil/trace.go:171","msg":"trace[1618999998] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"146.028638ms","start":"2026-05-11T21:06:41.245547Z","end":"2026-05-11T21:06:41.391575Z","steps":["trace[1618999998] 'process raft request' (duration: 145.815584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:41.391735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.362631ms","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-05-11T21:06:41.391784Z","caller":"traceutil/trace.go:171","msg":"trace[2036312644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"136.472983ms","start":"2026-05-11T21:06:41.255298Z","end":"2026-05-11T21:06:41.391771Z","steps":["trace[2036312644] 'agreement among raft nodes before linearized reading' (duration: 136.30127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:17.214851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.707663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-05-11T21:07:17.214967Z","caller":"traceutil/trace.go:171","msg":"trace[1995961203] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1173; }","duration":"183.914018ms","start":"2026-05-11T21:07:17.031026Z","end":"2026-05-11T21:07:17.21494Z","steps":["trace[1995961203] 'range keys from in-memory index tree' (duration: 183.435608ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:32.233903Z","caller":"traceutil/trace.go:171","msg":"trace[1246919169] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"105.96661ms","start":"2026-05-11T21:07:32.127909Z","end":"2026-05-11T21:07:32.233875Z","steps":["trace[1246919169] 'process raft request' (duration: 105.836337ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:32.23395Z","caller":"traceutil/trace.go:171","msg":"trace[679362199] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"106.43007ms","start":"2026-05-11T21:07:32.127489Z","end":"2026-05-11T21:07:32.233919Z","steps":["trace[679362199] 'process raft request' (duration: 19.77363ms)","trace[679362199] 'compare' (duration: 86.334153ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:08:04.413711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.985345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:08:04.413829Z","caller":"traceutil/trace.go:171","msg":"trace[466330917] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1847; }","duration":"135.192531ms","start":"2026-05-11T21:08:04.278614Z","end":"2026-05-11T21:08:04.413807Z","steps":["trace[466330917] 'count revisions from in-memory index tree' (duration: 134.606858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:04.413949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.502296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e966e6a1-465e-431b-87fe-65d3c3952e46\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-11T21:08:04.414058Z","caller":"traceutil/trace.go:171","msg":"trace[1722651949] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e966e6a1-465e-431b-87fe-65d3c3952e46; range_end:; response_count:1; response_revision:1847; }","duration":"167.755902ms","start":"2026-05-11T21:08:04.246273Z","end":"2026-05-11T21:08:04.414029Z","steps":["trace[1722651949] 'range keys from in-memory index tree' (duration: 167.182079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:04.414199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.952905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-05-11T21:08:04.414306Z","caller":"traceutil/trace.go:171","msg":"trace[616885313] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1847; }","duration":"102.072787ms","start":"2026-05-11T21:08:04.312209Z","end":"2026-05-11T21:08:04.414282Z","steps":["trace[616885313] 'range keys from in-memory index tree' (duration: 101.728141ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:04.587687Z","caller":"traceutil/trace.go:171","msg":"trace[1543720583] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"160.541668ms","start":"2026-05-11T21:08:04.426617Z","end":"2026-05-11T21:08:04.587159Z","steps":["trace[1543720583] 'process raft request' (duration: 160.337124ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:04.833121Z","caller":"traceutil/trace.go:171","msg":"trace[620573198] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"237.057062ms","start":"2026-05-11T21:08:04.596043Z","end":"2026-05-11T21:08:04.8331Z","steps":["trace[620573198] 'process raft request' (duration: 236.937719ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:04.980617Z","caller":"traceutil/trace.go:171","msg":"trace[2033944112] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"384.315629ms","start":"2026-05-11T21:08:04.596264Z","end":"2026-05-11T21:08:04.98058Z","steps":["trace[2033944112] 'process raft request' (duration: 384.037304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:04.980893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:08:04.596243Z","time spent":"384.504853ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:08:04.981172Z","caller":"traceutil/trace.go:171","msg":"trace[1050943358] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"384.756849ms","start":"2026-05-11T21:08:04.596357Z","end":"2026-05-11T21:08:04.981114Z","steps":["trace[1050943358] 'process raft request' (duration: 384.138626ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:04.981341Z","caller":"traceutil/trace.go:171","msg":"trace[605072861] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"384.34532ms","start":"2026-05-11T21:08:04.59693Z","end":"2026-05-11T21:08:04.981275Z","steps":["trace[605072861] 'process raft request' (duration: 384.058484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:04.981572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:08:04.596913Z","time spent":"384.529754ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:08:04.981553Z","caller":"traceutil/trace.go:171","msg":"trace[897512430] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"379.773112ms","start":"2026-05-11T21:08:04.601735Z","end":"2026-05-11T21:08:04.981508Z","steps":["trace[897512430] 'process raft request' (duration: 379.435325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:04.981614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:08:04.596323Z","time spent":"385.108936ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:08:04.981768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:08:04.601729Z","time spent":"379.931435ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:08:04.981788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.908073ms","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-05-11T21:08:04.981835Z","caller":"traceutil/trace.go:171","msg":"trace[2119279143] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"259.830257ms","start":"2026-05-11T21:08:04.721963Z","end":"2026-05-11T21:08:04.981793Z","steps":["trace[2119279143] 'process raft request' (duration: 259.435859ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:04.981883Z","caller":"traceutil/trace.go:171","msg":"trace[930763616] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"297.057476ms","start":"2026-05-11T21:08:04.684799Z","end":"2026-05-11T21:08:04.981856Z","steps":["trace[930763616] 'agreement among raft nodes before linearized reading' (duration: 296.833432ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:04.981573Z","caller":"traceutil/trace.go:171","msg":"trace[242635842] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1919; }","duration":"296.691319ms","start":"2026-05-11T21:08:04.684853Z","end":"2026-05-11T21:08:04.981544Z","steps":["trace[242635842] 'read index received' (duration: 148.206676ms)","trace[242635842] 'applied index is now lower than readState.Index' (duration: 148.482883ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:08:15.979707Z","caller":"traceutil/trace.go:171","msg":"trace[565765239] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"127.68148ms","start":"2026-05-11T21:08:15.851992Z","end":"2026-05-11T21:08:15.979674Z","steps":["trace[565765239] 'process raft request' (duration: 127.463875ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:15.997977Z","caller":"traceutil/trace.go:171","msg":"trace[415595036] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"144.229723ms","start":"2026-05-11T21:08:15.853651Z","end":"2026-05-11T21:08:15.997881Z","steps":["trace[415595036] 'process raft request' (duration: 144.189742ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:15.997966Z","caller":"traceutil/trace.go:171","msg":"trace[2040746975] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"144.295644ms","start":"2026-05-11T21:08:15.853633Z","end":"2026-05-11T21:08:15.997929Z","steps":["trace[2040746975] 'process raft request' (duration: 144.1008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:55.598945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.603359ms","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-05-11T21:08:55.59906Z","caller":"traceutil/trace.go:171","msg":"trace[2107527731] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2175; }","duration":"210.779893ms","start":"2026-05-11T21:08:55.388254Z","end":"2026-05-11T21:08:55.599034Z","steps":["trace[2107527731] 'range keys from in-memory index tree' (duration: 210.390065ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:09:44.002449Z","caller":"traceutil/trace.go:171","msg":"trace[806156828] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"102.582451ms","start":"2026-05-11T21:09:43.899834Z","end":"2026-05-11T21:09:44.002416Z","steps":["trace[806156828] 'process raft request' (duration: 102.384177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:10:17.916574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.712384ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-11T21:14:27.184675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.301163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:14:27.184822Z","caller":"traceutil/trace.go:171","msg":"trace[1077832549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5092; }","duration":"105.516308ms","start":"2026-05-11T21:14:27.079275Z","end":"2026-05-11T21:14:27.184791Z","steps":["trace[1077832549] 'range keys from in-memory index tree' (duration: 105.190631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:14:27.184926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.734982ms","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-05-11T21:14:27.185009Z","caller":"traceutil/trace.go:171","msg":"trace[1881133059] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5092; }","duration":"119.869815ms","start":"2026-05-11T21:14:27.065115Z","end":"2026-05-11T21:14:27.184984Z","steps":["trace[1881133059] 'count revisions from in-memory index tree' (duration: 119.606289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:14:27.185026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.009796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-05-11T21:14:27.185152Z","caller":"traceutil/trace.go:171","msg":"trace[1912985922] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5092; }","duration":"126.164339ms","start":"2026-05-11T21:14:27.05896Z","end":"2026-05-11T21:14:27.185124Z","steps":["trace[1912985922] 'range keys from in-memory index tree' (duration: 125.842462ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:27.277837Z","caller":"traceutil/trace.go:171","msg":"trace[784185127] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"164.353297ms","start":"2026-05-11T21:14:27.11346Z","end":"2026-05-11T21:14:27.277813Z","steps":["trace[784185127] 'process raft request' (duration: 164.236474ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:27.277965Z","caller":"traceutil/trace.go:171","msg":"trace[33335756] linearizableReadLoop","detail":"{readStateIndex:5290; appliedIndex:5286; }","duration":"144.745217ms","start":"2026-05-11T21:14:27.133196Z","end":"2026-05-11T21:14:27.277941Z","steps":["trace[33335756] 'read index received' (duration: 48.992919ms)","trace[33335756] 'applied index is now lower than readState.Index' (duration: 95.749548ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:14:27.277838Z","caller":"traceutil/trace.go:171","msg":"trace[428305053] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"168.839352ms","start":"2026-05-11T21:14:27.10896Z","end":"2026-05-11T21:14:27.277799Z","steps":["trace[428305053] 'process raft request' (duration: 168.499435ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:27.278113Z","caller":"traceutil/trace.go:171","msg":"trace[2101845945] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"164.366157ms","start":"2026-05-11T21:14:27.113704Z","end":"2026-05-11T21:14:27.27807Z","steps":["trace[2101845945] 'process raft request' (duration: 164.056641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:14:27.278248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.030223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1bf754fa-7091-476a-9ba1-2322eee6fb6f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-11T21:14:27.278309Z","caller":"traceutil/trace.go:171","msg":"trace[37829641] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1bf754fa-7091-476a-9ba1-2322eee6fb6f; range_end:; response_count:1; response_revision:5096; }","duration":"145.150466ms","start":"2026-05-11T21:14:27.13314Z","end":"2026-05-11T21:14:27.27829Z","steps":["trace[37829641] 'agreement among raft nodes before linearized reading' (duration: 144.913641ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:37.86818Z","caller":"traceutil/trace.go:171","msg":"trace[108057484] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"122.49684ms","start":"2026-05-11T21:14:37.745649Z","end":"2026-05-11T21:14:37.868145Z","steps":["trace[108057484] 'process raft request' (duration: 32.089106ms)","trace[108057484] 'compare' (duration: 90.047766ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:14:38.348684Z","caller":"traceutil/trace.go:171","msg":"trace[1129980674] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"234.694591ms","start":"2026-05-11T21:14:38.113955Z","end":"2026-05-11T21:14:38.348649Z","steps":["trace[1129980674] 'process raft request' (duration: 218.363152ms)","trace[1129980674] 'compare' (duration: 16.187156ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:14:46.41169Z","caller":"traceutil/trace.go:171","msg":"trace[2046934007] linearizableReadLoop","detail":"{readStateIndex:5685; appliedIndex:5684; }","duration":"242.743994ms","start":"2026-05-11T21:14:46.168905Z","end":"2026-05-11T21:14:46.411649Z","steps":["trace[2046934007] 'read index received' (duration: 242.603641ms)","trace[2046934007] 'applied index is now lower than readState.Index' (duration: 138.612µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:14:46.41181Z","caller":"traceutil/trace.go:171","msg":"trace[481765946] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"257.651672ms","start":"2026-05-11T21:14:46.154072Z","end":"2026-05-11T21:14:46.411724Z","steps":["trace[481765946] 'process raft request' (duration: 257.385987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:14:46.41212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.612071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T21:14:46.412129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.177452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5014e3df-3480-46a9-bae5-5293e47d9786\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-11T21:14:46.412182Z","caller":"traceutil/trace.go:171","msg":"trace[1930691395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5480; }","duration":"192.784814ms","start":"2026-05-11T21:14:46.219382Z","end":"2026-05-11T21:14:46.412167Z","steps":["trace[1930691395] 'agreement among raft nodes before linearized reading' (duration: 192.490338ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:46.412217Z","caller":"traceutil/trace.go:171","msg":"trace[2120806388] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5014e3df-3480-46a9-bae5-5293e47d9786; range_end:; response_count:1; response_revision:5480; }","duration":"243.323146ms","start":"2026-05-11T21:14:46.16887Z","end":"2026-05-11T21:14:46.412193Z","steps":["trace[2120806388] 'agreement among raft nodes before linearized reading' (duration: 242.960468ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:46.557686Z","caller":"traceutil/trace.go:171","msg":"trace[1394665016] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"140.109438ms","start":"2026-05-11T21:14:46.417544Z","end":"2026-05-11T21:14:46.557653Z","steps":["trace[1394665016] 'process raft request' (duration: 139.978165ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:46.557706Z","caller":"traceutil/trace.go:171","msg":"trace[1555613989] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"140.742681ms","start":"2026-05-11T21:14:46.416911Z","end":"2026-05-11T21:14:46.557653Z","steps":["trace[1555613989] 'process raft request' (duration: 69.039098ms)","trace[1555613989] 'compare' (duration: 71.238463ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:15:37.679746Z","caller":"traceutil/trace.go:171","msg":"trace[374547353] transaction","detail":"{read_only:false; response_revision:5976; number_of_response:1; }","duration":"105.596197ms","start":"2026-05-11T21:15:37.574111Z","end":"2026-05-11T21:15:37.679707Z","steps":["trace[374547353] 'process raft request' (duration: 83.073746ms)","trace[374547353] 'compare' (duration: 22.203335ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:15:48.961625Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2989} {"level":"info","ts":"2026-05-11T21:15:49.035299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2989,"took":"71.686552ms","hash":3494348189,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":33402880,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-11T21:15:49.035376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3494348189,"revision":2989,"compact-revision":-1} {"level":"info","ts":"2026-05-11T21:20:48.969173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6068} {"level":"info","ts":"2026-05-11T21:20:49.066903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6068,"took":"94.941771ms","hash":1284824687,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":21786624,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-11T21:20:49.066979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1284824687,"revision":6068,"compact-revision":2989} {"level":"info","ts":"2026-05-11T21:21:07.079393Z","caller":"traceutil/trace.go:171","msg":"trace[1511126434] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"102.205027ms","start":"2026-05-11T21:21:06.977155Z","end":"2026-05-11T21:21:07.07936Z","steps":["trace[1511126434] 'process raft request' (duration: 67.015675ms)","trace[1511126434] 'compare' (duration: 35.07238ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:21:07.090637Z","caller":"traceutil/trace.go:171","msg":"trace[1858392439] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"108.183095ms","start":"2026-05-11T21:21:06.982418Z","end":"2026-05-11T21:21:07.090601Z","steps":["trace[1858392439] 'process raft request' (duration: 107.9338ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:07.922458Z","caller":"traceutil/trace.go:171","msg":"trace[45819157] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"237.816031ms","start":"2026-05-11T21:21:07.684611Z","end":"2026-05-11T21:21:07.922427Z","steps":["trace[45819157] 'process raft request' (duration: 237.607607ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:07.92938Z","caller":"traceutil/trace.go:171","msg":"trace[55302600] linearizableReadLoop","detail":"{readStateIndex:8688; appliedIndex:8686; }","duration":"173.737789ms","start":"2026-05-11T21:21:07.755624Z","end":"2026-05-11T21:21:07.929362Z","steps":["trace[55302600] 'read index received' (duration: 166.662017ms)","trace[55302600] 'applied index is now lower than readState.Index' (duration: 7.074792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:21:07.929524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.876861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:21:07.929511Z","caller":"traceutil/trace.go:171","msg":"trace[47082453] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"241.379537ms","start":"2026-05-11T21:21:07.688082Z","end":"2026-05-11T21:21:07.929461Z","steps":["trace[47082453] 'process raft request' (duration: 241.183703ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:07.929564Z","caller":"traceutil/trace.go:171","msg":"trace[1467840164] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8391; }","duration":"173.999014ms","start":"2026-05-11T21:21:07.755551Z","end":"2026-05-11T21:21:07.92955Z","steps":["trace[1467840164] 'agreement among raft nodes before linearized reading' (duration: 173.882741ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:08.791064Z","caller":"traceutil/trace.go:171","msg":"trace[1157904807] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"162.613451ms","start":"2026-05-11T21:21:08.62841Z","end":"2026-05-11T21:21:08.791023Z","steps":["trace[1157904807] 'process raft request' (duration: 162.362845ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:08.803419Z","caller":"traceutil/trace.go:171","msg":"trace[1334519454] transaction","detail":"{read_only:false; response_revision:8394; number_of_response:1; }","duration":"174.844703ms","start":"2026-05-11T21:21:08.628539Z","end":"2026-05-11T21:21:08.803384Z","steps":["trace[1334519454] 'process raft request' (duration: 174.562137ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:08.80403Z","caller":"traceutil/trace.go:171","msg":"trace[1055477029] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"175.429545ms","start":"2026-05-11T21:21:08.628581Z","end":"2026-05-11T21:21:08.804011Z","steps":["trace[1055477029] 'process raft request' (duration: 174.753421ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:08.804234Z","caller":"traceutil/trace.go:171","msg":"trace[1395722495] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"144.157525ms","start":"2026-05-11T21:21:08.660046Z","end":"2026-05-11T21:21:08.804203Z","steps":["trace[1395722495] 'process raft request' (duration: 143.583513ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:09.2146Z","caller":"traceutil/trace.go:171","msg":"trace[1737540563] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"128.859148ms","start":"2026-05-11T21:21:09.085709Z","end":"2026-05-11T21:21:09.214568Z","steps":["trace[1737540563] 'process raft request' (duration: 128.661653ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:09.215699Z","caller":"traceutil/trace.go:171","msg":"trace[1161004889] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"119.979498ms","start":"2026-05-11T21:21:09.095678Z","end":"2026-05-11T21:21:09.215658Z","steps":["trace[1161004889] 'process raft request' (duration: 119.258103ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:37.059631Z","caller":"traceutil/trace.go:171","msg":"trace[1311363890] linearizableReadLoop","detail":"{readStateIndex:8902; appliedIndex:8901; }","duration":"127.463028ms","start":"2026-05-11T21:21:36.932136Z","end":"2026-05-11T21:21:37.059599Z","steps":["trace[1311363890] 'read index received' (duration: 127.227093ms)","trace[1311363890] 'applied index is now lower than readState.Index' (duration: 234.895µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:21:37.060226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.062132ms","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-05-11T21:21:37.060279Z","caller":"traceutil/trace.go:171","msg":"trace[855475624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8598; }","duration":"128.166494ms","start":"2026-05-11T21:21:36.932097Z","end":"2026-05-11T21:21:37.060264Z","steps":["trace[855475624] 'agreement among raft nodes before linearized reading' (duration: 127.965139ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:37.06051Z","caller":"traceutil/trace.go:171","msg":"trace[1928031210] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"141.722784ms","start":"2026-05-11T21:21:36.918776Z","end":"2026-05-11T21:21:37.060498Z","steps":["trace[1928031210] 'process raft request' (duration: 140.6225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:21:37.061031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.213356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T21:21:37.061078Z","caller":"traceutil/trace.go:171","msg":"trace[2084076174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8598; }","duration":"115.272537ms","start":"2026-05-11T21:21:36.945795Z","end":"2026-05-11T21:21:37.061067Z","steps":["trace[2084076174] 'agreement among raft nodes before linearized reading' (duration: 115.163935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:21:37.061383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.627277ms","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-05-11T21:21:37.061488Z","caller":"traceutil/trace.go:171","msg":"trace[2043882956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8598; }","duration":"116.81238ms","start":"2026-05-11T21:21:36.944653Z","end":"2026-05-11T21:21:37.061466Z","steps":["trace[2043882956] 'agreement among raft nodes before linearized reading' (duration: 115.39706ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:49.643954Z","caller":"traceutil/trace.go:171","msg":"trace[929146872] linearizableReadLoop","detail":"{readStateIndex:9017; appliedIndex:9016; }","duration":"130.786969ms","start":"2026-05-11T21:21:49.513131Z","end":"2026-05-11T21:21:49.643918Z","steps":["trace[929146872] 'read index received' (duration: 88.084455ms)","trace[929146872] 'applied index is now lower than readState.Index' (duration: 42.700944ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:21:49.643998Z","caller":"traceutil/trace.go:171","msg":"trace[1881001718] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"265.072194ms","start":"2026-05-11T21:21:49.378871Z","end":"2026-05-11T21:21:49.643943Z","steps":["trace[1881001718] 'process raft request' (duration: 222.39375ms)","trace[1881001718] 'compare' (duration: 42.456808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:21:49.644256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.102687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-v9h8r\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-05-11T21:21:49.644384Z","caller":"traceutil/trace.go:171","msg":"trace[707541243] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-v9h8r; range_end:; response_count:1; response_revision:8707; }","duration":"131.293711ms","start":"2026-05-11T21:21:49.513061Z","end":"2026-05-11T21:21:49.644355Z","steps":["trace[707541243] 'agreement among raft nodes before linearized reading' (duration: 130.941503ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:49.64862Z","caller":"traceutil/trace.go:171","msg":"trace[1122521871] transaction","detail":"{read_only:false; response_revision:8708; number_of_response:1; }","duration":"134.710494ms","start":"2026-05-11T21:21:49.513888Z","end":"2026-05-11T21:21:49.648598Z","steps":["trace[1122521871] 'process raft request' (duration: 134.553041ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:22:06.401752Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d7.wal"} {"level":"info","ts":"2026-05-11T21:23:33.68636Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T21:23:33.692211Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T21:23:33.692341Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T21:25:48.975615Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8164} {"level":"info","ts":"2026-05-11T21:25:49.034513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8164,"took":"56.852077ms","hash":2835756935,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":28147712,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T21:25:49.034595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2835756935,"revision":8164,"compact-revision":6068} {"level":"info","ts":"2026-05-11T21:28:46.931843Z","caller":"traceutil/trace.go:171","msg":"trace[1809196345] transaction","detail":"{read_only:false; response_revision:11902; number_of_response:1; }","duration":"163.558645ms","start":"2026-05-11T21:28:46.768252Z","end":"2026-05-11T21:28:46.931811Z","steps":["trace[1809196345] 'process raft request' (duration: 163.358401ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:30:48.983413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10518} {"level":"info","ts":"2026-05-11T21:30:49.069824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10518,"took":"83.613281ms","hash":1537707325,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":26054656,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T21:30:49.069905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1537707325,"revision":10518,"compact-revision":8164} {"level":"info","ts":"2026-05-11T21:35:15.753333Z","caller":"traceutil/trace.go:171","msg":"trace[1488337345] transaction","detail":"{read_only:false; response_revision:14500; number_of_response:1; }","duration":"145.535612ms","start":"2026-05-11T21:35:15.60777Z","end":"2026-05-11T21:35:15.753305Z","steps":["trace[1488337345] 'process raft request' (duration: 145.4073ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:35:15.75332Z","caller":"traceutil/trace.go:171","msg":"trace[1537588361] transaction","detail":"{read_only:false; response_revision:14499; number_of_response:1; }","duration":"145.506822ms","start":"2026-05-11T21:35:15.60777Z","end":"2026-05-11T21:35:15.753277Z","steps":["trace[1537588361] 'process raft request' (duration: 114.407805ms)","trace[1537588361] 'compare' (duration: 30.817731ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:35:21.795847Z","caller":"traceutil/trace.go:171","msg":"trace[945637212] transaction","detail":"{read_only:false; response_revision:14529; number_of_response:1; }","duration":"127.381934ms","start":"2026-05-11T21:35:21.668419Z","end":"2026-05-11T21:35:21.795801Z","steps":["trace[945637212] 'process raft request' (duration: 127.051587ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:35:48.991066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12737} {"level":"info","ts":"2026-05-11T21:35:49.069905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12737,"took":"74.481077ms","hash":1004827397,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":29360128,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T21:35:49.070008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1004827397,"revision":12737,"compact-revision":10518} {"level":"info","ts":"2026-05-11T21:37:04.8421Z","caller":"traceutil/trace.go:171","msg":"trace[1926739633] transaction","detail":"{read_only:false; response_revision:15766; number_of_response:1; }","duration":"134.379501ms","start":"2026-05-11T21:37:04.707695Z","end":"2026-05-11T21:37:04.842075Z","steps":["trace[1926739633] 'process raft request' (duration: 134.227308ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:40:48.99705Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15011} {"level":"info","ts":"2026-05-11T21:40:49.088139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15011,"took":"88.004257ms","hash":453617020,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":30547968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T21:40:49.08822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":453617020,"revision":15011,"compact-revision":12737} {"level":"info","ts":"2026-05-11T21:42:50.353889Z","caller":"traceutil/trace.go:171","msg":"trace[216167383] transaction","detail":"{read_only:false; response_revision:18395; number_of_response:1; }","duration":"118.485986ms","start":"2026-05-11T21:42:50.23538Z","end":"2026-05-11T21:42:50.353866Z","steps":["trace[216167383] 'process raft request' (duration: 118.339813ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:42:50.364491Z","caller":"traceutil/trace.go:171","msg":"trace[1488936745] transaction","detail":"{read_only:false; response_revision:18397; number_of_response:1; }","duration":"126.345225ms","start":"2026-05-11T21:42:50.238109Z","end":"2026-05-11T21:42:50.364454Z","steps":["trace[1488936745] 'process raft request' (duration: 126.247503ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:42:50.364518Z","caller":"traceutil/trace.go:171","msg":"trace[1668535748] transaction","detail":"{read_only:false; response_revision:18396; number_of_response:1; }","duration":"127.973729ms","start":"2026-05-11T21:42:50.236507Z","end":"2026-05-11T21:42:50.36448Z","steps":["trace[1668535748] 'process raft request' (duration: 127.668433ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:42:57.333269Z","caller":"traceutil/trace.go:171","msg":"trace[1822877565] linearizableReadLoop","detail":"{readStateIndex:19074; appliedIndex:19073; }","duration":"229.747867ms","start":"2026-05-11T21:42:57.103495Z","end":"2026-05-11T21:42:57.333243Z","steps":["trace[1822877565] 'read index received' (duration: 229.328648ms)","trace[1822877565] 'applied index is now lower than readState.Index' (duration: 417.479µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:42:57.333331Z","caller":"traceutil/trace.go:171","msg":"trace[153104716] transaction","detail":"{read_only:false; response_revision:18446; number_of_response:1; }","duration":"271.188083ms","start":"2026-05-11T21:42:57.062098Z","end":"2026-05-11T21:42:57.333286Z","steps":["trace[153104716] 'process raft request' (duration: 270.972788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:42:57.33348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.975142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T21:42:57.333519Z","caller":"traceutil/trace.go:171","msg":"trace[1428446843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18446; }","duration":"230.092035ms","start":"2026-05-11T21:42:57.103414Z","end":"2026-05-11T21:42:57.333506Z","steps":["trace[1428446843] 'agreement among raft nodes before linearized reading' (duration: 229.930251ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:42:57.66165Z","caller":"traceutil/trace.go:171","msg":"trace[807698824] transaction","detail":"{read_only:false; response_revision:18447; number_of_response:1; }","duration":"323.32194ms","start":"2026-05-11T21:42:57.338285Z","end":"2026-05-11T21:42:57.661607Z","steps":["trace[807698824] 'process raft request' (duration: 240.522648ms)","trace[807698824] 'compare' (duration: 82.600048ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:42:57.662424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:42:57.338263Z","time spent":"324.040535ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:42:57.728968Z","caller":"traceutil/trace.go:171","msg":"trace[935320919] linearizableReadLoop","detail":"{readStateIndex:19076; appliedIndex:19074; }","duration":"164.541752ms","start":"2026-05-11T21:42:57.564137Z","end":"2026-05-11T21:42:57.728679Z","steps":["trace[935320919] 'read index received' (duration: 14.704394ms)","trace[935320919] 'applied index is now lower than readState.Index' (duration: 149.835378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:42:57.729179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.009421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vjjxd\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-05-11T21:42:57.729315Z","caller":"traceutil/trace.go:171","msg":"trace[669379246] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vjjxd; range_end:; response_count:1; response_revision:18447; }","duration":"165.215007ms","start":"2026-05-11T21:42:57.564071Z","end":"2026-05-11T21:42:57.729286Z","steps":["trace[669379246] 'agreement among raft nodes before linearized reading' (duration: 164.777377ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:44:28.808897Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T21:44:28.815404Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T21:44:28.815604Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T21:45:49.003911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17454} {"level":"info","ts":"2026-05-11T21:45:49.113038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17454,"took":"104.19741ms","hash":2551206354,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":34881536,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-11T21:45:49.113098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2551206354,"revision":17454,"compact-revision":15011} {"level":"warn","ts":"2026-05-11T21:48:52.491235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.821808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T21:48:52.491351Z","caller":"traceutil/trace.go:171","msg":"trace[1912938425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21081; }","duration":"100.985791ms","start":"2026-05-11T21:48:52.390341Z","end":"2026-05-11T21:48:52.491327Z","steps":["trace[1912938425] 'range keys from in-memory index tree' (duration: 100.606523ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:57.839501Z","caller":"traceutil/trace.go:171","msg":"trace[902590697] transaction","detail":"{read_only:false; response_revision:21107; number_of_response:1; }","duration":"105.028589ms","start":"2026-05-11T21:48:57.734434Z","end":"2026-05-11T21:48:57.839462Z","steps":["trace[902590697] 'process raft request' (duration: 104.835225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:48:58.011009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.232953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:48:58.011106Z","caller":"traceutil/trace.go:171","msg":"trace[2056790922] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:21107; }","duration":"105.422897ms","start":"2026-05-11T21:48:57.905662Z","end":"2026-05-11T21:48:58.011085Z","steps":["trace[2056790922] 'count revisions from in-memory index tree' (duration: 105.11682ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:58.699891Z","caller":"traceutil/trace.go:171","msg":"trace[46315164] transaction","detail":"{read_only:false; response_revision:21112; number_of_response:1; }","duration":"168.1996ms","start":"2026-05-11T21:48:58.531659Z","end":"2026-05-11T21:48:58.699859Z","steps":["trace[46315164] 'process raft request' (duration: 168.028697ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:58.710294Z","caller":"traceutil/trace.go:171","msg":"trace[1145759623] transaction","detail":"{read_only:false; response_revision:21113; number_of_response:1; }","duration":"177.232494ms","start":"2026-05-11T21:48:58.533036Z","end":"2026-05-11T21:48:58.710268Z","steps":["trace[1145759623] 'process raft request' (duration: 177.05859ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:58.710454Z","caller":"traceutil/trace.go:171","msg":"trace[1474824212] transaction","detail":"{read_only:false; response_revision:21114; number_of_response:1; }","duration":"176.828726ms","start":"2026-05-11T21:48:58.533615Z","end":"2026-05-11T21:48:58.710443Z","steps":["trace[1474824212] 'process raft request' (duration: 176.56626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:48:58.983945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.71538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-11T21:48:58.984055Z","caller":"traceutil/trace.go:171","msg":"trace[2028253652] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21114; }","duration":"103.883774ms","start":"2026-05-11T21:48:58.880148Z","end":"2026-05-11T21:48:58.984032Z","steps":["trace[2028253652] 'range keys from in-memory index tree' (duration: 103.469445ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:50:49.012256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19857} {"level":"info","ts":"2026-05-11T21:50:49.136255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19857,"took":"120.363856ms","hash":1397880604,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":29638656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T21:50:49.136319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1397880604,"revision":19857,"compact-revision":17454} {"level":"info","ts":"2026-05-11T21:52:52.711709Z","caller":"traceutil/trace.go:171","msg":"trace[1260753157] linearizableReadLoop","detail":"{readStateIndex:24358; appliedIndex:24357; }","duration":"166.730519ms","start":"2026-05-11T21:52:52.544948Z","end":"2026-05-11T21:52:52.711678Z","steps":["trace[1260753157] 'read index received' (duration: 166.468403ms)","trace[1260753157] 'applied index is now lower than readState.Index' (duration: 260.666µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:52:52.711816Z","caller":"traceutil/trace.go:171","msg":"trace[1341461919] transaction","detail":"{read_only:false; response_revision:23563; number_of_response:1; }","duration":"182.892795ms","start":"2026-05-11T21:52:52.528878Z","end":"2026-05-11T21:52:52.711771Z","steps":["trace[1341461919] 'process raft request' (duration: 182.560958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:52:52.713706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.719911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T21:52:52.713765Z","caller":"traceutil/trace.go:171","msg":"trace[1701761536] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:23563; }","duration":"168.829273ms","start":"2026-05-11T21:52:52.54492Z","end":"2026-05-11T21:52:52.71375Z","steps":["trace[1701761536] 'agreement among raft nodes before linearized reading' (duration: 166.887853ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:54:21.455605Z","caller":"traceutil/trace.go:171","msg":"trace[605000905] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"131.402333ms","start":"2026-05-11T21:54:21.324174Z","end":"2026-05-11T21:54:21.455576Z","steps":["trace[605000905] 'process raft request' (duration: 131.194728ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:54:21.455877Z","caller":"traceutil/trace.go:171","msg":"trace[673005325] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"130.189376ms","start":"2026-05-11T21:54:21.325662Z","end":"2026-05-11T21:54:21.455851Z","steps":["trace[673005325] 'process raft request' (duration: 129.863189ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:54:21.455977Z","caller":"traceutil/trace.go:171","msg":"trace[1731170169] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"129.096324ms","start":"2026-05-11T21:54:21.326855Z","end":"2026-05-11T21:54:21.455951Z","steps":["trace[1731170169] 'process raft request' (duration: 128.932169ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:55:49.018595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22066} {"level":"info","ts":"2026-05-11T21:55:49.132483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22066,"took":"109.89734ms","hash":4122837859,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":43253760,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-11T21:55:49.132541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4122837859,"revision":22066,"compact-revision":19857} {"level":"info","ts":"2026-05-11T21:56:18.401429Z","caller":"traceutil/trace.go:171","msg":"trace[1425749582] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"141.033506ms","start":"2026-05-11T21:56:18.260376Z","end":"2026-05-11T21:56:18.401409Z","steps":["trace[1425749582] 'process raft request' (duration: 140.918543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.182334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.931193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:24.183314Z","caller":"traceutil/trace.go:171","msg":"trace[615605603] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"280.421987ms","start":"2026-05-11T21:56:23.902822Z","end":"2026-05-11T21:56:24.183244Z","steps":["trace[615605603] 'process raft request' (duration: 95.463132ms)","trace[615605603] 'compare' (duration: 183.77151ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:24.183486Z","caller":"traceutil/trace.go:171","msg":"trace[47146268] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"240.209496ms","start":"2026-05-11T21:56:23.943249Z","end":"2026-05-11T21:56:24.183458Z","steps":["trace[47146268] 'process raft request' (duration: 240.165575ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.183666Z","caller":"traceutil/trace.go:171","msg":"trace[1362493957] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"241.26026ms","start":"2026-05-11T21:56:23.942395Z","end":"2026-05-11T21:56:24.183655Z","steps":["trace[1362493957] 'process raft request' (duration: 240.977704ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.183438Z","caller":"traceutil/trace.go:171","msg":"trace[749568635] linearizableReadLoop","detail":"{readStateIndex:26535; appliedIndex:26534; }","duration":"262.148926ms","start":"2026-05-11T21:56:23.921246Z","end":"2026-05-11T21:56:24.183395Z","steps":["trace[749568635] 'read index received' (duration: 77.056218ms)","trace[749568635] 'applied index is now lower than readState.Index' (duration: 185.089948ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:24.183835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.560724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-05-11T21:56:24.183902Z","caller":"traceutil/trace.go:171","msg":"trace[1836698107] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25686; }","duration":"262.701507ms","start":"2026-05-11T21:56:23.921185Z","end":"2026-05-11T21:56:24.183887Z","steps":["trace[1836698107] 'agreement among raft nodes before linearized reading' (duration: 262.445652ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.184056Z","caller":"traceutil/trace.go:171","msg":"trace[710715518] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"247.197788ms","start":"2026-05-11T21:56:23.936796Z","end":"2026-05-11T21:56:24.183993Z","steps":["trace[710715518] 'process raft request' (duration: 246.444381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.487838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.220102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:24.488038Z","caller":"traceutil/trace.go:171","msg":"trace[1609332183] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26538; }","duration":"304.283288ms","start":"2026-05-11T21:56:24.183741Z","end":"2026-05-11T21:56:24.488024Z","steps":["trace[1609332183] 'read index received' (duration: 124.80697ms)","trace[1609332183] 'applied index is now lower than readState.Index' (duration: 179.475488ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:24.488117Z","caller":"traceutil/trace.go:171","msg":"trace[1229331869] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"325.423409ms","start":"2026-05-11T21:56:24.162685Z","end":"2026-05-11T21:56:24.488108Z","steps":["trace[1229331869] 'process raft request' (duration: 325.313007ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.488111Z","caller":"traceutil/trace.go:171","msg":"trace[167227244] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"326.087593ms","start":"2026-05-11T21:56:24.161986Z","end":"2026-05-11T21:56:24.488073Z","steps":["trace[167227244] 'process raft request' (duration: 325.967561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.488161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.162651Z","time spent":"325.484731ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:24.488294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.161971Z","time spent":"326.217056ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:24.488493Z","caller":"traceutil/trace.go:171","msg":"trace[1917643618] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"330.12605ms","start":"2026-05-11T21:56:24.158354Z","end":"2026-05-11T21:56:24.48848Z","steps":["trace[1917643618] 'process raft request' (duration: 150.176942ms)","trace[1917643618] 'compare' (duration: 178.861695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:24.488548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.158311Z","time spent":"330.204982ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:24.488583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.170351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T21:56:24.488622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.975212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-11T21:56:24.48865Z","caller":"traceutil/trace.go:171","msg":"trace[183065438] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25689; }","duration":"500.061534ms","start":"2026-05-11T21:56:23.988581Z","end":"2026-05-11T21:56:24.488642Z","steps":["trace[183065438] 'agreement among raft nodes before linearized reading' (duration: 499.87144ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.488657Z","caller":"traceutil/trace.go:171","msg":"trace[736870218] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25689; }","duration":"323.268733ms","start":"2026-05-11T21:56:24.165372Z","end":"2026-05-11T21:56:24.488641Z","steps":["trace[736870218] 'agreement among raft nodes before linearized reading' (duration: 323.092249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.48867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:23.988555Z","time spent":"500.111125ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-11T21:56:24.488691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.165321Z","time spent":"323.361855ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-05-11T21:56:24.488825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.439089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-05-11T21:56:24.48893Z","caller":"traceutil/trace.go:171","msg":"trace[1788701379] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25689; }","duration":"296.586942ms","start":"2026-05-11T21:56:24.192317Z","end":"2026-05-11T21:56:24.488904Z","steps":["trace[1788701379] 'agreement among raft nodes before linearized reading' (duration: 296.345317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.940076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.369192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:24.940176Z","caller":"traceutil/trace.go:171","msg":"trace[1657479479] linearizableReadLoop","detail":"{readStateIndex:26542; appliedIndex:26541; }","duration":"391.575713ms","start":"2026-05-11T21:56:24.548581Z","end":"2026-05-11T21:56:24.940157Z","steps":["trace[1657479479] 'read index received' (duration: 214.992627ms)","trace[1657479479] 'applied index is now lower than readState.Index' (duration: 176.581846ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:24.940363Z","caller":"traceutil/trace.go:171","msg":"trace[1658271418] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"422.588637ms","start":"2026-05-11T21:56:24.51776Z","end":"2026-05-11T21:56:24.940349Z","steps":["trace[1658271418] 'process raft request' (duration: 245.861827ms)","trace[1658271418] 'compare' (duration: 175.865451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:24.940419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.517733Z","time spent":"422.653229ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:24.940613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.991163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:24.941002Z","caller":"traceutil/trace.go:171","msg":"trace[560130263] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25690; }","duration":"392.406582ms","start":"2026-05-11T21:56:24.548519Z","end":"2026-05-11T21:56:24.940925Z","steps":["trace[560130263] 'agreement among raft nodes before linearized reading' (duration: 391.889841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.941119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.548498Z","time spent":"392.599386ms","remote":"127.0.0.1:56316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-11T21:56:24.9887Z","caller":"traceutil/trace.go:171","msg":"trace[1173746789] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"121.07659ms","start":"2026-05-11T21:56:24.867596Z","end":"2026-05-11T21:56:24.988673Z","steps":["trace[1173746789] 'process raft request' (duration: 120.891946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.989111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.041336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-05-11T21:56:24.989153Z","caller":"traceutil/trace.go:171","msg":"trace[826770215] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25691; }","duration":"314.125047ms","start":"2026-05-11T21:56:24.675012Z","end":"2026-05-11T21:56:24.989137Z","steps":["trace[826770215] 'agreement among raft nodes before linearized reading' (duration: 313.867492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.989185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.674995Z","time spent":"314.180329ms","remote":"127.0.0.1:43338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418327,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-05-11T21:56:25.384041Z","caller":"traceutil/trace.go:171","msg":"trace[2095684216] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"201.435768ms","start":"2026-05-11T21:56:25.18253Z","end":"2026-05-11T21:56:25.383966Z","steps":["trace[2095684216] 'process raft request' (duration: 201.157952ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:25.38417Z","caller":"traceutil/trace.go:171","msg":"trace[1192205742] linearizableReadLoop","detail":"{readStateIndex:26544; appliedIndex:26543; }","duration":"145.672505ms","start":"2026-05-11T21:56:25.238201Z","end":"2026-05-11T21:56:25.383873Z","steps":["trace[1192205742] 'read index received' (duration: 145.343428ms)","trace[1192205742] 'applied index is now lower than readState.Index' (duration: 327.517µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:25.38439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.150115ms","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-05-11T21:56:25.384504Z","caller":"traceutil/trace.go:171","msg":"trace[1815249388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25692; }","duration":"146.3467ms","start":"2026-05-11T21:56:25.238127Z","end":"2026-05-11T21:56:25.384474Z","steps":["trace[1815249388] 'agreement among raft nodes before linearized reading' (duration: 145.977412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:25.384919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.360486ms","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-05-11T21:56:25.385167Z","caller":"traceutil/trace.go:171","msg":"trace[412937545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25692; }","duration":"143.859106ms","start":"2026-05-11T21:56:25.241293Z","end":"2026-05-11T21:56:25.385152Z","steps":["trace[412937545] 'agreement among raft nodes before linearized reading' (duration: 143.036629ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:25.624597Z","caller":"traceutil/trace.go:171","msg":"trace[1561952604] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"217.753747ms","start":"2026-05-11T21:56:25.406797Z","end":"2026-05-11T21:56:25.624551Z","steps":["trace[1561952604] 'process raft request' (duration: 217.301037ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:25.624649Z","caller":"traceutil/trace.go:171","msg":"trace[423981517] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"234.69492ms","start":"2026-05-11T21:56:25.38991Z","end":"2026-05-11T21:56:25.624605Z","steps":["trace[423981517] 'process raft request' (duration: 213.166799ms)","trace[423981517] 'compare' (duration: 20.59652ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:26.124814Z","caller":"traceutil/trace.go:171","msg":"trace[844269217] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"288.541771ms","start":"2026-05-11T21:56:25.836234Z","end":"2026-05-11T21:56:26.124775Z","steps":["trace[844269217] 'process raft request' (duration: 288.261245ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:26.169481Z","caller":"traceutil/trace.go:171","msg":"trace[1406172629] linearizableReadLoop","detail":"{readStateIndex:26549; appliedIndex:26547; }","duration":"152.117233ms","start":"2026-05-11T21:56:26.017331Z","end":"2026-05-11T21:56:26.169448Z","steps":["trace[1406172629] 'read index received' (duration: 107.242844ms)","trace[1406172629] 'applied index is now lower than readState.Index' (duration: 44.872779ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:26.169827Z","caller":"traceutil/trace.go:171","msg":"trace[465498684] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"265.270503ms","start":"2026-05-11T21:56:25.904538Z","end":"2026-05-11T21:56:26.169809Z","steps":["trace[465498684] 'process raft request' (duration: 263.453075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.169973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.623034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18624"} {"level":"info","ts":"2026-05-11T21:56:26.170053Z","caller":"traceutil/trace.go:171","msg":"trace[11459954] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25697; }","duration":"152.764237ms","start":"2026-05-11T21:56:26.017265Z","end":"2026-05-11T21:56:26.17003Z","steps":["trace[11459954] 'agreement among raft nodes before linearized reading' (duration: 152.515301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.511957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.578268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:26.512405Z","caller":"traceutil/trace.go:171","msg":"trace[2002312552] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"323.462278ms","start":"2026-05-11T21:56:26.188919Z","end":"2026-05-11T21:56:26.512381Z","steps":["trace[2002312552] 'process raft request' (duration: 323.357915ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:26.512441Z","caller":"traceutil/trace.go:171","msg":"trace[915838351] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"379.709321ms","start":"2026-05-11T21:56:26.132686Z","end":"2026-05-11T21:56:26.512395Z","steps":["trace[915838351] 'process raft request' (duration: 230.567921ms)","trace[915838351] 'compare' (duration: 147.439493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:26.512503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.188896Z","time spent":"323.56383ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:26.512553Z","caller":"traceutil/trace.go:171","msg":"trace[524761061] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"378.603787ms","start":"2026-05-11T21:56:26.133884Z","end":"2026-05-11T21:56:26.512488Z","steps":["trace[524761061] 'process raft request' (duration: 378.196818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.512656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.132656Z","time spent":"379.880944ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:26.512664Z","caller":"traceutil/trace.go:171","msg":"trace[88871636] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"322.837954ms","start":"2026-05-11T21:56:26.189801Z","end":"2026-05-11T21:56:26.512639Z","steps":["trace[88871636] 'process raft request' (duration: 322.533918ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:26.512464Z","caller":"traceutil/trace.go:171","msg":"trace[463160316] linearizableReadLoop","detail":"{readStateIndex:26552; appliedIndex:26549; }","duration":"342.852432ms","start":"2026-05-11T21:56:26.169563Z","end":"2026-05-11T21:56:26.512415Z","steps":["trace[463160316] 'read index received' (duration: 193.63333ms)","trace[463160316] 'applied index is now lower than readState.Index' (duration: 149.213672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:26.51276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.133874Z","time spent":"378.789861ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1168,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:26.512759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.189785Z","time spent":"322.926006ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:26.51284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.288773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-05-11T21:56:26.512899Z","caller":"traceutil/trace.go:171","msg":"trace[1760346415] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"377.222447ms","start":"2026-05-11T21:56:26.135665Z","end":"2026-05-11T21:56:26.512888Z","steps":["trace[1760346415] 'process raft request' (duration: 376.529693ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:26.512912Z","caller":"traceutil/trace.go:171","msg":"trace[1976264470] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:25702; }","duration":"380.418606ms","start":"2026-05-11T21:56:26.132475Z","end":"2026-05-11T21:56:26.512894Z","steps":["trace[1976264470] 'agreement among raft nodes before linearized reading' (duration: 380.067538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.512962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.135644Z","time spent":"377.284589ms","remote":"127.0.0.1:56350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:26.512988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.132456Z","time spent":"380.520998ms","remote":"127.0.0.1:56028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"info","ts":"2026-05-11T21:56:26.513101Z","caller":"traceutil/trace.go:171","msg":"trace[1390912846] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"322.554958ms","start":"2026-05-11T21:56:26.19053Z","end":"2026-05-11T21:56:26.513085Z","steps":["trace[1390912846] 'process raft request' (duration: 322.059797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.51318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.190518Z","time spent":"322.62333ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:26.527414Z","caller":"traceutil/trace.go:171","msg":"trace[652046037] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"141.26244ms","start":"2026-05-11T21:56:26.386131Z","end":"2026-05-11T21:56:26.527394Z","steps":["trace[652046037] 'process raft request' (duration: 141.099087ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:26.8803Z","caller":"traceutil/trace.go:171","msg":"trace[1856044918] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"384.511003ms","start":"2026-05-11T21:56:26.495738Z","end":"2026-05-11T21:56:26.880249Z","steps":["trace[1856044918] 'process raft request' (duration: 364.2687ms)","trace[1856044918] 'compare' (duration: 19.543128ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:26.880425Z","caller":"traceutil/trace.go:171","msg":"trace[573402688] linearizableReadLoop","detail":"{readStateIndex:26559; appliedIndex:26555; }","duration":"367.613442ms","start":"2026-05-11T21:56:26.512779Z","end":"2026-05-11T21:56:26.880392Z","steps":["trace[573402688] 'read index received' (duration: 14.421268ms)","trace[573402688] 'applied index is now lower than readState.Index' (duration: 353.190674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:26.880577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.495711Z","time spent":"384.696297ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:26.880603Z","caller":"traceutil/trace.go:171","msg":"trace[747578681] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"357.639589ms","start":"2026-05-11T21:56:26.522949Z","end":"2026-05-11T21:56:26.880588Z","steps":["trace[747578681] 'process raft request' (duration: 357.361643ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:26.880594Z","caller":"traceutil/trace.go:171","msg":"trace[1309284549] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"384.728467ms","start":"2026-05-11T21:56:26.495826Z","end":"2026-05-11T21:56:26.880555Z","steps":["trace[1309284549] 'process raft request' (duration: 384.283318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.880786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.495793Z","time spent":"384.889611ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:26.880804Z","caller":"traceutil/trace.go:171","msg":"trace[2115361395] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"382.903548ms","start":"2026-05-11T21:56:26.497869Z","end":"2026-05-11T21:56:26.880772Z","steps":["trace[2115361395] 'process raft request' (duration: 382.379987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.880907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.408331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:26.880987Z","caller":"traceutil/trace.go:171","msg":"trace[1933922186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25708; }","duration":"168.521893ms","start":"2026-05-11T21:56:26.712428Z","end":"2026-05-11T21:56:26.88095Z","steps":["trace[1933922186] 'agreement among raft nodes before linearized reading' (duration: 168.401661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.880992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.497854Z","time spent":"383.047221ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:26.880998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.072372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T21:56:26.881059Z","caller":"traceutil/trace.go:171","msg":"trace[1761347919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25708; }","duration":"693.176464ms","start":"2026-05-11T21:56:26.187865Z","end":"2026-05-11T21:56:26.881041Z","steps":["trace[1761347919] 'agreement among raft nodes before linearized reading' (duration: 692.929809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.881026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.61878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18624"} {"level":"warn","ts":"2026-05-11T21:56:26.881057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.379363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:208"} {"level":"warn","ts":"2026-05-11T21:56:26.8811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.187853Z","time spent":"693.235725ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T21:56:26.881124Z","caller":"traceutil/trace.go:171","msg":"trace[606907932] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25708; }","duration":"705.798045ms","start":"2026-05-11T21:56:26.175302Z","end":"2026-05-11T21:56:26.8811Z","steps":["trace[606907932] 'agreement among raft nodes before linearized reading' (duration: 705.476368ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:26.881168Z","caller":"traceutil/trace.go:171","msg":"trace[2111255296] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:25708; }","duration":"364.564676ms","start":"2026-05-11T21:56:26.516575Z","end":"2026-05-11T21:56:26.88114Z","steps":["trace[2111255296] 'agreement among raft nodes before linearized reading' (duration: 364.23989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:26.881178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.17528Z","time spent":"705.887366ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18647,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-11T21:56:26.881224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.516548Z","time spent":"364.664649ms","remote":"127.0.0.1:56028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-05-11T21:56:26.881507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:26.522924Z","time spent":"358.505966ms","remote":"127.0.0.1:56316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7836,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:27.113065Z","caller":"traceutil/trace.go:171","msg":"trace[878226181] linearizableReadLoop","detail":"{readStateIndex:26562; appliedIndex:26561; }","duration":"163.18913ms","start":"2026-05-11T21:56:26.949853Z","end":"2026-05-11T21:56:27.113042Z","steps":["trace[878226181] 'read index received' (duration: 125.043964ms)","trace[878226181] 'applied index is now lower than readState.Index' (duration: 38.141206ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:27.113196Z","caller":"traceutil/trace.go:171","msg":"trace[977835860] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"223.329096ms","start":"2026-05-11T21:56:26.889851Z","end":"2026-05-11T21:56:27.11318Z","steps":["trace[977835860] 'process raft request' (duration: 185.071839ms)","trace[977835860] 'compare' (duration: 37.983681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:27.113518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.718881ms","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":"info","ts":"2026-05-11T21:56:27.113668Z","caller":"traceutil/trace.go:171","msg":"trace[157573875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25710; }","duration":"163.897285ms","start":"2026-05-11T21:56:26.949735Z","end":"2026-05-11T21:56:27.113632Z","steps":["trace[157573875] 'agreement among raft nodes before linearized reading' (duration: 163.518917ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:27.124578Z","caller":"traceutil/trace.go:171","msg":"trace[1546709272] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"129.630382ms","start":"2026-05-11T21:56:26.994923Z","end":"2026-05-11T21:56:27.124553Z","steps":["trace[1546709272] 'process raft request' (duration: 129.488359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:27.125091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.076273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277951"} {"level":"info","ts":"2026-05-11T21:56:27.125175Z","caller":"traceutil/trace.go:171","msg":"trace[1738985433] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25711; }","duration":"102.207775ms","start":"2026-05-11T21:56:27.022947Z","end":"2026-05-11T21:56:27.125155Z","steps":["trace[1738985433] 'agreement among raft nodes before linearized reading' (duration: 101.670674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:27.358966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.806143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:27.359122Z","caller":"traceutil/trace.go:171","msg":"trace[7414019] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25712; }","duration":"106.089179ms","start":"2026-05-11T21:56:27.253006Z","end":"2026-05-11T21:56:27.359095Z","steps":["trace[7414019] 'count revisions from in-memory index tree' (duration: 105.715251ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:27.829369Z","caller":"traceutil/trace.go:171","msg":"trace[1438884032] linearizableReadLoop","detail":"{readStateIndex:26568; appliedIndex:26567; }","duration":"200.435767ms","start":"2026-05-11T21:56:27.628897Z","end":"2026-05-11T21:56:27.829332Z","steps":["trace[1438884032] 'read index received' (duration: 200.177932ms)","trace[1438884032] 'applied index is now lower than readState.Index' (duration: 256.905µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:27.829531Z","caller":"traceutil/trace.go:171","msg":"trace[2007181318] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"239.961452ms","start":"2026-05-11T21:56:27.589558Z","end":"2026-05-11T21:56:27.82952Z","steps":["trace[2007181318] 'process raft request' (duration: 239.500282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:27.829988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.06335ms","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-05-11T21:56:27.830095Z","caller":"traceutil/trace.go:171","msg":"trace[876853935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25715; }","duration":"201.244854ms","start":"2026-05-11T21:56:27.628827Z","end":"2026-05-11T21:56:27.830072Z","steps":["trace[876853935] 'agreement among raft nodes before linearized reading' (duration: 200.873106ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:28.710014Z","caller":"traceutil/trace.go:171","msg":"trace[1521561925] linearizableReadLoop","detail":"{readStateIndex:26573; appliedIndex:26571; }","duration":"129.141771ms","start":"2026-05-11T21:56:28.580848Z","end":"2026-05-11T21:56:28.709989Z","steps":["trace[1521561925] 'read index received' (duration: 101.452289ms)","trace[1521561925] 'applied index is now lower than readState.Index' (duration: 27.688022ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:28.710159Z","caller":"traceutil/trace.go:171","msg":"trace[183005540] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"189.094684ms","start":"2026-05-11T21:56:28.521049Z","end":"2026-05-11T21:56:28.710143Z","steps":["trace[183005540] 'process raft request' (duration: 161.243508ms)","trace[183005540] 'compare' (duration: 27.479408ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:28.710311Z","caller":"traceutil/trace.go:171","msg":"trace[162473327] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"189.179965ms","start":"2026-05-11T21:56:28.521122Z","end":"2026-05-11T21:56:28.710302Z","steps":["trace[162473327] 'process raft request' (duration: 188.810837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:28.71041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.638525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:28.710512Z","caller":"traceutil/trace.go:171","msg":"trace[27672624] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25720; }","duration":"110.801449ms","start":"2026-05-11T21:56:28.599685Z","end":"2026-05-11T21:56:28.710486Z","steps":["trace[27672624] 'agreement among raft nodes before linearized reading' (duration: 110.635765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:28.710421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.555561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:28.710604Z","caller":"traceutil/trace.go:171","msg":"trace[1067408050] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25720; }","duration":"129.805676ms","start":"2026-05-11T21:56:28.580781Z","end":"2026-05-11T21:56:28.710586Z","steps":["trace[1067408050] 'agreement among raft nodes before linearized reading' (duration: 129.554411ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.052797Z","caller":"traceutil/trace.go:171","msg":"trace[915675089] linearizableReadLoop","detail":"{readStateIndex:26574; appliedIndex:26573; }","duration":"160.650436ms","start":"2026-05-11T21:56:28.892117Z","end":"2026-05-11T21:56:29.052767Z","steps":["trace[915675089] 'read index received' (duration: 160.452271ms)","trace[915675089] 'applied index is now lower than readState.Index' (duration: 196.885µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:29.053229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.067455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-11T21:56:29.053354Z","caller":"traceutil/trace.go:171","msg":"trace[807848796] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25721; }","duration":"161.2873ms","start":"2026-05-11T21:56:28.892039Z","end":"2026-05-11T21:56:29.053326Z","steps":["trace[807848796] 'agreement among raft nodes before linearized reading' (duration: 160.85383ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.053449Z","caller":"traceutil/trace.go:171","msg":"trace[105216] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"164.556439ms","start":"2026-05-11T21:56:28.888847Z","end":"2026-05-11T21:56:29.053403Z","steps":["trace[105216] 'process raft request' (duration: 163.732591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:29.060441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.963393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T21:56:29.0605Z","caller":"traceutil/trace.go:171","msg":"trace[746619956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25723; }","duration":"154.068825ms","start":"2026-05-11T21:56:28.906407Z","end":"2026-05-11T21:56:29.060476Z","steps":["trace[746619956] 'agreement among raft nodes before linearized reading' (duration: 153.860861ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.060761Z","caller":"traceutil/trace.go:171","msg":"trace[1912338854] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"166.642553ms","start":"2026-05-11T21:56:28.894093Z","end":"2026-05-11T21:56:29.060736Z","steps":["trace[1912338854] 'process raft request' (duration: 166.131843ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.060903Z","caller":"traceutil/trace.go:171","msg":"trace[1119637245] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"167.810958ms","start":"2026-05-11T21:56:28.893079Z","end":"2026-05-11T21:56:29.06089Z","steps":["trace[1119637245] 'process raft request' (duration: 166.999851ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.193962Z","caller":"traceutil/trace.go:171","msg":"trace[256345464] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"129.805705ms","start":"2026-05-11T21:56:29.064118Z","end":"2026-05-11T21:56:29.193924Z","steps":["trace[256345464] 'process raft request' (duration: 128.583339ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.346118Z","caller":"traceutil/trace.go:171","msg":"trace[1632400917] linearizableReadLoop","detail":"{readStateIndex:26578; appliedIndex:26576; }","duration":"214.17469ms","start":"2026-05-11T21:56:29.131909Z","end":"2026-05-11T21:56:29.346084Z","steps":["trace[1632400917] 'read index received' (duration: 60.80904ms)","trace[1632400917] 'applied index is now lower than readState.Index' (duration: 153.36386ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:29.346165Z","caller":"traceutil/trace.go:171","msg":"trace[331682059] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"214.592848ms","start":"2026-05-11T21:56:29.131551Z","end":"2026-05-11T21:56:29.346143Z","steps":["trace[331682059] 'process raft request' (duration: 214.288432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:29.346429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.490326ms","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":"info","ts":"2026-05-11T21:56:29.346476Z","caller":"traceutil/trace.go:171","msg":"trace[885476753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25725; }","duration":"214.596529ms","start":"2026-05-11T21:56:29.131865Z","end":"2026-05-11T21:56:29.346462Z","steps":["trace[885476753] 'agreement among raft nodes before linearized reading' (duration: 214.384544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:29.575437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.018099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T21:56:29.575544Z","caller":"traceutil/trace.go:171","msg":"trace[1303951939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25726; }","duration":"100.205943ms","start":"2026-05-11T21:56:29.475313Z","end":"2026-05-11T21:56:29.575519Z","steps":["trace[1303951939] 'range keys from in-memory index tree' (duration: 99.749964ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.729304Z","caller":"traceutil/trace.go:171","msg":"trace[1042730139] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"149.805883ms","start":"2026-05-11T21:56:29.579473Z","end":"2026-05-11T21:56:29.729278Z","steps":["trace[1042730139] 'process raft request' (duration: 149.608859ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:29.799107Z","caller":"traceutil/trace.go:171","msg":"trace[1320129429] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"218.941282ms","start":"2026-05-11T21:56:29.580134Z","end":"2026-05-11T21:56:29.799075Z","steps":["trace[1320129429] 'process raft request' (duration: 200.663801ms)","trace[1320129429] 'compare' (duration: 17.73218ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:38.503286Z","caller":"traceutil/trace.go:171","msg":"trace[1358617927] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"304.222866ms","start":"2026-05-11T21:56:38.199024Z","end":"2026-05-11T21:56:38.503247Z","steps":["trace[1358617927] 'process raft request' (duration: 304.002492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.503509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.198994Z","time spent":"304.39621ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:38.51462Z","caller":"traceutil/trace.go:171","msg":"trace[35034063] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"229.153991ms","start":"2026-05-11T21:56:38.285442Z","end":"2026-05-11T21:56:38.514596Z","steps":["trace[35034063] 'process raft request' (duration: 229.013568ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:38.87589Z","caller":"traceutil/trace.go:171","msg":"trace[1332901533] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"317.646833ms","start":"2026-05-11T21:56:38.558208Z","end":"2026-05-11T21:56:38.875855Z","steps":["trace[1332901533] 'process raft request' (duration: 251.301394ms)","trace[1332901533] 'compare' (duration: 65.887659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:38.87604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.558189Z","time spent":"317.765425ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:39.19147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.506634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:39.191921Z","caller":"traceutil/trace.go:171","msg":"trace[1638302474] linearizableReadLoop","detail":"{readStateIndex:26649; appliedIndex:26647; }","duration":"110.069734ms","start":"2026-05-11T21:56:39.081819Z","end":"2026-05-11T21:56:39.191889Z","steps":["trace[1638302474] 'read index received' (duration: 43.101µs)","trace[1638302474] 'applied index is now lower than readState.Index' (duration: 110.024813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:39.192107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.277518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:39.192165Z","caller":"traceutil/trace.go:171","msg":"trace[1417402618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25794; }","duration":"110.33919ms","start":"2026-05-11T21:56:39.08181Z","end":"2026-05-11T21:56:39.192149Z","steps":["trace[1417402618] 'agreement among raft nodes before linearized reading' (duration: 110.183326ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:39.192206Z","caller":"traceutil/trace.go:171","msg":"trace[1117162830] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"431.618381ms","start":"2026-05-11T21:56:38.760543Z","end":"2026-05-11T21:56:39.192162Z","steps":["trace[1117162830] 'process raft request' (duration: 284.022944ms)","trace[1117162830] 'compare' (duration: 146.097804ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:39.192384Z","caller":"traceutil/trace.go:171","msg":"trace[1798708566] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"430.507338ms","start":"2026-05-11T21:56:38.761838Z","end":"2026-05-11T21:56:39.192345Z","steps":["trace[1798708566] 'process raft request' (duration: 429.812083ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:39.192572Z","caller":"traceutil/trace.go:171","msg":"trace[1530384485] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"100.906978ms","start":"2026-05-11T21:56:39.091654Z","end":"2026-05-11T21:56:39.192561Z","steps":["trace[1530384485] 'process raft request' (duration: 100.174313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:39.192677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.761812Z","time spent":"430.697951ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:39.192418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.760505Z","time spent":"431.793875ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:39.76242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.593893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:39.762714Z","caller":"traceutil/trace.go:171","msg":"trace[470494915] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"669.498318ms","start":"2026-05-11T21:56:39.093178Z","end":"2026-05-11T21:56:39.762676Z","steps":["trace[470494915] 'process raft request' (duration: 669.362915ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:39.762756Z","caller":"traceutil/trace.go:171","msg":"trace[1744436623] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"378.041745ms","start":"2026-05-11T21:56:39.38469Z","end":"2026-05-11T21:56:39.762732Z","steps":["trace[1744436623] 'process raft request' (duration: 377.970564ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:39.762753Z","caller":"traceutil/trace.go:171","msg":"trace[81942919] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"669.771394ms","start":"2026-05-11T21:56:39.092934Z","end":"2026-05-11T21:56:39.762706Z","steps":["trace[81942919] 'process raft request' (duration: 386.772232ms)","trace[81942919] 'compare' (duration: 281.863107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:39.76285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.384649Z","time spent":"378.157947ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:39.762845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.156202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T21:56:39.762895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.093149Z","time spent":"669.647901ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:39.762913Z","caller":"traceutil/trace.go:171","msg":"trace[1703671792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25798; }","duration":"569.285916ms","start":"2026-05-11T21:56:39.193615Z","end":"2026-05-11T21:56:39.762901Z","steps":["trace[1703671792] 'agreement among raft nodes before linearized reading' (duration: 569.165853ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:39.762742Z","caller":"traceutil/trace.go:171","msg":"trace[120111962] linearizableReadLoop","detail":"{readStateIndex:26652; appliedIndex:26650; }","duration":"569.041279ms","start":"2026-05-11T21:56:39.193681Z","end":"2026-05-11T21:56:39.762723Z","steps":["trace[120111962] 'read index received' (duration: 286.035737ms)","trace[120111962] 'applied index is now lower than readState.Index' (duration: 283.004062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:39.762998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.092897Z","time spent":"669.971078ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:39.762949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.193592Z","time spent":"569.349097ms","remote":"127.0.0.1:55840","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-05-11T21:56:39.763298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.209232ms","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:6"} {"level":"warn","ts":"2026-05-11T21:56:39.763329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.504709ms","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":"info","ts":"2026-05-11T21:56:39.763371Z","caller":"traceutil/trace.go:171","msg":"trace[2063733848] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25798; }","duration":"157.336984ms","start":"2026-05-11T21:56:39.606009Z","end":"2026-05-11T21:56:39.763346Z","steps":["trace[2063733848] 'agreement among raft nodes before linearized reading' (duration: 157.205961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:39.763361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.405995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:39.763375Z","caller":"traceutil/trace.go:171","msg":"trace[2080577756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25798; }","duration":"352.59239ms","start":"2026-05-11T21:56:39.410768Z","end":"2026-05-11T21:56:39.76336Z","steps":["trace[2080577756] 'agreement among raft nodes before linearized reading' (duration: 352.422217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:39.763465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.410747Z","time spent":"352.709623ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T21:56:39.763434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.410156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T21:56:39.763477Z","caller":"traceutil/trace.go:171","msg":"trace[859881887] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25798; }","duration":"546.62272ms","start":"2026-05-11T21:56:39.216828Z","end":"2026-05-11T21:56:39.76345Z","steps":["trace[859881887] 'agreement among raft nodes before linearized reading' (duration: 546.360735ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:39.763546Z","caller":"traceutil/trace.go:171","msg":"trace[1934766322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25798; }","duration":"525.580181ms","start":"2026-05-11T21:56:39.23794Z","end":"2026-05-11T21:56:39.76352Z","steps":["trace[1934766322] 'agreement among raft nodes before linearized reading' (duration: 525.262294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:39.763562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.216801Z","time spent":"546.744862ms","remote":"127.0.0.1:51886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-11T21:56:39.763604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.237914Z","time spent":"525.680282ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T21:56:40.339222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.146181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:40.339355Z","caller":"traceutil/trace.go:171","msg":"trace[64432048] linearizableReadLoop","detail":"{readStateIndex:26655; appliedIndex:26653; }","duration":"549.979132ms","start":"2026-05-11T21:56:39.789354Z","end":"2026-05-11T21:56:40.339333Z","steps":["trace[64432048] 'read index received' (duration: 18.986767ms)","trace[64432048] 'applied index is now lower than readState.Index' (duration: 530.990595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:40.339622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.146156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:56:40.339651Z","caller":"traceutil/trace.go:171","msg":"trace[1711386031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25800; }","duration":"485.217217ms","start":"2026-05-11T21:56:39.854423Z","end":"2026-05-11T21:56:40.33964Z","steps":["trace[1711386031] 'agreement among raft nodes before linearized reading' (duration: 485.050674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:40.339694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.291309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T21:56:40.339809Z","caller":"traceutil/trace.go:171","msg":"trace[1129163024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25800; }","duration":"550.489943ms","start":"2026-05-11T21:56:39.789291Z","end":"2026-05-11T21:56:40.339781Z","steps":["trace[1129163024] 'agreement among raft nodes before linearized reading' (duration: 550.127126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:40.339868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.789264Z","time spent":"550.587446ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T21:56:40.339869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.854402Z","time spent":"485.452853ms","remote":"127.0.0.1:56108","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-05-11T21:56:40.339923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.019321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:56:40.340015Z","caller":"traceutil/trace.go:171","msg":"trace[949144143] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25800; }","duration":"260.196245ms","start":"2026-05-11T21:56:40.079796Z","end":"2026-05-11T21:56:40.339993Z","steps":["trace[949144143] 'agreement among raft nodes before linearized reading' (duration: 259.98723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:40.340014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.2218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T21:56:40.340071Z","caller":"traceutil/trace.go:171","msg":"trace[1426761649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25800; }","duration":"437.300162ms","start":"2026-05-11T21:56:39.902757Z","end":"2026-05-11T21:56:40.340057Z","steps":["trace[1426761649] 'agreement among raft nodes before linearized reading' (duration: 437.068907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:40.340116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.902748Z","time spent":"437.355073ms","remote":"127.0.0.1:56108","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-05-11T21:56:40.340886Z","caller":"traceutil/trace.go:171","msg":"trace[2010783668] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"567.950276ms","start":"2026-05-11T21:56:39.77139Z","end":"2026-05-11T21:56:40.339341Z","steps":["trace[2010783668] 'process raft request' (duration: 279.530379ms)","trace[2010783668] 'compare' (duration: 287.962977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:40.341063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:39.771369Z","time spent":"569.596031ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:40.676417Z","caller":"traceutil/trace.go:171","msg":"trace[876642358] linearizableReadLoop","detail":"{readStateIndex:26658; appliedIndex:26655; }","duration":"330.210732ms","start":"2026-05-11T21:56:40.34617Z","end":"2026-05-11T21:56:40.676381Z","steps":["trace[876642358] 'read index received' (duration: 304.40415ms)","trace[876642358] 'applied index is now lower than readState.Index' (duration: 25.804422ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:40.676441Z","caller":"traceutil/trace.go:171","msg":"trace[441180599] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"331.05056ms","start":"2026-05-11T21:56:40.345349Z","end":"2026-05-11T21:56:40.6764Z","steps":["trace[441180599] 'process raft request' (duration: 305.269339ms)","trace[441180599] 'compare' (duration: 25.135848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:40.676657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:40.345326Z","time spent":"331.216393ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:40.676765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.553749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18624"} {"level":"info","ts":"2026-05-11T21:56:40.676743Z","caller":"traceutil/trace.go:171","msg":"trace[277675807] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"330.605891ms","start":"2026-05-11T21:56:40.3461Z","end":"2026-05-11T21:56:40.676706Z","steps":["trace[277675807] 'process raft request' (duration: 330.142651ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:40.676824Z","caller":"traceutil/trace.go:171","msg":"trace[1713668048] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25804; }","duration":"330.661642ms","start":"2026-05-11T21:56:40.346149Z","end":"2026-05-11T21:56:40.67681Z","steps":["trace[1713668048] 'agreement among raft nodes before linearized reading' (duration: 330.377016ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:40.676836Z","caller":"traceutil/trace.go:171","msg":"trace[1480236776] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"330.839055ms","start":"2026-05-11T21:56:40.345977Z","end":"2026-05-11T21:56:40.676816Z","steps":["trace[1480236776] 'process raft request' (duration: 330.097539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:40.676873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:40.346136Z","time spent":"330.722083ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18647,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-11T21:56:40.676934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:40.34596Z","time spent":"330.919497ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:40.676934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:40.346087Z","time spent":"330.747033ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:40.67692Z","caller":"traceutil/trace.go:171","msg":"trace[1139483368] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"155.070226ms","start":"2026-05-11T21:56:40.52181Z","end":"2026-05-11T21:56:40.67688Z","steps":["trace[1139483368] 'process raft request' (duration: 154.497474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:41.507796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.479612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:41.507893Z","caller":"traceutil/trace.go:171","msg":"trace[1981086879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25805; }","duration":"426.635415ms","start":"2026-05-11T21:56:41.081231Z","end":"2026-05-11T21:56:41.507866Z","steps":["trace[1981086879] 'range keys from in-memory index tree' (duration: 426.38033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:41.507954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:41.081203Z","time spent":"426.737537ms","remote":"127.0.0.1:55824","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-05-11T21:56:41.509563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.706509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:41.509651Z","caller":"traceutil/trace.go:171","msg":"trace[2136705400] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"310.741305ms","start":"2026-05-11T21:56:41.198894Z","end":"2026-05-11T21:56:41.509635Z","steps":["trace[2136705400] 'process raft request' (duration: 205.879163ms)","trace[2136705400] 'compare' (duration: 104.052385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:41.509707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:41.198866Z","time spent":"310.813057ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:41.520982Z","caller":"traceutil/trace.go:171","msg":"trace[805315491] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"321.031926ms","start":"2026-05-11T21:56:41.199934Z","end":"2026-05-11T21:56:41.520966Z","steps":["trace[805315491] 'process raft request' (duration: 320.947594ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:41.521008Z","caller":"traceutil/trace.go:171","msg":"trace[414082532] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"321.166538ms","start":"2026-05-11T21:56:41.199795Z","end":"2026-05-11T21:56:41.520962Z","steps":["trace[414082532] 'process raft request' (duration: 320.921243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:41.521055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:41.199927Z","time spent":"321.094417ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:41.521015Z","caller":"traceutil/trace.go:171","msg":"trace[1183747117] linearizableReadLoop","detail":"{readStateIndex:26663; appliedIndex:26660; }","duration":"261.41222ms","start":"2026-05-11T21:56:41.259573Z","end":"2026-05-11T21:56:41.520985Z","steps":["trace[1183747117] 'read index received' (duration: 145.222265ms)","trace[1183747117] 'applied index is now lower than readState.Index' (duration: 116.144664ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:41.521174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:41.199761Z","time spent":"321.315211ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:41.521236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.621035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T21:56:41.521292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.644397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:41.521341Z","caller":"traceutil/trace.go:171","msg":"trace[1989273259] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25808; }","duration":"261.801059ms","start":"2026-05-11T21:56:41.259513Z","end":"2026-05-11T21:56:41.521315Z","steps":["trace[1989273259] 'agreement among raft nodes before linearized reading' (duration: 261.605415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:41.521334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.970646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-11T21:56:41.521389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.026156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"} {"level":"info","ts":"2026-05-11T21:56:41.521469Z","caller":"traceutil/trace.go:171","msg":"trace[836016620] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25808; }","duration":"192.231091ms","start":"2026-05-11T21:56:41.329225Z","end":"2026-05-11T21:56:41.521456Z","steps":["trace[836016620] 'agreement among raft nodes before linearized reading' (duration: 191.926484ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:41.521388Z","caller":"traceutil/trace.go:171","msg":"trace[2107267184] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25808; }","duration":"103.78994ms","start":"2026-05-11T21:56:41.417577Z","end":"2026-05-11T21:56:41.521367Z","steps":["trace[2107267184] 'agreement among raft nodes before linearized reading' (duration: 103.639997ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:41.521404Z","caller":"traceutil/trace.go:171","msg":"trace[1985132579] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25808; }","duration":"185.096238ms","start":"2026-05-11T21:56:41.336292Z","end":"2026-05-11T21:56:41.521388Z","steps":["trace[1985132579] 'agreement among raft nodes before linearized reading' (duration: 184.961266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:47.680321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.301502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-11T21:56:47.680772Z","caller":"traceutil/trace.go:171","msg":"trace[1422834583] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"140.958104ms","start":"2026-05-11T21:56:47.539789Z","end":"2026-05-11T21:56:47.680747Z","steps":["trace[1422834583] 'process raft request' (duration: 140.827962ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:47.68079Z","caller":"traceutil/trace.go:171","msg":"trace[1273560117] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"147.416852ms","start":"2026-05-11T21:56:47.533313Z","end":"2026-05-11T21:56:47.680729Z","steps":["trace[1273560117] 'process raft request' (duration: 147.144276ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:47.681038Z","caller":"traceutil/trace.go:171","msg":"trace[404490686] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"138.493461ms","start":"2026-05-11T21:56:47.542492Z","end":"2026-05-11T21:56:47.680985Z","steps":["trace[404490686] 'process raft request' (duration: 138.209936ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:47.981868Z","caller":"traceutil/trace.go:171","msg":"trace[838699942] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"183.690629ms","start":"2026-05-11T21:56:47.798151Z","end":"2026-05-11T21:56:47.981842Z","steps":["trace[838699942] 'process raft request' (duration: 168.3114ms)","trace[838699942] 'compare' (duration: 15.098043ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:47.98219Z","caller":"traceutil/trace.go:171","msg":"trace[1907714061] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"183.317711ms","start":"2026-05-11T21:56:47.798834Z","end":"2026-05-11T21:56:47.982151Z","steps":["trace[1907714061] 'process raft request' (duration: 182.958283ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:47.982259Z","caller":"traceutil/trace.go:171","msg":"trace[2086197378] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"120.073979ms","start":"2026-05-11T21:56:47.862166Z","end":"2026-05-11T21:56:47.98224Z","steps":["trace[2086197378] 'process raft request' (duration: 119.891105ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:47.982232Z","caller":"traceutil/trace.go:171","msg":"trace[1089962556] linearizableReadLoop","detail":"{readStateIndex:26774; appliedIndex:26771; }","duration":"136.673573ms","start":"2026-05-11T21:56:47.845526Z","end":"2026-05-11T21:56:47.982199Z","steps":["trace[1089962556] 'read index received' (duration: 37.767657ms)","trace[1089962556] 'applied index is now lower than readState.Index' (duration: 98.904656ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:47.982519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.97859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T21:56:47.982548Z","caller":"traceutil/trace.go:171","msg":"trace[1296358267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25917; }","duration":"137.069542ms","start":"2026-05-11T21:56:47.845471Z","end":"2026-05-11T21:56:47.98254Z","steps":["trace[1296358267] 'agreement among raft nodes before linearized reading' (duration: 136.878998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:47.982556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.897206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d007d60e-8127-42d5-b42a-ea2035a5b8fb\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-11T21:56:47.983205Z","caller":"traceutil/trace.go:171","msg":"trace[106282625] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d007d60e-8127-42d5-b42a-ea2035a5b8fb; range_end:; response_count:1; response_revision:25917; }","duration":"114.001469ms","start":"2026-05-11T21:56:47.868586Z","end":"2026-05-11T21:56:47.982587Z","steps":["trace[106282625] 'agreement among raft nodes before linearized reading' (duration: 113.780454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.253563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.014025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:48.253701Z","caller":"traceutil/trace.go:171","msg":"trace[1764806736] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"266.99604ms","start":"2026-05-11T21:56:47.98668Z","end":"2026-05-11T21:56:48.253676Z","steps":["trace[1764806736] 'process raft request' (duration: 140.765381ms)","trace[1764806736] 'compare' (duration: 125.626066ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:48.516533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.217377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:48.516668Z","caller":"traceutil/trace.go:171","msg":"trace[374391449] linearizableReadLoop","detail":"{readStateIndex:26777; appliedIndex:26775; }","duration":"435.32307ms","start":"2026-05-11T21:56:48.081321Z","end":"2026-05-11T21:56:48.516644Z","steps":["trace[374391449] 'read index received' (duration: 46.138857ms)","trace[374391449] 'applied index is now lower than readState.Index' (duration: 389.182673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:48.516941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.606296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:48.517035Z","caller":"traceutil/trace.go:171","msg":"trace[529818170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25919; }","duration":"435.758939ms","start":"2026-05-11T21:56:48.081252Z","end":"2026-05-11T21:56:48.517011Z","steps":["trace[529818170] 'agreement among raft nodes before linearized reading' (duration: 435.533364ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:48.517017Z","caller":"traceutil/trace.go:171","msg":"trace[1565550804] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"527.348699ms","start":"2026-05-11T21:56:47.989638Z","end":"2026-05-11T21:56:48.516987Z","steps":["trace[1565550804] 'process raft request' (duration: 394.543658ms)","trace[1565550804] 'compare' (duration: 132.060004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:48.5171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:48.08123Z","time spent":"435.858932ms","remote":"127.0.0.1:55824","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-05-11T21:56:48.517179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:47.989605Z","time spent":"527.493021ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:48.517046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.510518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:56:48.517239Z","caller":"traceutil/trace.go:171","msg":"trace[739526739] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25919; }","duration":"156.742302ms","start":"2026-05-11T21:56:48.360471Z","end":"2026-05-11T21:56:48.517213Z","steps":["trace[739526739] 'agreement among raft nodes before linearized reading' (duration: 156.519828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.517514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.365417ms","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":"info","ts":"2026-05-11T21:56:48.518585Z","caller":"traceutil/trace.go:171","msg":"trace[630281329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25919; }","duration":"136.540191ms","start":"2026-05-11T21:56:48.381062Z","end":"2026-05-11T21:56:48.517603Z","steps":["trace[630281329] 'agreement among raft nodes before linearized reading' (duration: 135.968459ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:48.871267Z","caller":"traceutil/trace.go:171","msg":"trace[285382396] linearizableReadLoop","detail":"{readStateIndex:26778; appliedIndex:26777; }","duration":"343.466976ms","start":"2026-05-11T21:56:48.527769Z","end":"2026-05-11T21:56:48.871236Z","steps":["trace[285382396] 'read index received' (duration: 343.18934ms)","trace[285382396] 'applied index is now lower than readState.Index' (duration: 276.066µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:48.871371Z","caller":"traceutil/trace.go:171","msg":"trace[896027778] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"347.112953ms","start":"2026-05-11T21:56:48.524214Z","end":"2026-05-11T21:56:48.871327Z","steps":["trace[896027778] 'process raft request' (duration: 346.789546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.871479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.67539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-05-11T21:56:48.871536Z","caller":"traceutil/trace.go:171","msg":"trace[1027631905] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25920; }","duration":"343.775532ms","start":"2026-05-11T21:56:48.527748Z","end":"2026-05-11T21:56:48.871524Z","steps":["trace[1027631905] 'agreement among raft nodes before linearized reading' (duration: 343.639999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.871548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:48.524192Z","time spent":"347.256467ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:48.871583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:48.527741Z","time spent":"343.829733ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1281,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"warn","ts":"2026-05-11T21:56:48.872388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.036525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:56:48.872465Z","caller":"traceutil/trace.go:171","msg":"trace[367447181] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"164.617431ms","start":"2026-05-11T21:56:48.707827Z","end":"2026-05-11T21:56:48.872445Z","steps":["trace[367447181] 'process raft request' (duration: 163.982446ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:48.872477Z","caller":"traceutil/trace.go:171","msg":"trace[2090528069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25921; }","duration":"155.155579ms","start":"2026-05-11T21:56:48.717303Z","end":"2026-05-11T21:56:48.872459Z","steps":["trace[2090528069] 'agreement among raft nodes before linearized reading' (duration: 154.961934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.872501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.322913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T21:56:48.872423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.809581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d007d60e-8127-42d5-b42a-ea2035a5b8fb\" ","response":"range_response_count:1 size:3761"} {"level":"warn","ts":"2026-05-11T21:56:48.872627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.492387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:48.872669Z","caller":"traceutil/trace.go:171","msg":"trace[927993098] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25921; }","duration":"178.59169ms","start":"2026-05-11T21:56:48.694069Z","end":"2026-05-11T21:56:48.87266Z","steps":["trace[927993098] 'agreement among raft nodes before linearized reading' (duration: 178.523399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.872689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.74119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T21:56:48.872732Z","caller":"traceutil/trace.go:171","msg":"trace[850953853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25921; }","duration":"154.816691ms","start":"2026-05-11T21:56:48.717905Z","end":"2026-05-11T21:56:48.872721Z","steps":["trace[850953853] 'agreement among raft nodes before linearized reading' (duration: 154.717199ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:48.872641Z","caller":"traceutil/trace.go:171","msg":"trace[1939928302] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d007d60e-8127-42d5-b42a-ea2035a5b8fb; range_end:; response_count:1; response_revision:25921; }","duration":"328.114628ms","start":"2026-05-11T21:56:48.544501Z","end":"2026-05-11T21:56:48.872615Z","steps":["trace[1939928302] 'agreement among raft nodes before linearized reading' (duration: 327.607197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.872845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:48.544483Z","time spent":"328.345983ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d007d60e-8127-42d5-b42a-ea2035a5b8fb\" "} {"level":"warn","ts":"2026-05-11T21:56:48.872977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.245725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T21:56:48.873022Z","caller":"traceutil/trace.go:171","msg":"trace[1616655117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25921; }","duration":"158.341437ms","start":"2026-05-11T21:56:48.714671Z","end":"2026-05-11T21:56:48.873012Z","steps":["trace[1616655117] 'agreement among raft nodes before linearized reading' (duration: 158.188223ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:48.872555Z","caller":"traceutil/trace.go:171","msg":"trace[369685437] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25921; }","duration":"127.398515ms","start":"2026-05-11T21:56:48.745139Z","end":"2026-05-11T21:56:48.872538Z","steps":["trace[369685437] 'agreement among raft nodes before linearized reading' (duration: 127.292532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.873309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.149693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:56:48.873406Z","caller":"traceutil/trace.go:171","msg":"trace[550203328] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25921; }","duration":"310.326037ms","start":"2026-05-11T21:56:48.563037Z","end":"2026-05-11T21:56:48.873363Z","steps":["trace[550203328] 'agreement among raft nodes before linearized reading' (duration: 310.174124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:48.873511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:48.563008Z","time spent":"310.48969ms","remote":"127.0.0.1:56238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-11T21:56:51.149757Z","caller":"traceutil/trace.go:171","msg":"trace[603866830] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"137.485771ms","start":"2026-05-11T21:56:51.012221Z","end":"2026-05-11T21:56:51.149707Z","steps":["trace[603866830] 'process raft request' (duration: 137.242896ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:57.292164Z","caller":"traceutil/trace.go:171","msg":"trace[1248456687] linearizableReadLoop","detail":"{readStateIndex:26864; appliedIndex:26863; }","duration":"383.070323ms","start":"2026-05-11T21:56:56.909065Z","end":"2026-05-11T21:56:57.292136Z","steps":["trace[1248456687] 'read index received' (duration: 382.840768ms)","trace[1248456687] 'applied index is now lower than readState.Index' (duration: 228.134µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:57.292224Z","caller":"traceutil/trace.go:171","msg":"trace[384702283] transaction","detail":"{read_only:false; response_revision:26004; number_of_response:1; }","duration":"391.909461ms","start":"2026-05-11T21:56:56.900263Z","end":"2026-05-11T21:56:57.292173Z","steps":["trace[384702283] 'process raft request' (duration: 391.654146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.292424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:56.900226Z","time spent":"392.079865ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:57.292609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.505261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:56:57.292714Z","caller":"traceutil/trace.go:171","msg":"trace[1330173850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26004; }","duration":"383.663065ms","start":"2026-05-11T21:56:56.909025Z","end":"2026-05-11T21:56:57.292688Z","steps":["trace[1330173850] 'agreement among raft nodes before linearized reading' (duration: 383.267457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.292767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:56.909009Z","time spent":"383.747707ms","remote":"127.0.0.1:56108","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-05-11T21:56:57.316166Z","caller":"traceutil/trace.go:171","msg":"trace[1035026122] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"145.008892ms","start":"2026-05-11T21:56:57.171139Z","end":"2026-05-11T21:56:57.316148Z","steps":["trace[1035026122] 'process raft request' (duration: 143.596611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.316715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.591618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T21:56:57.316738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.834174ms","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":"info","ts":"2026-05-11T21:56:57.31676Z","caller":"traceutil/trace.go:171","msg":"trace[579588875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26007; }","duration":"235.66771ms","start":"2026-05-11T21:56:57.081079Z","end":"2026-05-11T21:56:57.316747Z","steps":["trace[579588875] 'agreement among raft nodes before linearized reading' (duration: 235.595129ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:57.31679Z","caller":"traceutil/trace.go:171","msg":"trace[628883731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26007; }","duration":"401.894276ms","start":"2026-05-11T21:56:56.91488Z","end":"2026-05-11T21:56:57.316774Z","steps":["trace[628883731] 'agreement among raft nodes before linearized reading' (duration: 401.692392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.316834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:56.914874Z","time spent":"401.952247ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T21:56:57.316886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.733452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-11T21:56:57.316886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.253137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-11T21:56:57.316964Z","caller":"traceutil/trace.go:171","msg":"trace[1273760914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26007; }","duration":"395.873536ms","start":"2026-05-11T21:56:56.921069Z","end":"2026-05-11T21:56:57.316943Z","steps":["trace[1273760914] 'agreement among raft nodes before linearized reading' (duration: 395.677532ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:57.316971Z","caller":"traceutil/trace.go:171","msg":"trace[605917897] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26007; }","duration":"167.39948ms","start":"2026-05-11T21:56:57.149553Z","end":"2026-05-11T21:56:57.316952Z","steps":["trace[605917897] 'agreement among raft nodes before linearized reading' (duration: 167.144485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.317014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:56.921043Z","time spent":"395.960898ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T21:56:57.317061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.514945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T21:56:57.31719Z","caller":"traceutil/trace.go:171","msg":"trace[1025080162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26007; }","duration":"399.696208ms","start":"2026-05-11T21:56:56.917464Z","end":"2026-05-11T21:56:57.31716Z","steps":["trace[1025080162] 'agreement among raft nodes before linearized reading' (duration: 399.32393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.317248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:56.917443Z","time spent":"399.7919ms","remote":"127.0.0.1:56108","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-05-11T21:57:11.614713Z","caller":"traceutil/trace.go:171","msg":"trace[1055536473] linearizableReadLoop","detail":"{readStateIndex:26978; appliedIndex:26977; }","duration":"241.647218ms","start":"2026-05-11T21:57:11.373034Z","end":"2026-05-11T21:57:11.614681Z","steps":["trace[1055536473] 'read index received' (duration: 150.167801ms)","trace[1055536473] 'applied index is now lower than readState.Index' (duration: 91.478087ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:11.614901Z","caller":"traceutil/trace.go:171","msg":"trace[1413556798] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"246.840549ms","start":"2026-05-11T21:57:11.368041Z","end":"2026-05-11T21:57:11.614882Z","steps":["trace[1413556798] 'process raft request' (duration: 155.199049ms)","trace[1413556798] 'compare' (duration: 90.801912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:11.615205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.745822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-11T21:57:11.615278Z","caller":"traceutil/trace.go:171","msg":"trace[1446955975] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26115; }","duration":"257.850175ms","start":"2026-05-11T21:57:11.357409Z","end":"2026-05-11T21:57:11.615259Z","steps":["trace[1446955975] 'agreement among raft nodes before linearized reading' (duration: 257.61985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:11.615367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.626829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-11T21:57:11.615341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.519558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-11T21:57:11.615413Z","caller":"traceutil/trace.go:171","msg":"trace[871049188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26115; }","duration":"228.722042ms","start":"2026-05-11T21:57:11.386678Z","end":"2026-05-11T21:57:11.6154Z","steps":["trace[871049188] 'agreement among raft nodes before linearized reading' (duration: 228.521618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:11.615386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.487955ms","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-05-11T21:57:11.615398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.679789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T21:57:11.615442Z","caller":"traceutil/trace.go:171","msg":"trace[1487358289] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26115; }","duration":"236.677691ms","start":"2026-05-11T21:57:11.378743Z","end":"2026-05-11T21:57:11.615421Z","steps":["trace[1487358289] 'agreement among raft nodes before linearized reading' (duration: 236.306633ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:11.615486Z","caller":"traceutil/trace.go:171","msg":"trace[884566331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26115; }","duration":"198.827222ms","start":"2026-05-11T21:57:11.416641Z","end":"2026-05-11T21:57:11.615468Z","steps":["trace[884566331] 'agreement among raft nodes before linearized reading' (duration: 198.529656ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:11.615479Z","caller":"traceutil/trace.go:171","msg":"trace[1162976123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26115; }","duration":"147.613307ms","start":"2026-05-11T21:57:11.467841Z","end":"2026-05-11T21:57:11.615454Z","steps":["trace[1162976123] 'agreement among raft nodes before linearized reading' (duration: 147.348841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:11.615729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.301012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T21:57:11.615788Z","caller":"traceutil/trace.go:171","msg":"trace[101539270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26115; }","duration":"148.455005ms","start":"2026-05-11T21:57:11.467318Z","end":"2026-05-11T21:57:11.615773Z","steps":["trace[101539270] 'agreement among raft nodes before linearized reading' (duration: 147.78156ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:11.819473Z","caller":"traceutil/trace.go:171","msg":"trace[2016768502] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"198.110138ms","start":"2026-05-11T21:57:11.621326Z","end":"2026-05-11T21:57:11.819436Z","steps":["trace[2016768502] 'process raft request' (duration: 198.017526ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:11.819517Z","caller":"traceutil/trace.go:171","msg":"trace[1658227996] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"198.623768ms","start":"2026-05-11T21:57:11.620849Z","end":"2026-05-11T21:57:11.819473Z","steps":["trace[1658227996] 'process raft request' (duration: 198.409404ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:11.819605Z","caller":"traceutil/trace.go:171","msg":"trace[1747613550] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"197.367671ms","start":"2026-05-11T21:57:11.622185Z","end":"2026-05-11T21:57:11.819553Z","steps":["trace[1747613550] 'process raft request' (duration: 197.199488ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:11.81945Z","caller":"traceutil/trace.go:171","msg":"trace[156379708] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"198.661529ms","start":"2026-05-11T21:57:11.620728Z","end":"2026-05-11T21:57:11.819389Z","steps":["trace[156379708] 'process raft request' (duration: 128.266273ms)","trace[156379708] 'compare' (duration: 70.007347ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:11.81952Z","caller":"traceutil/trace.go:171","msg":"trace[763091774] linearizableReadLoop","detail":"{readStateIndex:26982; appliedIndex:26978; }","duration":"194.883669ms","start":"2026-05-11T21:57:11.624602Z","end":"2026-05-11T21:57:11.819485Z","steps":["trace[763091774] 'read index received' (duration: 124.519234ms)","trace[763091774] 'applied index is now lower than readState.Index' (duration: 70.362365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:11.819693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.056682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:57:11.819896Z","caller":"traceutil/trace.go:171","msg":"trace[978296993] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26119; }","duration":"195.308647ms","start":"2026-05-11T21:57:11.624568Z","end":"2026-05-11T21:57:11.819876Z","steps":["trace[978296993] 'agreement among raft nodes before linearized reading' (duration: 195.021431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:11.841945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.437324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-11T21:57:11.842021Z","caller":"traceutil/trace.go:171","msg":"trace[1789565855] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26122; }","duration":"213.555477ms","start":"2026-05-11T21:57:11.628447Z","end":"2026-05-11T21:57:11.842003Z","steps":["trace[1789565855] 'agreement among raft nodes before linearized reading' (duration: 213.370683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:12.195027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.388577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:57:12.195199Z","caller":"traceutil/trace.go:171","msg":"trace[1408247364] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"226.861102ms","start":"2026-05-11T21:57:11.968272Z","end":"2026-05-11T21:57:12.195133Z","steps":["trace[1408247364] 'process raft request' (duration: 32.257261ms)","trace[1408247364] 'compare' (duration: 194.145461ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:12.197326Z","caller":"traceutil/trace.go:171","msg":"trace[656869993] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"127.525128ms","start":"2026-05-11T21:57:12.069768Z","end":"2026-05-11T21:57:12.197293Z","steps":["trace[656869993] 'process raft request' (duration: 127.330723ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:12.203908Z","caller":"traceutil/trace.go:171","msg":"trace[1129545094] linearizableReadLoop","detail":"{readStateIndex:26988; appliedIndex:26986; }","duration":"124.941151ms","start":"2026-05-11T21:57:12.078947Z","end":"2026-05-11T21:57:12.203888Z","steps":["trace[1129545094] 'read index received' (duration: 118.29797ms)","trace[1129545094] 'applied index is now lower than readState.Index' (duration: 6.642291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:12.204037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.067155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:57:12.204009Z","caller":"traceutil/trace.go:171","msg":"trace[1373888462] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"132.31271ms","start":"2026-05-11T21:57:12.071654Z","end":"2026-05-11T21:57:12.203966Z","steps":["trace[1373888462] 'process raft request' (duration: 132.117796ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:12.204072Z","caller":"traceutil/trace.go:171","msg":"trace[819469914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26125; }","duration":"125.153016ms","start":"2026-05-11T21:57:12.078907Z","end":"2026-05-11T21:57:12.20406Z","steps":["trace[819469914] 'agreement among raft nodes before linearized reading' (duration: 125.069854ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:12.499961Z","caller":"traceutil/trace.go:171","msg":"trace[2088487539] linearizableReadLoop","detail":"{readStateIndex:26990; appliedIndex:26989; }","duration":"101.692196ms","start":"2026-05-11T21:57:12.398233Z","end":"2026-05-11T21:57:12.499925Z","steps":["trace[2088487539] 'read index received' (duration: 47.21321ms)","trace[2088487539] 'applied index is now lower than readState.Index' (duration: 54.476505ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:12.500002Z","caller":"traceutil/trace.go:171","msg":"trace[1617844664] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"138.910341ms","start":"2026-05-11T21:57:12.361033Z","end":"2026-05-11T21:57:12.499944Z","steps":["trace[1617844664] 'process raft request' (duration: 84.365604ms)","trace[1617844664] 'compare' (duration: 54.20426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:12.500292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.041752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T21:57:12.500339Z","caller":"traceutil/trace.go:171","msg":"trace[1961079194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26126; }","duration":"102.140035ms","start":"2026-05-11T21:57:12.398186Z","end":"2026-05-11T21:57:12.500326Z","steps":["trace[1961079194] 'agreement among raft nodes before linearized reading' (duration: 101.862619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:12.860642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.759054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:57:12.86074Z","caller":"traceutil/trace.go:171","msg":"trace[1654737699] linearizableReadLoop","detail":"{readStateIndex:26991; appliedIndex:26990; }","duration":"354.778868ms","start":"2026-05-11T21:57:12.505942Z","end":"2026-05-11T21:57:12.860721Z","steps":["trace[1654737699] 'read index received' (duration: 102.802319ms)","trace[1654737699] 'applied index is now lower than readState.Index' (duration: 251.975479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:12.86088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.947881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-11T21:57:12.860898Z","caller":"traceutil/trace.go:171","msg":"trace[226985099] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26127; }","duration":"354.992102ms","start":"2026-05-11T21:57:12.5059Z","end":"2026-05-11T21:57:12.860892Z","steps":["trace[226985099] 'agreement among raft nodes before linearized reading' (duration: 354.86115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:12.860925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:57:12.505879Z","time spent":"355.039003ms","remote":"127.0.0.1:55868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-11T21:57:12.860981Z","caller":"traceutil/trace.go:171","msg":"trace[954771443] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"355.83064ms","start":"2026-05-11T21:57:12.505096Z","end":"2026-05-11T21:57:12.860927Z","steps":["trace[954771443] 'process raft request' (duration: 103.699788ms)","trace[954771443] 'compare' (duration: 251.524068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:12.861081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:57:12.50508Z","time spent":"355.953692ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:57:13.222808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.462237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:57:13.222942Z","caller":"traceutil/trace.go:171","msg":"trace[1052112916] linearizableReadLoop","detail":"{readStateIndex:26992; appliedIndex:26991; }","duration":"142.520617ms","start":"2026-05-11T21:57:13.080401Z","end":"2026-05-11T21:57:13.222922Z","steps":["trace[1052112916] 'read index received' (duration: 70.551µs)","trace[1052112916] 'applied index is now lower than readState.Index' (duration: 142.448516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:13.223013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.62076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:57:13.223038Z","caller":"traceutil/trace.go:171","msg":"trace[1082039715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26128; }","duration":"142.703962ms","start":"2026-05-11T21:57:13.080325Z","end":"2026-05-11T21:57:13.223029Z","steps":["trace[1082039715] 'agreement among raft nodes before linearized reading' (duration: 142.649671ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:13.22325Z","caller":"traceutil/trace.go:171","msg":"trace[1253068327] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"208.13069ms","start":"2026-05-11T21:57:13.015075Z","end":"2026-05-11T21:57:13.223205Z","steps":["trace[1253068327] 'process raft request' (duration: 36.162533ms)","trace[1253068327] 'compare' (duration: 171.217102ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:16.048572Z","caller":"traceutil/trace.go:171","msg":"trace[556597731] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"132.722939ms","start":"2026-05-11T21:57:15.915822Z","end":"2026-05-11T21:57:16.048545Z","steps":["trace[556597731] 'process raft request' (duration: 85.112531ms)","trace[556597731] 'compare' (duration: 47.439583ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:16.048922Z","caller":"traceutil/trace.go:171","msg":"trace[1865095812] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"131.111464ms","start":"2026-05-11T21:57:15.917758Z","end":"2026-05-11T21:57:16.04887Z","steps":["trace[1865095812] 'process raft request' (duration: 130.745046ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:16.048967Z","caller":"traceutil/trace.go:171","msg":"trace[1874062939] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"130.958641ms","start":"2026-05-11T21:57:15.917972Z","end":"2026-05-11T21:57:16.048931Z","steps":["trace[1874062939] 'process raft request' (duration: 130.771607ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:16.049001Z","caller":"traceutil/trace.go:171","msg":"trace[852682837] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"113.200642ms","start":"2026-05-11T21:57:15.935773Z","end":"2026-05-11T21:57:16.048974Z","steps":["trace[852682837] 'process raft request' (duration: 113.13457ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:16.048987Z","caller":"traceutil/trace.go:171","msg":"trace[2083911711] linearizableReadLoop","detail":"{readStateIndex:27023; appliedIndex:27020; }","duration":"118.214269ms","start":"2026-05-11T21:57:15.930745Z","end":"2026-05-11T21:57:16.048959Z","steps":["trace[2083911711] 'read index received' (duration: 70.278703ms)","trace[2083911711] 'applied index is now lower than readState.Index' (duration: 47.932886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:16.049297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.76497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-11T21:57:16.049354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.541206ms","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":"info","ts":"2026-05-11T21:57:16.049393Z","caller":"traceutil/trace.go:171","msg":"trace[985003889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26160; }","duration":"133.609637ms","start":"2026-05-11T21:57:15.915771Z","end":"2026-05-11T21:57:16.049381Z","steps":["trace[985003889] 'agreement among raft nodes before linearized reading' (duration: 133.356752ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:16.049395Z","caller":"traceutil/trace.go:171","msg":"trace[2138544453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26160; }","duration":"132.951634ms","start":"2026-05-11T21:57:15.916421Z","end":"2026-05-11T21:57:16.049372Z","steps":["trace[2138544453] 'agreement among raft nodes before linearized reading' (duration: 132.641758ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:16.219504Z","caller":"traceutil/trace.go:171","msg":"trace[1166370358] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"165.534132ms","start":"2026-05-11T21:57:16.053935Z","end":"2026-05-11T21:57:16.219469Z","steps":["trace[1166370358] 'process raft request' (duration: 125.435023ms)","trace[1166370358] 'compare' (duration: 39.652668ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:16.219573Z","caller":"traceutil/trace.go:171","msg":"trace[2127376693] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"165.397777ms","start":"2026-05-11T21:57:16.05415Z","end":"2026-05-11T21:57:16.219548Z","steps":["trace[2127376693] 'process raft request' (duration: 165.237024ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:16.219542Z","caller":"traceutil/trace.go:171","msg":"trace[1481905960] linearizableReadLoop","detail":"{readStateIndex:27027; appliedIndex:27024; }","duration":"138.148525ms","start":"2026-05-11T21:57:16.081361Z","end":"2026-05-11T21:57:16.21951Z","steps":["trace[1481905960] 'read index received' (duration: 34.082779ms)","trace[1481905960] 'applied index is now lower than readState.Index' (duration: 104.064346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:16.219879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.492642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:57:16.220008Z","caller":"traceutil/trace.go:171","msg":"trace[363975988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26163; }","duration":"138.651506ms","start":"2026-05-11T21:57:16.081309Z","end":"2026-05-11T21:57:16.219961Z","steps":["trace[363975988] 'agreement among raft nodes before linearized reading' (duration: 138.36958ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:21.687721Z","caller":"traceutil/trace.go:171","msg":"trace[386633785] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"211.942323ms","start":"2026-05-11T21:57:21.475741Z","end":"2026-05-11T21:57:21.687683Z","steps":["trace[386633785] 'process raft request' (duration: 211.725918ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:21.834816Z","caller":"traceutil/trace.go:171","msg":"trace[1170475075] linearizableReadLoop","detail":"{readStateIndex:27063; appliedIndex:27062; }","duration":"100.676204ms","start":"2026-05-11T21:57:21.734108Z","end":"2026-05-11T21:57:21.834784Z","steps":["trace[1170475075] 'read index received' (duration: 78.693583ms)","trace[1170475075] 'applied index is now lower than readState.Index' (duration: 21.981521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:21.835023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.894678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ddebc252-082e-4b87-ad2a-2d20fc6e69ed\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-05-11T21:57:21.83506Z","caller":"traceutil/trace.go:171","msg":"trace[1388140652] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ddebc252-082e-4b87-ad2a-2d20fc6e69ed; range_end:; response_count:1; response_revision:26198; }","duration":"100.99332ms","start":"2026-05-11T21:57:21.734052Z","end":"2026-05-11T21:57:21.835045Z","steps":["trace[1388140652] 'agreement among raft nodes before linearized reading' (duration: 100.834237ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:21.835009Z","caller":"traceutil/trace.go:171","msg":"trace[1974055794] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"103.037674ms","start":"2026-05-11T21:57:21.73189Z","end":"2026-05-11T21:57:21.834927Z","steps":["trace[1974055794] 'process raft request' (duration: 81.032703ms)","trace[1974055794] 'compare' (duration: 21.721135ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:31.868331Z","caller":"traceutil/trace.go:171","msg":"trace[622021684] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"136.125011ms","start":"2026-05-11T21:57:31.732159Z","end":"2026-05-11T21:57:31.868284Z","steps":["trace[622021684] 'process raft request' (duration: 135.770534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:32.197223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.361578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:57:32.197335Z","caller":"traceutil/trace.go:171","msg":"trace[461540529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26272; }","duration":"116.521112ms","start":"2026-05-11T21:57:32.080788Z","end":"2026-05-11T21:57:32.197309Z","steps":["trace[461540529] 'range keys from in-memory index tree' (duration: 116.204634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:32.197442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.464077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T21:57:32.197528Z","caller":"traceutil/trace.go:171","msg":"trace[112187063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26272; }","duration":"186.60035ms","start":"2026-05-11T21:57:32.010906Z","end":"2026-05-11T21:57:32.197506Z","steps":["trace[112187063] 'range keys from in-memory index tree' (duration: 186.228163ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.211275Z","caller":"traceutil/trace.go:171","msg":"trace[1286901445] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"104.509175ms","start":"2026-05-11T21:57:32.10674Z","end":"2026-05-11T21:57:32.21125Z","steps":["trace[1286901445] 'process raft request' (duration: 104.296531ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.260156Z","caller":"traceutil/trace.go:171","msg":"trace[115110352] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"148.879084ms","start":"2026-05-11T21:57:32.111246Z","end":"2026-05-11T21:57:32.260125Z","steps":["trace[115110352] 'process raft request' (duration: 148.628338ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.260449Z","caller":"traceutil/trace.go:171","msg":"trace[2049624049] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"148.6978ms","start":"2026-05-11T21:57:32.11172Z","end":"2026-05-11T21:57:32.260418Z","steps":["trace[2049624049] 'process raft request' (duration: 148.345072ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.260507Z","caller":"traceutil/trace.go:171","msg":"trace[146949078] linearizableReadLoop","detail":"{readStateIndex:27142; appliedIndex:27139; }","duration":"142.373215ms","start":"2026-05-11T21:57:32.118103Z","end":"2026-05-11T21:57:32.260477Z","steps":["trace[146949078] 'read index received' (duration: 92.971998ms)","trace[146949078] 'applied index is now lower than readState.Index' (duration: 49.399407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:32.260889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.780623ms","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-05-11T21:57:32.260966Z","caller":"traceutil/trace.go:171","msg":"trace[2123048502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26276; }","duration":"142.862305ms","start":"2026-05-11T21:57:32.118082Z","end":"2026-05-11T21:57:32.260944Z","steps":["trace[2123048502] 'agreement among raft nodes before linearized reading' (duration: 142.550798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:32.261036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.915101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-11T21:57:32.261152Z","caller":"traceutil/trace.go:171","msg":"trace[2123659791] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26276; }","duration":"103.154967ms","start":"2026-05-11T21:57:32.157974Z","end":"2026-05-11T21:57:32.261128Z","steps":["trace[2123659791] 'agreement among raft nodes before linearized reading' (duration: 102.792089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:32.26225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.039566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T21:57:32.262375Z","caller":"traceutil/trace.go:171","msg":"trace[823383580] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26276; }","duration":"105.2528ms","start":"2026-05-11T21:57:32.157099Z","end":"2026-05-11T21:57:32.262352Z","steps":["trace[823383580] 'agreement among raft nodes before linearized reading' (duration: 103.573585ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.422371Z","caller":"traceutil/trace.go:171","msg":"trace[108366712] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"155.510625ms","start":"2026-05-11T21:57:32.266812Z","end":"2026-05-11T21:57:32.422323Z","steps":["trace[108366712] 'process raft request' (duration: 155.293581ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.422933Z","caller":"traceutil/trace.go:171","msg":"trace[1042834643] linearizableReadLoop","detail":"{readStateIndex:27147; appliedIndex:27144; }","duration":"121.956459ms","start":"2026-05-11T21:57:32.300953Z","end":"2026-05-11T21:57:32.422909Z","steps":["trace[1042834643] 'read index received' (duration: 121.488838ms)","trace[1042834643] 'applied index is now lower than readState.Index' (duration: 466.211µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:32.423054Z","caller":"traceutil/trace.go:171","msg":"trace[1593302249] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"136.357527ms","start":"2026-05-11T21:57:32.286688Z","end":"2026-05-11T21:57:32.423045Z","steps":["trace[1593302249] 'process raft request' (duration: 135.993069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:32.423291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.308566ms","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":"info","ts":"2026-05-11T21:57:32.423371Z","caller":"traceutil/trace.go:171","msg":"trace[2053772922] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"124.671536ms","start":"2026-05-11T21:57:32.298688Z","end":"2026-05-11T21:57:32.42336Z","steps":["trace[2053772922] 'process raft request' (duration: 124.174266ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.423382Z","caller":"traceutil/trace.go:171","msg":"trace[1581479963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26280; }","duration":"122.417738ms","start":"2026-05-11T21:57:32.300943Z","end":"2026-05-11T21:57:32.42336Z","steps":["trace[1581479963] 'agreement among raft nodes before linearized reading' (duration: 122.152273ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:32.423288Z","caller":"traceutil/trace.go:171","msg":"trace[1939644694] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"135.163511ms","start":"2026-05-11T21:57:32.288078Z","end":"2026-05-11T21:57:32.423241Z","steps":["trace[1939644694] 'process raft request' (duration: 134.740872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:32.42346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.11532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T21:57:32.423524Z","caller":"traceutil/trace.go:171","msg":"trace[830214342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26280; }","duration":"121.236912ms","start":"2026-05-11T21:57:32.302272Z","end":"2026-05-11T21:57:32.423509Z","steps":["trace[830214342] 'agreement among raft nodes before linearized reading' (duration: 121.004967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:32.423539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.876405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-11T21:57:32.423629Z","caller":"traceutil/trace.go:171","msg":"trace[219633276] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:26280; }","duration":"114.030368ms","start":"2026-05-11T21:57:32.309577Z","end":"2026-05-11T21:57:32.423608Z","steps":["trace[219633276] 'agreement among raft nodes before linearized reading' (duration: 113.754793ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:36.002558Z","caller":"traceutil/trace.go:171","msg":"trace[297401521] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"121.223952ms","start":"2026-05-11T21:57:35.881307Z","end":"2026-05-11T21:57:36.002531Z","steps":["trace[297401521] 'process raft request' (duration: 121.008838ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:36.154981Z","caller":"traceutil/trace.go:171","msg":"trace[111689507] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"245.652514ms","start":"2026-05-11T21:57:35.909291Z","end":"2026-05-11T21:57:36.154944Z","steps":["trace[111689507] 'process raft request' (duration: 218.936712ms)","trace[111689507] 'compare' (duration: 26.461337ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:36.592448Z","caller":"traceutil/trace.go:171","msg":"trace[2013588023] linearizableReadLoop","detail":"{readStateIndex:27186; appliedIndex:27185; }","duration":"150.740834ms","start":"2026-05-11T21:57:36.441669Z","end":"2026-05-11T21:57:36.59241Z","steps":["trace[2013588023] 'read index received' (duration: 135.57522ms)","trace[2013588023] 'applied index is now lower than readState.Index' (duration: 15.164064ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:57:36.592611Z","caller":"traceutil/trace.go:171","msg":"trace[257799385] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"151.146863ms","start":"2026-05-11T21:57:36.441397Z","end":"2026-05-11T21:57:36.592544Z","steps":["trace[257799385] 'process raft request' (duration: 135.851846ms)","trace[257799385] 'compare' (duration: 14.96592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:36.593866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.161445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:57:36.593944Z","caller":"traceutil/trace.go:171","msg":"trace[1450345595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26318; }","duration":"152.297478ms","start":"2026-05-11T21:57:36.441628Z","end":"2026-05-11T21:57:36.593925Z","steps":["trace[1450345595] 'agreement among raft nodes before linearized reading' (duration: 151.004779ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:36.616887Z","caller":"traceutil/trace.go:171","msg":"trace[1264456640] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"175.168157ms","start":"2026-05-11T21:57:36.441688Z","end":"2026-05-11T21:57:36.616857Z","steps":["trace[1264456640] 'process raft request' (duration: 174.934382ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:36.617334Z","caller":"traceutil/trace.go:171","msg":"trace[2071436422] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"175.540285ms","start":"2026-05-11T21:57:36.441742Z","end":"2026-05-11T21:57:36.617282Z","steps":["trace[2071436422] 'process raft request' (duration: 175.038144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:36.617479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.744868ms","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":"info","ts":"2026-05-11T21:57:36.617539Z","caller":"traceutil/trace.go:171","msg":"trace[1102272040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26320; }","duration":"139.835771ms","start":"2026-05-11T21:57:36.477684Z","end":"2026-05-11T21:57:36.61752Z","steps":["trace[1102272040] 'agreement among raft nodes before linearized reading' (duration: 139.622636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:36.617773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.246583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T21:57:36.617816Z","caller":"traceutil/trace.go:171","msg":"trace[872646788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26320; }","duration":"143.314285ms","start":"2026-05-11T21:57:36.474492Z","end":"2026-05-11T21:57:36.617806Z","steps":["trace[872646788] 'agreement among raft nodes before linearized reading' (duration: 142.765963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:57:36.82733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.562481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:57:36.827741Z","caller":"traceutil/trace.go:171","msg":"trace[1569564211] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"203.737707ms","start":"2026-05-11T21:57:36.623981Z","end":"2026-05-11T21:57:36.827719Z","steps":["trace[1569564211] 'process raft request' (duration: 203.683966ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:36.827817Z","caller":"traceutil/trace.go:171","msg":"trace[28738892] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"205.675988ms","start":"2026-05-11T21:57:36.622102Z","end":"2026-05-11T21:57:36.827778Z","steps":["trace[28738892] 'process raft request' (duration: 205.488394ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:36.827982Z","caller":"traceutil/trace.go:171","msg":"trace[138407932] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"228.313413ms","start":"2026-05-11T21:57:36.599481Z","end":"2026-05-11T21:57:36.827794Z","steps":["trace[138407932] 'process raft request' (duration: 105.053657ms)","trace[138407932] 'compare' (duration: 122.408728ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:37.087941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.661316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:57:37.088059Z","caller":"traceutil/trace.go:171","msg":"trace[515366726] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:26323; }","duration":"153.816969ms","start":"2026-05-11T21:57:36.934214Z","end":"2026-05-11T21:57:37.088031Z","steps":["trace[515366726] 'count revisions from in-memory index tree' (duration: 153.526403ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:37.224548Z","caller":"traceutil/trace.go:171","msg":"trace[2055821474] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"114.443127ms","start":"2026-05-11T21:57:37.110075Z","end":"2026-05-11T21:57:37.224518Z","steps":["trace[2055821474] 'process raft request' (duration: 114.287634ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:58:04.802931Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b3c.wal"} {"level":"info","ts":"2026-05-11T22:00:49.030405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25310} {"level":"info","ts":"2026-05-11T22:00:49.177044Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25310,"took":"140.558797ms","hash":197646588,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":38735872,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-05-11T22:00:49.177152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":197646588,"revision":25310,"compact-revision":22066} {"level":"info","ts":"2026-05-11T22:00:53.697491Z","caller":"traceutil/trace.go:171","msg":"trace[2095806535] transaction","detail":"{read_only:false; response_revision:28370; number_of_response:1; }","duration":"104.658978ms","start":"2026-05-11T22:00:53.592794Z","end":"2026-05-11T22:00:53.697453Z","steps":["trace[2095806535] 'process raft request' (duration: 104.439233ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T22:02:11.723281Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T22:02:11.73203Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T22:02:11.732375Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T22:05:49.038156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28329} {"level":"info","ts":"2026-05-11T22:05:49.185607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28329,"took":"142.009373ms","hash":3632703290,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":32509952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T22:05:49.185664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3632703290,"revision":28329,"compact-revision":25310} {"level":"info","ts":"2026-05-11T22:10:49.04552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30724} {"level":"info","ts":"2026-05-11T22:10:49.187822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30724,"took":"136.939535ms","hash":1497455715,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":30392320,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T22:10:49.187915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1497455715,"revision":30724,"compact-revision":28329} {"level":"info","ts":"2026-05-11T22:15:49.052944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32976} {"level":"info","ts":"2026-05-11T22:15:49.199528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32976,"took":"140.598182ms","hash":104244586,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":31031296,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T22:15:49.199597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":104244586,"revision":32976,"compact-revision":30724} {"level":"info","ts":"2026-05-11T22:20:49.059732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35265} {"level":"info","ts":"2026-05-11T22:20:49.199644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35265,"took":"133.608564ms","hash":1207763928,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":31305728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T22:20:49.199724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1207763928,"revision":35265,"compact-revision":32976} {"level":"info","ts":"2026-05-11T22:23:39.019751Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T22:23:39.025615Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T22:23:39.025715Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-11T22:25:49.067434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37503} {"level":"info","ts":"2026-05-11T22:25:49.190547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37503,"took":"117.309464ms","hash":3972030471,"current-db-size-bytes":62709760,"current-db-size":"63 MB","current-db-size-in-use-bytes":32509952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T22:25:49.190634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3972030471,"revision":37503,"compact-revision":35265}